{"level":"warn","ts":"2026-04-07T00:08:00.958893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:08:00.95897Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.57: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.57:2380","--initial-cluster=instance=https://199.19.213.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:08:00.959027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:08:00.959036Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-04-07T00:08:00.959053Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:08:00.959663Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"]} {"level":"info","ts":"2026-04-07T00:08:00.959734Z","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.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:08:00.996488Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"36.536442ms"} {"level":"info","ts":"2026-04-07T00:08:01.043095Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ef5ef2a9544a39ef","cluster-id":"58d35553a3d286c0"} {"level":"info","ts":"2026-04-07T00:08:01.043265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:08:01.04337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 0"} {"level":"info","ts":"2026-04-07T00:08:01.043393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ef5ef2a9544a39ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:08:01.043423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 1"} {"level":"info","ts":"2026-04-07T00:08:01.043615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"warn","ts":"2026-04-07T00:08:01.234342Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:08:01.240949Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:08:01.247915Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:08:01.271596Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ef5ef2a9544a39ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:08:01.272031Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ef5ef2a9544a39ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:08:01.272073Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:08:01.272259Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:08:01.272286Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:08:01.272498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"info","ts":"2026-04-07T00:08:01.272577Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","added-peer-id":"ef5ef2a9544a39ef","added-peer-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-04-07T00:08:01.275309Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:08:01.275429Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-04-07T00:08:01.27546Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-04-07T00:08:01.276302Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ef5ef2a9544a39ef","initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:08:01.276327Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:08:01.344066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:08:01.344143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:08:01.344172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgPreVoteResp from ef5ef2a9544a39ef at term 1"} {"level":"info","ts":"2026-04-07T00:08:01.344222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:08:01.344233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgVoteResp from ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-04-07T00:08:01.344252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became leader at term 2"} {"level":"info","ts":"2026-04-07T00:08:01.344294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ef5ef2a9544a39ef elected leader ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-04-07T00:08:01.345238Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:01.345708Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ef5ef2a9544a39ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.57:2379]}","request-path":"/0/members/ef5ef2a9544a39ef/attributes","cluster-id":"58d35553a3d286c0","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:08:01.345762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:08:01.346272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:08:01.346282Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:01.3464Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:01.346437Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:01.347078Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:08:01.347123Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:08:01.350674Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.57:2379"} {"level":"info","ts":"2026-04-07T00:08:01.350825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:08:32.893863Z","caller":"traceutil/trace.go:171","msg":"trace[1934393695] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"210.11798ms","start":"2026-04-07T00:08:32.683671Z","end":"2026-04-07T00:08:32.893789Z","steps":["trace[1934393695] 'process raft request' (duration: 209.947836ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.932443Z","caller":"traceutil/trace.go:171","msg":"trace[1429092779] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"247.768391ms","start":"2026-04-07T00:08:32.684649Z","end":"2026-04-07T00:08:32.932417Z","steps":["trace[1429092779] 'process raft request' (duration: 247.570727ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.932971Z","caller":"traceutil/trace.go:171","msg":"trace[239806806] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"248.16665ms","start":"2026-04-07T00:08:32.684791Z","end":"2026-04-07T00:08:32.932958Z","steps":["trace[239806806] 'process raft request' (duration: 247.553087ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933262Z","caller":"traceutil/trace.go:171","msg":"trace[885402379] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"241.410868ms","start":"2026-04-07T00:08:32.69184Z","end":"2026-04-07T00:08:32.933251Z","steps":["trace[885402379] 'process raft request' (duration: 241.202673ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933352Z","caller":"traceutil/trace.go:171","msg":"trace[1706499666] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"242.192286ms","start":"2026-04-07T00:08:32.691112Z","end":"2026-04-07T00:08:32.933304Z","steps":["trace[1706499666] 'process raft request' (duration: 241.878399ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933424Z","caller":"traceutil/trace.go:171","msg":"trace[2108865972] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"248.074929ms","start":"2026-04-07T00:08:32.685339Z","end":"2026-04-07T00:08:32.933414Z","steps":["trace[2108865972] 'process raft request' (duration: 247.406253ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933417Z","caller":"traceutil/trace.go:171","msg":"trace[2024255348] transaction","detail":"{read_only:false; number_of_response:1; response_revision:477; }","duration":"244.1387ms","start":"2026-04-07T00:08:32.689255Z","end":"2026-04-07T00:08:32.933394Z","steps":["trace[2024255348] 'process raft request' (duration: 243.576417ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933446Z","caller":"traceutil/trace.go:171","msg":"trace[1543611631] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:490; }","duration":"178.030144ms","start":"2026-04-07T00:08:32.75539Z","end":"2026-04-07T00:08:32.93342Z","steps":["trace[1543611631] 'read index received' (duration: 138.878709ms)","trace[1543611631] 'applied index is now lower than readState.Index' (duration: 39.150545ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:32.933504Z","caller":"traceutil/trace.go:171","msg":"trace[1808454973] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"241.612062ms","start":"2026-04-07T00:08:32.691884Z","end":"2026-04-07T00:08:32.933496Z","steps":["trace[1808454973] 'process raft request' (duration: 241.229934ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933361Z","caller":"traceutil/trace.go:171","msg":"trace[776877467] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"244.199242ms","start":"2026-04-07T00:08:32.689119Z","end":"2026-04-07T00:08:32.933318Z","steps":["trace[776877467] 'process raft request' (duration: 243.66609ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933546Z","caller":"traceutil/trace.go:171","msg":"trace[2079751196] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"221.450718ms","start":"2026-04-07T00:08:32.712086Z","end":"2026-04-07T00:08:32.933536Z","steps":["trace[2079751196] 'process raft request' (duration: 221.210573ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933643Z","caller":"traceutil/trace.go:171","msg":"trace[379554873] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"241.691275ms","start":"2026-04-07T00:08:32.691941Z","end":"2026-04-07T00:08:32.933633Z","steps":["trace[379554873] 'process raft request' (duration: 241.215884ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933683Z","caller":"traceutil/trace.go:171","msg":"trace[883565535] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"244.388265ms","start":"2026-04-07T00:08:32.68928Z","end":"2026-04-07T00:08:32.933668Z","steps":["trace[883565535] 'process raft request' (duration: 243.588197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:32.933693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.286221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:32.933756Z","caller":"traceutil/trace.go:171","msg":"trace[1362133520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"178.362643ms","start":"2026-04-07T00:08:32.755383Z","end":"2026-04-07T00:08:32.933746Z","steps":["trace[1362133520] 'agreement among raft nodes before linearized reading' (duration: 178.202469ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.933471Z","caller":"traceutil/trace.go:171","msg":"trace[1220751766] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"235.73773ms","start":"2026-04-07T00:08:32.697724Z","end":"2026-04-07T00:08:32.933462Z","steps":["trace[1220751766] 'process raft request' (duration: 235.475964ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:32.93383Z","caller":"traceutil/trace.go:171","msg":"trace[1679925554] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"243.889223ms","start":"2026-04-07T00:08:32.689395Z","end":"2026-04-07T00:08:32.933284Z","steps":["trace[1679925554] 'process raft request' (duration: 243.569526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139027Z","caller":"traceutil/trace.go:171","msg":"trace[1458233433] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"237.437007ms","start":"2026-04-07T00:08:32.901552Z","end":"2026-04-07T00:08:33.138989Z","steps":["trace[1458233433] 'process raft request' (duration: 190.925249ms)","trace[1458233433] 'compare' (duration: 46.038146ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:33.139226Z","caller":"traceutil/trace.go:171","msg":"trace[1151380251] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"184.537872ms","start":"2026-04-07T00:08:32.954667Z","end":"2026-04-07T00:08:33.139204Z","steps":["trace[1151380251] 'process raft request' (duration: 184.446389ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139325Z","caller":"traceutil/trace.go:171","msg":"trace[889371945] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"189.166975ms","start":"2026-04-07T00:08:32.950144Z","end":"2026-04-07T00:08:33.139311Z","steps":["trace[889371945] 'process raft request' (duration: 188.753467ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139417Z","caller":"traceutil/trace.go:171","msg":"trace[1501979161] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:504; }","duration":"205.885744ms","start":"2026-04-07T00:08:32.933505Z","end":"2026-04-07T00:08:33.139391Z","steps":["trace[1501979161] 'read index received' (duration: 159.057139ms)","trace[1501979161] 'applied index is now lower than readState.Index' (duration: 46.786705ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:33.139477Z","caller":"traceutil/trace.go:171","msg":"trace[230898319] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"141.1238ms","start":"2026-04-07T00:08:32.998336Z","end":"2026-04-07T00:08:33.13946Z","steps":["trace[230898319] 'process raft request' (duration: 141.075678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139482Z","caller":"traceutil/trace.go:171","msg":"trace[1650976213] transaction","detail":"{read_only:false; number_of_response:0; response_revision:488; }","duration":"200.753947ms","start":"2026-04-07T00:08:32.938697Z","end":"2026-04-07T00:08:33.139451Z","steps":["trace[1650976213] 'process raft request' (duration: 200.172433ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139554Z","caller":"traceutil/trace.go:171","msg":"trace[1239137094] transaction","detail":"{read_only:false; number_of_response:1; response_revision:489; }","duration":"189.310449ms","start":"2026-04-07T00:08:32.950229Z","end":"2026-04-07T00:08:33.13954Z","steps":["trace[1239137094] 'process raft request' (duration: 188.729645ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139576Z","caller":"traceutil/trace.go:171","msg":"trace[1526621190] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"179.981468ms","start":"2026-04-07T00:08:32.959583Z","end":"2026-04-07T00:08:33.139564Z","steps":["trace[1526621190] 'process raft request' (duration: 179.716521ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.13965Z","caller":"traceutil/trace.go:171","msg":"trace[1504984624] transaction","detail":"{read_only:false; number_of_response:1; response_revision:489; }","duration":"189.379171ms","start":"2026-04-07T00:08:32.950262Z","end":"2026-04-07T00:08:33.139642Z","steps":["trace[1504984624] 'process raft request' (duration: 188.739006ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139674Z","caller":"traceutil/trace.go:171","msg":"trace[1921553005] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"233.397255ms","start":"2026-04-07T00:08:32.906268Z","end":"2026-04-07T00:08:33.139665Z","steps":["trace[1921553005] 'process raft request' (duration: 232.497144ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139618Z","caller":"traceutil/trace.go:171","msg":"trace[987702410] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"181.512752ms","start":"2026-04-07T00:08:32.958007Z","end":"2026-04-07T00:08:33.13952Z","steps":["trace[987702410] 'process raft request' (duration: 181.153544ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:33.139731Z","caller":"traceutil/trace.go:171","msg":"trace[893022404] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"185.132755ms","start":"2026-04-07T00:08:32.954591Z","end":"2026-04-07T00:08:33.139724Z","steps":["trace[893022404] 'process raft request' (duration: 184.447339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:33.139857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.40971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:33.139907Z","caller":"traceutil/trace.go:171","msg":"trace[264634367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"204.489532ms","start":"2026-04-07T00:08:32.935404Z","end":"2026-04-07T00:08:33.139893Z","steps":["trace[264634367] 'agreement among raft nodes before linearized reading' (duration: 204.346359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:33.140051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.783476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T00:08:33.140191Z","caller":"traceutil/trace.go:171","msg":"trace[363072990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"234.958991ms","start":"2026-04-07T00:08:32.9052Z","end":"2026-04-07T00:08:33.140159Z","steps":["trace[363072990] 'agreement among raft nodes before linearized reading' (duration: 234.46885ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:35.937879Z","caller":"traceutil/trace.go:171","msg":"trace[1311023584] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"179.481951ms","start":"2026-04-07T00:08:35.758371Z","end":"2026-04-07T00:08:35.937853Z","steps":["trace[1311023584] 'read index received' (duration: 179.39055ms)","trace[1311023584] 'applied index is now lower than readState.Index' (duration: 90.511µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:35.938011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.630535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:35.937996Z","caller":"traceutil/trace.go:171","msg":"trace[1783803784] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"232.023211ms","start":"2026-04-07T00:08:35.705941Z","end":"2026-04-07T00:08:35.937964Z","steps":["trace[1783803784] 'process raft request' (duration: 231.809037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:35.938038Z","caller":"traceutil/trace.go:171","msg":"trace[1420307498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"179.705488ms","start":"2026-04-07T00:08:35.758326Z","end":"2026-04-07T00:08:35.938031Z","steps":["trace[1420307498] 'agreement among raft nodes before linearized reading' (duration: 179.627575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:40.01655Z","caller":"traceutil/trace.go:171","msg":"trace[1827421143] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"211.180777ms","start":"2026-04-07T00:08:39.805345Z","end":"2026-04-07T00:08:40.016526Z","steps":["trace[1827421143] 'process raft request' (duration: 210.682515ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:40.601961Z","caller":"traceutil/trace.go:171","msg":"trace[1342585743] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"494.79268ms","start":"2026-04-07T00:08:40.107141Z","end":"2026-04-07T00:08:40.601934Z","steps":["trace[1342585743] 'read index received' (duration: 480.985723ms)","trace[1342585743] 'applied index is now lower than readState.Index' (duration: 13.805867ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:40.602148Z","caller":"traceutil/trace.go:171","msg":"trace[1645834816] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"518.473201ms","start":"2026-04-07T00:08:40.083663Z","end":"2026-04-07T00:08:40.602136Z","steps":["trace[1645834816] 'process raft request' (duration: 504.54488ms)","trace[1645834816] 'compare' (duration: 12.981928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:40.602547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:40.083627Z","time spent":"518.545732ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:08:40.602575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.411736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T00:08:40.602687Z","caller":"traceutil/trace.go:171","msg":"trace[347338227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"495.573418ms","start":"2026-04-07T00:08:40.107093Z","end":"2026-04-07T00:08:40.602666Z","steps":["trace[347338227] 'agreement among raft nodes before linearized reading' (duration: 495.256261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:40.602735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:40.107072Z","time spent":"495.65569ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:08:56.123455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.497516ms","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-07T00:08:56.123548Z","caller":"traceutil/trace.go:171","msg":"trace[791455461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"114.63952ms","start":"2026-04-07T00:08:56.008892Z","end":"2026-04-07T00:08:56.123531Z","steps":["trace[791455461] 'range keys from in-memory index tree' (duration: 114.352763ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:04.832738Z","caller":"traceutil/trace.go:171","msg":"trace[396475545] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"104.032411ms","start":"2026-04-07T00:09:04.728689Z","end":"2026-04-07T00:09:04.832722Z","steps":["trace[396475545] 'process raft request' (duration: 103.96294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:25.482367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.767204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:09:25.482602Z","caller":"traceutil/trace.go:171","msg":"trace[866746357] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1182; }","duration":"356.430516ms","start":"2026-04-07T00:09:25.126151Z","end":"2026-04-07T00:09:25.482581Z","steps":["trace[866746357] 'read index received' (duration: 249.820244ms)","trace[866746357] 'applied index is now lower than readState.Index' (duration: 106.608732ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:25.482679Z","caller":"traceutil/trace.go:171","msg":"trace[48340551] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"499.777848ms","start":"2026-04-07T00:09:24.98287Z","end":"2026-04-07T00:09:25.482648Z","steps":["trace[48340551] 'process raft request' (duration: 499.646525ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:25.482672Z","caller":"traceutil/trace.go:171","msg":"trace[790807127] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"499.805119ms","start":"2026-04-07T00:09:24.982785Z","end":"2026-04-07T00:09:25.48259Z","steps":["trace[790807127] 'process raft request' (duration: 90.396247ms)","trace[790807127] 'compare' (duration: 408.574778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:09:25.482894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:24.982845Z","time spent":"499.904781ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:09:25.482925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.762295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:09:25.482972Z","caller":"traceutil/trace.go:171","msg":"trace[292081231] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1146; }","duration":"356.846036ms","start":"2026-04-07T00:09:25.126114Z","end":"2026-04-07T00:09:25.48296Z","steps":["trace[292081231] 'agreement among raft nodes before linearized reading' (duration: 356.541288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:25.483009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:25.126094Z","time spent":"356.905499ms","remote":"127.0.0.1:33962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":129,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:09:25.483103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:24.982768Z","time spent":"500.005974ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:05.465412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.970708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:10:05.46546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.148436ms","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:5"} {"level":"info","ts":"2026-04-07T00:10:05.46552Z","caller":"traceutil/trace.go:171","msg":"trace[1053327167] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"112.106243ms","start":"2026-04-07T00:10:05.353391Z","end":"2026-04-07T00:10:05.465498Z","steps":["trace[1053327167] 'range keys from in-memory index tree' (duration: 111.883245ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:05.465569Z","caller":"traceutil/trace.go:171","msg":"trace[1917220098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"136.295621ms","start":"2026-04-07T00:10:05.329254Z","end":"2026-04-07T00:10:05.465549Z","steps":["trace[1917220098] 'count revisions from in-memory index tree' (duration: 136.101636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:05.465807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.54497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:05.465891Z","caller":"traceutil/trace.go:171","msg":"trace[936503620] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"136.693953ms","start":"2026-04-07T00:10:05.329177Z","end":"2026-04-07T00:10:05.465871Z","steps":["trace[936503620] 'range keys from in-memory index tree' (duration: 136.463176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:05.465988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.502176ms","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:5"} {"level":"info","ts":"2026-04-07T00:10:05.466081Z","caller":"traceutil/trace.go:171","msg":"trace[2081890585] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"112.62481ms","start":"2026-04-07T00:10:05.353434Z","end":"2026-04-07T00:10:05.466059Z","steps":["trace[2081890585] 'count revisions from in-memory index tree' (duration: 112.465325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:15.339023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.529238ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:15.339178Z","caller":"traceutil/trace.go:171","msg":"trace[2014524432] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1828; }","duration":"293.696853ms","start":"2026-04-07T00:10:15.045459Z","end":"2026-04-07T00:10:15.339155Z","steps":["trace[2014524432] 'range keys from in-memory index tree' (duration: 293.513327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:15.339729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.129722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:15.340055Z","caller":"traceutil/trace.go:171","msg":"trace[1233911405] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"601.128334ms","start":"2026-04-07T00:10:14.738888Z","end":"2026-04-07T00:10:15.340017Z","steps":["trace[1233911405] 'process raft request' (duration: 391.485286ms)","trace[1233911405] 'compare' (duration: 208.514422ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:15.340276Z","caller":"traceutil/trace.go:171","msg":"trace[632714210] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"550.742674ms","start":"2026-04-07T00:10:14.789495Z","end":"2026-04-07T00:10:15.340238Z","steps":["trace[632714210] 'process raft request' (duration: 550.31411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.340325Z","caller":"traceutil/trace.go:171","msg":"trace[1424988721] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1903; }","duration":"506.427381ms","start":"2026-04-07T00:10:14.833849Z","end":"2026-04-07T00:10:15.340276Z","steps":["trace[1424988721] 'read index received' (duration: 296.530655ms)","trace[1424988721] 'applied index is now lower than readState.Index' (duration: 209.894226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:15.340444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:14.78947Z","time spent":"550.907109ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:15.34059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.080205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-07T00:10:15.340593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.73683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ebc8454-d82b-4202-8927-850580f26034\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-07T00:10:15.340645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.24746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-04-07T00:10:15.340669Z","caller":"traceutil/trace.go:171","msg":"trace[456139954] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"441.189209ms","start":"2026-04-07T00:10:14.899459Z","end":"2026-04-07T00:10:15.340649Z","steps":["trace[456139954] 'agreement among raft nodes before linearized reading' (duration: 440.992243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.340696Z","caller":"traceutil/trace.go:171","msg":"trace[2062607231] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1831; }","duration":"377.334112ms","start":"2026-04-07T00:10:14.963346Z","end":"2026-04-07T00:10:15.34068Z","steps":["trace[2062607231] 'agreement among raft nodes before linearized reading' (duration: 377.101635ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.340698Z","caller":"traceutil/trace.go:171","msg":"trace[754725472] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ebc8454-d82b-4202-8927-850580f26034; range_end:; response_count:1; response_revision:1831; }","duration":"506.851483ms","start":"2026-04-07T00:10:14.833805Z","end":"2026-04-07T00:10:15.340657Z","steps":["trace[754725472] 'agreement among raft nodes before linearized reading' (duration: 506.616656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:15.340728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:14.899441Z","time spent":"441.274262ms","remote":"127.0.0.1:41574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T00:10:15.340742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:14.963304Z","time spent":"377.425675ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10690,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-04-07T00:10:15.340744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:14.83379Z","time spent":"506.943106ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ebc8454-d82b-4202-8927-850580f26034\" "} {"level":"warn","ts":"2026-04-07T00:10:15.340978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:14.738861Z","time spent":"601.294639ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:10:15.503331Z","caller":"traceutil/trace.go:171","msg":"trace[1740415957] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"152.257806ms","start":"2026-04-07T00:10:15.351049Z","end":"2026-04-07T00:10:15.503307Z","steps":["trace[1740415957] 'process raft request' (duration: 139.822853ms)","trace[1740415957] 'compare' (duration: 12.063471ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:15.503337Z","caller":"traceutil/trace.go:171","msg":"trace[1711662525] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"146.124187ms","start":"2026-04-07T00:10:15.357185Z","end":"2026-04-07T00:10:15.503309Z","steps":["trace[1711662525] 'process raft request' (duration: 146.029724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.787061Z","caller":"traceutil/trace.go:171","msg":"trace[1604003351] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"278.298854ms","start":"2026-04-07T00:10:15.508729Z","end":"2026-04-07T00:10:15.787028Z","steps":["trace[1604003351] 'process raft request' (duration: 238.477103ms)","trace[1604003351] 'compare' (duration: 39.599024ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:15.802752Z","caller":"traceutil/trace.go:171","msg":"trace[1039085168] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"293.884819ms","start":"2026-04-07T00:10:15.508836Z","end":"2026-04-07T00:10:15.80272Z","steps":["trace[1039085168] 'process raft request' (duration: 293.65169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.802906Z","caller":"traceutil/trace.go:171","msg":"trace[844651408] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"293.855189ms","start":"2026-04-07T00:10:15.509028Z","end":"2026-04-07T00:10:15.802883Z","steps":["trace[844651408] 'process raft request' (duration: 293.619291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.803151Z","caller":"traceutil/trace.go:171","msg":"trace[1891780267] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"291.352017ms","start":"2026-04-07T00:10:15.511788Z","end":"2026-04-07T00:10:15.80314Z","steps":["trace[1891780267] 'process raft request' (duration: 290.941574ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.803336Z","caller":"traceutil/trace.go:171","msg":"trace[1771010532] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"292.396721ms","start":"2026-04-07T00:10:15.510922Z","end":"2026-04-07T00:10:15.803319Z","steps":["trace[1771010532] 'process raft request' (duration: 291.7746ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:15.803328Z","caller":"traceutil/trace.go:171","msg":"trace[127412369] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"290.039484ms","start":"2026-04-07T00:10:15.513252Z","end":"2026-04-07T00:10:15.803292Z","steps":["trace[127412369] 'process raft request' (duration: 289.552228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:44.217505Z","caller":"traceutil/trace.go:171","msg":"trace[1146147957] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2123; }","duration":"197.057866ms","start":"2026-04-07T00:10:44.020419Z","end":"2026-04-07T00:10:44.217477Z","steps":["trace[1146147957] 'read index received' (duration: 176.340462ms)","trace[1146147957] 'applied index is now lower than readState.Index' (duration: 20.713344ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:44.217619Z","caller":"traceutil/trace.go:171","msg":"trace[1712934086] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"250.195543ms","start":"2026-04-07T00:10:43.967378Z","end":"2026-04-07T00:10:44.217573Z","steps":["trace[1712934086] 'process raft request' (duration: 229.372214ms)","trace[1712934086] 'compare' (duration: 20.440745ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:44.217871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.448309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T00:10:44.217925Z","caller":"traceutil/trace.go:171","msg":"trace[2114808637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2043; }","duration":"197.558082ms","start":"2026-04-07T00:10:44.020356Z","end":"2026-04-07T00:10:44.217914Z","steps":["trace[2114808637] 'agreement among raft nodes before linearized reading' (duration: 197.244521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:44.272199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.752092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:10:44.272374Z","caller":"traceutil/trace.go:171","msg":"trace[520188850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2044; }","duration":"247.953499ms","start":"2026-04-07T00:10:44.024395Z","end":"2026-04-07T00:10:44.272348Z","steps":["trace[520188850] 'agreement among raft nodes before linearized reading' (duration: 247.527084ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:44.272749Z","caller":"traceutil/trace.go:171","msg":"trace[994132425] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"129.31282ms","start":"2026-04-07T00:10:44.143421Z","end":"2026-04-07T00:10:44.272734Z","steps":["trace[994132425] 'process raft request' (duration: 128.301697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:44.273127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.754373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T00:10:44.274523Z","caller":"traceutil/trace.go:171","msg":"trace[1984003656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2044; }","duration":"237.14942ms","start":"2026-04-07T00:10:44.037358Z","end":"2026-04-07T00:10:44.274508Z","steps":["trace[1984003656] 'agreement among raft nodes before linearized reading' (duration: 235.68483ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:44.930266Z","caller":"traceutil/trace.go:171","msg":"trace[1641487298] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"164.716465ms","start":"2026-04-07T00:10:44.765521Z","end":"2026-04-07T00:10:44.930238Z","steps":["trace[1641487298] 'process raft request' (duration: 136.32172ms)","trace[1641487298] 'compare' (duration: 28.271151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:45.153256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.105244ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:45.153344Z","caller":"traceutil/trace.go:171","msg":"trace[874933561] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2048; }","duration":"108.211718ms","start":"2026-04-07T00:10:45.04512Z","end":"2026-04-07T00:10:45.153331Z","steps":["trace[874933561] 'range keys from in-memory index tree' (duration: 108.087984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:45.153394Z","caller":"traceutil/trace.go:171","msg":"trace[1876208984] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"119.42816ms","start":"2026-04-07T00:10:45.033943Z","end":"2026-04-07T00:10:45.153371Z","steps":["trace[1876208984] 'process raft request' (duration: 65.301516ms)","trace[1876208984] 'compare' (duration: 54.02272ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:45.602131Z","caller":"traceutil/trace.go:171","msg":"trace[136494617] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"212.524362ms","start":"2026-04-07T00:10:45.389577Z","end":"2026-04-07T00:10:45.602101Z","steps":["trace[136494617] 'process raft request' (duration: 212.373628ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:45.630888Z","caller":"traceutil/trace.go:171","msg":"trace[918804420] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"181.100792ms","start":"2026-04-07T00:10:45.449753Z","end":"2026-04-07T00:10:45.630854Z","steps":["trace[918804420] 'process raft request' (duration: 180.860414ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:45.631034Z","caller":"traceutil/trace.go:171","msg":"trace[1191229900] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"178.426361ms","start":"2026-04-07T00:10:45.452598Z","end":"2026-04-07T00:10:45.631024Z","steps":["trace[1191229900] 'process raft request' (duration: 178.151682ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:46.043115Z","caller":"traceutil/trace.go:171","msg":"trace[1471651406] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"126.785074ms","start":"2026-04-07T00:10:45.916306Z","end":"2026-04-07T00:10:46.043091Z","steps":["trace[1471651406] 'process raft request' (duration: 126.639208ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:46.209769Z","caller":"traceutil/trace.go:171","msg":"trace[1611279609] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"101.305188ms","start":"2026-04-07T00:10:46.108433Z","end":"2026-04-07T00:10:46.209738Z","steps":["trace[1611279609] 'process raft request' (duration: 100.589943ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:46.566221Z","caller":"traceutil/trace.go:171","msg":"trace[851599679] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"340.177439ms","start":"2026-04-07T00:10:46.22602Z","end":"2026-04-07T00:10:46.566197Z","steps":["trace[851599679] 'process raft request' (duration: 340.017574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:46.566378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:46.226003Z","time spent":"340.288163ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:10:46.628857Z","caller":"traceutil/trace.go:171","msg":"trace[652617080] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2136; }","duration":"348.497083ms","start":"2026-04-07T00:10:46.280329Z","end":"2026-04-07T00:10:46.628826Z","steps":["trace[652617080] 'read index received' (duration: 285.659328ms)","trace[652617080] 'applied index is now lower than readState.Index' (duration: 62.836505ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:46.629135Z","caller":"traceutil/trace.go:171","msg":"trace[2047759906] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"349.165886ms","start":"2026-04-07T00:10:46.279911Z","end":"2026-04-07T00:10:46.629077Z","steps":["trace[2047759906] 'process raft request' (duration: 322.386082ms)","trace[2047759906] 'compare' (duration: 26.407611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:46.629197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.844996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T00:10:46.629235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.074157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T00:10:46.629327Z","caller":"traceutil/trace.go:171","msg":"trace[409620270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2056; }","duration":"340.20218ms","start":"2026-04-07T00:10:46.289109Z","end":"2026-04-07T00:10:46.629311Z","steps":["trace[409620270] 'agreement among raft nodes before linearized reading' (duration: 339.994753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:46.62934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:46.279884Z","time spent":"349.343112ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:46.629375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:46.289095Z","time spent":"340.270343ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:10:46.629579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.830704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:10:46.629659Z","caller":"traceutil/trace.go:171","msg":"trace[284510648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2056; }","duration":"331.930507ms","start":"2026-04-07T00:10:46.297715Z","end":"2026-04-07T00:10:46.629646Z","steps":["trace[284510648] 'agreement among raft nodes before linearized reading' (duration: 331.675509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:46.629723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:46.297705Z","time spent":"332.007341ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:10:46.629908Z","caller":"traceutil/trace.go:171","msg":"trace[1318913283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2056; }","duration":"349.006001ms","start":"2026-04-07T00:10:46.280293Z","end":"2026-04-07T00:10:46.629299Z","steps":["trace[1318913283] 'agreement among raft nodes before linearized reading' (duration: 348.773513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:46.629963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:46.28028Z","time spent":"349.670894ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T00:10:46.801993Z","caller":"traceutil/trace.go:171","msg":"trace[89176014] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"168.920224ms","start":"2026-04-07T00:10:46.633048Z","end":"2026-04-07T00:10:46.801968Z","steps":["trace[89176014] 'process raft request' (duration: 152.618098ms)","trace[89176014] 'compare' (duration: 16.097169ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:46.802263Z","caller":"traceutil/trace.go:171","msg":"trace[1220301678] linearizableReadLoop","detail":"{readStateIndex:2141; appliedIndex:2138; }","duration":"164.284457ms","start":"2026-04-07T00:10:46.637938Z","end":"2026-04-07T00:10:46.802222Z","steps":["trace[1220301678] 'read index received' (duration: 147.737091ms)","trace[1220301678] 'applied index is now lower than readState.Index' (duration: 16.546466ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:46.802291Z","caller":"traceutil/trace.go:171","msg":"trace[1612765273] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"168.604115ms","start":"2026-04-07T00:10:46.633665Z","end":"2026-04-07T00:10:46.802269Z","steps":["trace[1612765273] 'process raft request' (duration: 168.251443ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:46.802366Z","caller":"traceutil/trace.go:171","msg":"trace[1841128610] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"168.45751ms","start":"2026-04-07T00:10:46.633881Z","end":"2026-04-07T00:10:46.802338Z","steps":["trace[1841128610] 'process raft request' (duration: 168.231792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:46.802444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.490424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T00:10:46.802466Z","caller":"traceutil/trace.go:171","msg":"trace[869205613] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2059; }","duration":"164.556416ms","start":"2026-04-07T00:10:46.637903Z","end":"2026-04-07T00:10:46.80246Z","steps":["trace[869205613] 'agreement among raft nodes before linearized reading' (duration: 164.440192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:46.802586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.357494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-07T00:10:46.802672Z","caller":"traceutil/trace.go:171","msg":"trace[1115193037] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2059; }","duration":"129.488689ms","start":"2026-04-07T00:10:46.673168Z","end":"2026-04-07T00:10:46.802656Z","steps":["trace[1115193037] 'agreement among raft nodes before linearized reading' (duration: 129.24512ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:00.965405Z","caller":"traceutil/trace.go:171","msg":"trace[1614523555] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"102.412485ms","start":"2026-04-07T00:12:00.862953Z","end":"2026-04-07T00:12:00.965366Z","steps":["trace[1614523555] 'process raft request' (duration: 98.037393ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.454153Z","caller":"traceutil/trace.go:171","msg":"trace[48681543] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"127.558642ms","start":"2026-04-07T00:12:33.326562Z","end":"2026-04-07T00:12:33.454121Z","steps":["trace[48681543] 'process raft request' (duration: 127.51561ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.45438Z","caller":"traceutil/trace.go:171","msg":"trace[1245720916] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"129.022497ms","start":"2026-04-07T00:12:33.325322Z","end":"2026-04-07T00:12:33.454344Z","steps":["trace[1245720916] 'process raft request' (duration: 128.599601ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.019609Z","caller":"traceutil/trace.go:171","msg":"trace[1683908922] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"362.483395ms","start":"2026-04-07T00:12:33.657101Z","end":"2026-04-07T00:12:34.019584Z","steps":["trace[1683908922] 'process raft request' (duration: 362.173515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.019725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.423821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:34.019806Z","caller":"traceutil/trace.go:171","msg":"trace[748776825] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2740; }","duration":"274.569446ms","start":"2026-04-07T00:12:33.745216Z","end":"2026-04-07T00:12:34.019785Z","steps":["trace[748776825] 'agreement among raft nodes before linearized reading' (duration: 274.433641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.020114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:33.657085Z","time spent":"362.606891ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:12:34.019527Z","caller":"traceutil/trace.go:171","msg":"trace[2048921764] linearizableReadLoop","detail":"{readStateIndex:2846; appliedIndex:2845; }","duration":"274.216404ms","start":"2026-04-07T00:12:33.745286Z","end":"2026-04-07T00:12:34.019502Z","steps":["trace[2048921764] 'read index received' (duration: 274.065258ms)","trace[2048921764] 'applied index is now lower than readState.Index' (duration: 149.966µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:34.044544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.590803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:34.044626Z","caller":"traceutil/trace.go:171","msg":"trace[141050859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2742; }","duration":"285.709756ms","start":"2026-04-07T00:12:33.758901Z","end":"2026-04-07T00:12:34.044611Z","steps":["trace[141050859] 'agreement among raft nodes before linearized reading' (duration: 285.583322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.044941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.403034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a3eab34b7e8901\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-07T00:12:34.044981Z","caller":"traceutil/trace.go:171","msg":"trace[1916913255] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a3eab34b7e8901; range_end:; response_count:1; response_revision:2742; }","duration":"141.480848ms","start":"2026-04-07T00:12:33.90349Z","end":"2026-04-07T00:12:34.044971Z","steps":["trace[1916913255] 'agreement among raft nodes before linearized reading' (duration: 141.361943ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:39.230563Z","caller":"traceutil/trace.go:171","msg":"trace[534228917] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2873; }","duration":"103.516179ms","start":"2026-04-07T00:12:39.127031Z","end":"2026-04-07T00:12:39.230547Z","steps":["trace[534228917] 'read index received' (duration: 103.385825ms)","trace[534228917] 'applied index is now lower than readState.Index' (duration: 129.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:39.230592Z","caller":"traceutil/trace.go:171","msg":"trace[188832705] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"121.00403ms","start":"2026-04-07T00:12:39.109566Z","end":"2026-04-07T00:12:39.23057Z","steps":["trace[188832705] 'process raft request' (duration: 120.888975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:39.230682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.637514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:12:39.230699Z","caller":"traceutil/trace.go:171","msg":"trace[382822368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2767; }","duration":"103.692727ms","start":"2026-04-07T00:12:39.127001Z","end":"2026-04-07T00:12:39.230694Z","steps":["trace[382822368] 'agreement among raft nodes before linearized reading' (duration: 103.608573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:20.931411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.531489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:16:20.931603Z","caller":"traceutil/trace.go:171","msg":"trace[1005633410] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"124.412728ms","start":"2026-04-07T00:16:20.807168Z","end":"2026-04-07T00:16:20.93158Z","steps":["trace[1005633410] 'process raft request' (duration: 19.634755ms)","trace[1005633410] 'compare' (duration: 104.371668ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:20.974312Z","caller":"traceutil/trace.go:171","msg":"trace[1473828666] linearizableReadLoop","detail":"{readStateIndex:4802; appliedIndex:4799; }","duration":"106.247012ms","start":"2026-04-07T00:16:20.868049Z","end":"2026-04-07T00:16:20.974296Z","steps":["trace[1473828666] 'read index received' (duration: 63.296424ms)","trace[1473828666] 'applied index is now lower than readState.Index' (duration: 42.949938ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:20.97452Z","caller":"traceutil/trace.go:171","msg":"trace[2132288266] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"129.463235ms","start":"2026-04-07T00:16:20.844974Z","end":"2026-04-07T00:16:20.974437Z","steps":["trace[2132288266] 'process raft request' (duration: 129.234322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:20.975033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.975072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-07T00:16:20.975068Z","caller":"traceutil/trace.go:171","msg":"trace[1739112081] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4621; }","duration":"107.047714ms","start":"2026-04-07T00:16:20.868012Z","end":"2026-04-07T00:16:20.975059Z","steps":["trace[1739112081] 'agreement among raft nodes before linearized reading' (duration: 106.350505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:27.502971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.577536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:16:27.503192Z","caller":"traceutil/trace.go:171","msg":"trace[1383470518] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"298.971343ms","start":"2026-04-07T00:16:27.204181Z","end":"2026-04-07T00:16:27.503153Z","steps":["trace[1383470518] 'process raft request' (duration: 126.107922ms)","trace[1383470518] 'compare' (duration: 171.555463ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:27.503403Z","caller":"traceutil/trace.go:171","msg":"trace[106972200] linearizableReadLoop","detail":"{readStateIndex:4912; appliedIndex:4911; }","duration":"209.190537ms","start":"2026-04-07T00:16:27.294196Z","end":"2026-04-07T00:16:27.503386Z","steps":["trace[106972200] 'read index received' (duration: 36.032572ms)","trace[106972200] 'applied index is now lower than readState.Index' (duration: 173.156505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:27.503592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.381619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T00:16:27.503621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.454811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:27.503714Z","caller":"traceutil/trace.go:171","msg":"trace[2079270090] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4730; }","duration":"209.509582ms","start":"2026-04-07T00:16:27.294145Z","end":"2026-04-07T00:16:27.503655Z","steps":["trace[2079270090] 'agreement among raft nodes before linearized reading' (duration: 209.316079ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:27.50381Z","caller":"traceutil/trace.go:171","msg":"trace[2031982737] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4730; }","duration":"133.672116ms","start":"2026-04-07T00:16:27.370086Z","end":"2026-04-07T00:16:27.503758Z","steps":["trace[2031982737] 'agreement among raft nodes before linearized reading' (duration: 133.465673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:27.50435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.835197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-07T00:16:27.504586Z","caller":"traceutil/trace.go:171","msg":"trace[14092836] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4728; }","duration":"336.09939ms","start":"2026-04-07T00:16:27.168468Z","end":"2026-04-07T00:16:27.504567Z","steps":["trace[14092836] 'range keys from in-memory index tree' (duration: 334.195884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:27.504947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.48513ms","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-07T00:16:27.505026Z","caller":"traceutil/trace.go:171","msg":"trace[86120232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4728; }","duration":"335.33723ms","start":"2026-04-07T00:16:27.169664Z","end":"2026-04-07T00:16:27.505001Z","steps":["trace[86120232] 'range keys from in-memory index tree' (duration: 332.366358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:27.505071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:27.169657Z","time spent":"335.401181ms","remote":"127.0.0.1:33732","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-07T00:16:27.505973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:27.168449Z","time spent":"336.363373ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193181,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-07T00:16:27.823835Z","caller":"traceutil/trace.go:171","msg":"trace[1103151839] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"137.570308ms","start":"2026-04-07T00:16:27.686237Z","end":"2026-04-07T00:16:27.823807Z","steps":["trace[1103151839] 'process raft request' (duration: 44.611589ms)","trace[1103151839] 'compare' (duration: 92.841407ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:28.071767Z","caller":"traceutil/trace.go:171","msg":"trace[348104994] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"376.809532ms","start":"2026-04-07T00:16:27.694931Z","end":"2026-04-07T00:16:28.071741Z","steps":["trace[348104994] 'process raft request' (duration: 345.639403ms)","trace[348104994] 'compare' (duration: 30.840594ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:28.071765Z","caller":"traceutil/trace.go:171","msg":"trace[1640252861] linearizableReadLoop","detail":"{readStateIndex:4918; appliedIndex:4916; }","duration":"370.150681ms","start":"2026-04-07T00:16:27.701558Z","end":"2026-04-07T00:16:28.071709Z","steps":["trace[1640252861] 'read index received' (duration: 29.29971ms)","trace[1640252861] 'applied index is now lower than readState.Index' (duration: 340.849651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:28.071873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:27.694912Z","time spent":"376.906752ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:16:28.072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.411215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T00:16:28.072086Z","caller":"traceutil/trace.go:171","msg":"trace[1398754354] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4735; }","duration":"370.538757ms","start":"2026-04-07T00:16:27.701526Z","end":"2026-04-07T00:16:28.072064Z","steps":["trace[1398754354] 'agreement among raft nodes before linearized reading' (duration: 370.305483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.072145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:27.701509Z","time spent":"370.621397ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-07T00:16:28.0871Z","caller":"traceutil/trace.go:171","msg":"trace[2086155242] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"231.954055ms","start":"2026-04-07T00:16:27.855126Z","end":"2026-04-07T00:16:28.08708Z","steps":["trace[2086155242] 'process raft request' (duration: 231.844173ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.228642Z","caller":"traceutil/trace.go:171","msg":"trace[755142215] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"185.646862ms","start":"2026-04-07T00:16:28.04297Z","end":"2026-04-07T00:16:28.228617Z","steps":["trace[755142215] 'process raft request' (duration: 142.135784ms)","trace[755142215] 'compare' (duration: 43.187304ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:28.228695Z","caller":"traceutil/trace.go:171","msg":"trace[1952914336] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"149.517546ms","start":"2026-04-07T00:16:28.079149Z","end":"2026-04-07T00:16:28.228666Z","steps":["trace[1952914336] 'process raft request' (duration: 149.413664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.22907Z","caller":"traceutil/trace.go:171","msg":"trace[1238683851] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"150.089392ms","start":"2026-04-07T00:16:28.078972Z","end":"2026-04-07T00:16:28.229061Z","steps":["trace[1238683851] 'process raft request' (duration: 149.533315ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.229231Z","caller":"traceutil/trace.go:171","msg":"trace[634192223] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"150.720622ms","start":"2026-04-07T00:16:28.078502Z","end":"2026-04-07T00:16:28.229223Z","steps":["trace[634192223] 'process raft request' (duration: 149.929102ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.229458Z","caller":"traceutil/trace.go:171","msg":"trace[97575497] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4918; }","duration":"157.574666ms","start":"2026-04-07T00:16:28.071854Z","end":"2026-04-07T00:16:28.229429Z","steps":["trace[97575497] 'read index received' (duration: 15.057517ms)","trace[97575497] 'applied index is now lower than readState.Index' (duration: 142.515739ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:28.2296Z","caller":"traceutil/trace.go:171","msg":"trace[1444016899] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"150.275315ms","start":"2026-04-07T00:16:28.0793Z","end":"2026-04-07T00:16:28.229575Z","steps":["trace[1444016899] 'process raft request' (duration: 149.315451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.230863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.755157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3f88bc81-77e4-4158-b3c8-1839dc1ecf7e\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-07T00:16:28.230923Z","caller":"traceutil/trace.go:171","msg":"trace[1364172739] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3f88bc81-77e4-4158-b3c8-1839dc1ecf7e; range_end:; response_count:1; response_revision:4742; }","duration":"154.854488ms","start":"2026-04-07T00:16:28.076052Z","end":"2026-04-07T00:16:28.230906Z","steps":["trace[1364172739] 'agreement among raft nodes before linearized reading' (duration: 154.637605ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.231243Z","caller":"traceutil/trace.go:171","msg":"trace[1090144493] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"141.165181ms","start":"2026-04-07T00:16:28.090061Z","end":"2026-04-07T00:16:28.231227Z","steps":["trace[1090144493] 'process raft request' (duration: 139.209163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.231444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.990478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:16:28.231524Z","caller":"traceutil/trace.go:171","msg":"trace[247197568] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4741; }","duration":"407.114409ms","start":"2026-04-07T00:16:27.824392Z","end":"2026-04-07T00:16:28.231507Z","steps":["trace[247197568] 'agreement among raft nodes before linearized reading' (duration: 405.465837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.231573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:27.824379Z","time spent":"407.18253ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":461,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:16:28.231746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.135296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:28.231798Z","caller":"traceutil/trace.go:171","msg":"trace[901699018] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:4741; }","duration":"302.216448ms","start":"2026-04-07T00:16:27.929563Z","end":"2026-04-07T00:16:28.23178Z","steps":["trace[901699018] 'agreement among raft nodes before linearized reading' (duration: 300.022818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.231837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:27.929543Z","time spent":"302.282179ms","remote":"127.0.0.1:33176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:16:41.178016Z","caller":"traceutil/trace.go:171","msg":"trace[1881206024] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"358.919808ms","start":"2026-04-07T00:16:40.819074Z","end":"2026-04-07T00:16:41.177994Z","steps":["trace[1881206024] 'read index received' (duration: 303.528784ms)","trace[1881206024] 'applied index is now lower than readState.Index' (duration: 55.389884ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:41.178126Z","caller":"traceutil/trace.go:171","msg":"trace[619135866] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"369.446425ms","start":"2026-04-07T00:16:40.808653Z","end":"2026-04-07T00:16:41.178099Z","steps":["trace[619135866] 'process raft request' (duration: 313.934879ms)","trace[619135866] 'compare' (duration: 55.300042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:41.178213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.125332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-04-07T00:16:41.178249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:40.808625Z","time spent":"369.557406ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17135,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:16:41.178257Z","caller":"traceutil/trace.go:171","msg":"trace[1538295190] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4908; }","duration":"359.202363ms","start":"2026-04-07T00:16:40.819042Z","end":"2026-04-07T00:16:41.178244Z","steps":["trace[1538295190] 'agreement among raft nodes before linearized reading' (duration: 359.040161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:41.178328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:40.819031Z","time spent":"359.288904ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-07T00:16:41.477136Z","caller":"traceutil/trace.go:171","msg":"trace[1189819410] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"417.857412ms","start":"2026-04-07T00:16:41.05925Z","end":"2026-04-07T00:16:41.477107Z","steps":["trace[1189819410] 'process raft request' (duration: 392.391993ms)","trace[1189819410] 'compare' (duration: 24.484455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:41.477301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:41.059228Z","time spent":"417.987974ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:16:41.477166Z","caller":"traceutil/trace.go:171","msg":"trace[697812218] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5096; }","duration":"295.806225ms","start":"2026-04-07T00:16:41.181332Z","end":"2026-04-07T00:16:41.477139Z","steps":["trace[697812218] 'read index received' (duration: 270.333776ms)","trace[697812218] 'applied index is now lower than readState.Index' (duration: 25.471289ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:41.477449Z","caller":"traceutil/trace.go:171","msg":"trace[792609908] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"416.423131ms","start":"2026-04-07T00:16:41.060991Z","end":"2026-04-07T00:16:41.477414Z","steps":["trace[792609908] 'process raft request' (duration: 416.009455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:41.477632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.280611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7c8353ff-15d0-4999-921f-56696e8c4c34\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-07T00:16:41.477698Z","caller":"traceutil/trace.go:171","msg":"trace[1347627826] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7c8353ff-15d0-4999-921f-56696e8c4c34; range_end:; response_count:1; response_revision:4910; }","duration":"296.381093ms","start":"2026-04-07T00:16:41.1813Z","end":"2026-04-07T00:16:41.477682Z","steps":["trace[1347627826] 'agreement among raft nodes before linearized reading' (duration: 296.211831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:41.477817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:41.060964Z","time spent":"416.664444ms","remote":"127.0.0.1:33732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:16:41.47798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.945721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-04-07T00:16:41.478069Z","caller":"traceutil/trace.go:171","msg":"trace[883440887] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4910; }","duration":"295.097195ms","start":"2026-04-07T00:16:41.182957Z","end":"2026-04-07T00:16:41.478054Z","steps":["trace[883440887] 'agreement among raft nodes before linearized reading' (duration: 294.723749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:41.478187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.641817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-07T00:16:41.478241Z","caller":"traceutil/trace.go:171","msg":"trace[701901495] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4910; }","duration":"112.736619ms","start":"2026-04-07T00:16:41.36549Z","end":"2026-04-07T00:16:41.478227Z","steps":["trace[701901495] 'agreement among raft nodes before linearized reading' (duration: 112.555416ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:41.939124Z","caller":"traceutil/trace.go:171","msg":"trace[2087062605] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"121.250556ms","start":"2026-04-07T00:16:41.817849Z","end":"2026-04-07T00:16:41.9391Z","steps":["trace[2087062605] 'read index received' (duration: 114.902882ms)","trace[2087062605] 'applied index is now lower than readState.Index' (duration: 6.346414ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:41.93923Z","caller":"traceutil/trace.go:171","msg":"trace[556126296] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"122.513515ms","start":"2026-04-07T00:16:41.816681Z","end":"2026-04-07T00:16:41.939195Z","steps":["trace[556126296] 'process raft request' (duration: 116.068099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:41.939386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.51973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a3eaee286dea77\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-07T00:16:41.939518Z","caller":"traceutil/trace.go:171","msg":"trace[1613955222] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a3eaee286dea77; range_end:; response_count:1; response_revision:4920; }","duration":"121.584361ms","start":"2026-04-07T00:16:41.817834Z","end":"2026-04-07T00:16:41.939418Z","steps":["trace[1613955222] 'agreement among raft nodes before linearized reading' (duration: 121.399988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:42.459794Z","caller":"traceutil/trace.go:171","msg":"trace[1593953360] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"149.619141ms","start":"2026-04-07T00:16:42.310143Z","end":"2026-04-07T00:16:42.459762Z","steps":["trace[1593953360] 'process raft request' (duration: 149.344387ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:20.870175Z","caller":"traceutil/trace.go:171","msg":"trace[1612535550] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"147.412503ms","start":"2026-04-07T00:17:20.722738Z","end":"2026-04-07T00:17:20.870151Z","steps":["trace[1612535550] 'process raft request' (duration: 147.242029ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:01.801496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2886} {"level":"info","ts":"2026-04-07T00:18:01.888798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2886,"took":"86.095319ms","hash":1954056094,"current-db-size-bytes":54153216,"current-db-size":"54 MB","current-db-size-in-use-bytes":29016064,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:18:01.88894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1954056094,"revision":2886,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T00:20:46.601766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.657114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-04-07T00:20:46.60185Z","caller":"traceutil/trace.go:171","msg":"trace[1930919204] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6687; }","duration":"186.789598ms","start":"2026-04-07T00:20:46.415041Z","end":"2026-04-07T00:20:46.601831Z","steps":["trace[1930919204] 'range keys from in-memory index tree' (duration: 186.488718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:20:46.601902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.871862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:20:46.602967Z","caller":"traceutil/trace.go:171","msg":"trace[1320105487] transaction","detail":"{read_only:false; response_revision:6688; number_of_response:1; }","duration":"173.578913ms","start":"2026-04-07T00:20:46.429358Z","end":"2026-04-07T00:20:46.602937Z","steps":["trace[1320105487] 'process raft request' (duration: 11.584618ms)","trace[1320105487] 'compare' (duration: 160.593885ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:01.807566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5548} {"level":"info","ts":"2026-04-07T00:23:01.90373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5548,"took":"94.891634ms","hash":1085710083,"current-db-size-bytes":54153216,"current-db-size":"54 MB","current-db-size-in-use-bytes":18309120,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T00:23:01.903796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1085710083,"revision":5548,"compact-revision":2886} {"level":"info","ts":"2026-04-07T00:23:33.032912Z","caller":"traceutil/trace.go:171","msg":"trace[516373041] transaction","detail":"{read_only:false; response_revision:7555; number_of_response:1; }","duration":"106.69583ms","start":"2026-04-07T00:23:32.926187Z","end":"2026-04-07T00:23:33.032883Z","steps":["trace[516373041] 'process raft request' (duration: 105.881434ms)"],"step_count":1}