{"level":"warn","ts":"2026-05-05T12:54:06.402956Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:54:06.403081Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.246: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.246:2380","--initial-cluster=instance=https://199.19.213.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.246: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-05-05T12:54:06.403164Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:54:06.403178Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-05-05T12:54:06.403203Z","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-05-05T12:54:06.404156Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"]} {"level":"info","ts":"2026-05-05T12:54:06.404229Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.246: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-05-05T12:54:06.407989Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.532027ms"} {"level":"info","ts":"2026-05-05T12:54:06.413999Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b4fc23d90360acd","cluster-id":"fbafe76a301d853c"} {"level":"info","ts":"2026-05-05T12:54:06.414075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:54:06.414098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 0"} {"level":"info","ts":"2026-05-05T12:54:06.414117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b4fc23d90360acd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:54:06.414128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 1"} {"level":"info","ts":"2026-05-05T12:54:06.414168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"warn","ts":"2026-05-05T12:54:06.420419Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:54:06.422076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:54:06.424088Z","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-05-05T12:54:06.426869Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b4fc23d90360acd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:54:06.427112Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b4fc23d90360acd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:54:06.427203Z","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-05-05T12:54:06.427318Z","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-05-05T12:54:06.427354Z","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-05-05T12:54:06.428855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"info","ts":"2026-05-05T12:54:06.429868Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","added-peer-id":"2b4fc23d90360acd","added-peer-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-05-05T12:54:06.431196Z","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-05-05T12:54:06.431325Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-05-05T12:54:06.431398Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-05-05T12:54:06.431483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b4fc23d90360acd","initial-advertise-peer-urls":["https://199.19.213.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:54:06.431639Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:54:06.915055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:54:06.91514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:54:06.915167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgPreVoteResp from 2b4fc23d90360acd at term 1"} {"level":"info","ts":"2026-05-05T12:54:06.915181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:54:06.915188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgVoteResp from 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-05-05T12:54:06.915199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became leader at term 2"} {"level":"info","ts":"2026-05-05T12:54:06.91521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b4fc23d90360acd elected leader 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-05-05T12:54:06.916048Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:54:06.916764Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b4fc23d90360acd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.246:2379]}","request-path":"/0/members/2b4fc23d90360acd/attributes","cluster-id":"fbafe76a301d853c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:54:06.916767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:54:06.916856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:54:06.917072Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:54:06.91716Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:54:06.917281Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:54:06.917399Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:54:06.917429Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:54:06.920239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.246:2379"} {"level":"info","ts":"2026-05-05T12:54:06.921589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:54:21.899589Z","caller":"traceutil/trace.go:171","msg":"trace[973719573] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"130.659215ms","start":"2026-05-05T12:54:21.768864Z","end":"2026-05-05T12:54:21.899523Z","steps":["trace[973719573] 'process raft request' (duration: 130.258045ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:22.261789Z","caller":"traceutil/trace.go:171","msg":"trace[80942256] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"263.977165ms","start":"2026-05-05T12:54:21.997792Z","end":"2026-05-05T12:54:22.261769Z","steps":["trace[80942256] 'read index received' (duration: 250.299129ms)","trace[80942256] 'applied index is now lower than readState.Index' (duration: 13.674296ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:22.261881Z","caller":"traceutil/trace.go:171","msg":"trace[1237166350] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"357.044695ms","start":"2026-05-05T12:54:21.904796Z","end":"2026-05-05T12:54:22.261841Z","steps":["trace[1237166350] 'process raft request' (duration: 343.343558ms)","trace[1237166350] 'compare' (duration: 13.473812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:54:22.262464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.60977ms","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-05-05T12:54:22.262588Z","caller":"traceutil/trace.go:171","msg":"trace[414691059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"264.818076ms","start":"2026-05-05T12:54:21.997729Z","end":"2026-05-05T12:54:22.262547Z","steps":["trace[414691059] 'agreement among raft nodes before linearized reading' (duration: 264.173639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:54:22.267243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:54:21.904727Z","time spent":"358.266855ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:54:22.516551Z","caller":"traceutil/trace.go:171","msg":"trace[327408472] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"120.286579ms","start":"2026-05-05T12:54:22.396227Z","end":"2026-05-05T12:54:22.516514Z","steps":["trace[327408472] 'read index received' (duration: 120.025643ms)","trace[327408472] 'applied index is now lower than readState.Index' (duration: 259.646µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:22.516596Z","caller":"traceutil/trace.go:171","msg":"trace[1953272429] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"236.290014ms","start":"2026-05-05T12:54:22.28027Z","end":"2026-05-05T12:54:22.51656Z","steps":["trace[1953272429] 'process raft request' (duration: 236.055038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:54:22.51675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.507175ms","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-05-05T12:54:22.516808Z","caller":"traceutil/trace.go:171","msg":"trace[1133750808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"120.645337ms","start":"2026-05-05T12:54:22.396148Z","end":"2026-05-05T12:54:22.516793Z","steps":["trace[1133750808] 'agreement among raft nodes before linearized reading' (duration: 120.490624ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:39.791242Z","caller":"traceutil/trace.go:171","msg":"trace[405744306] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"164.856276ms","start":"2026-05-05T12:54:39.62634Z","end":"2026-05-05T12:54:39.791196Z","steps":["trace[405744306] 'process raft request' (duration: 152.642236ms)","trace[405744306] 'compare' (duration: 11.77165ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:39.791562Z","caller":"traceutil/trace.go:171","msg":"trace[810966403] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"112.846717ms","start":"2026-05-05T12:54:39.678692Z","end":"2026-05-05T12:54:39.791539Z","steps":["trace[810966403] 'process raft request' (duration: 112.442986ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:39.944366Z","caller":"traceutil/trace.go:171","msg":"trace[683746621] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"139.79689ms","start":"2026-05-05T12:54:39.80453Z","end":"2026-05-05T12:54:39.944327Z","steps":["trace[683746621] 'process raft request' (duration: 139.676198ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:39.944656Z","caller":"traceutil/trace.go:171","msg":"trace[333599536] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"124.968195ms","start":"2026-05-05T12:54:39.81967Z","end":"2026-05-05T12:54:39.944638Z","steps":["trace[333599536] 'process raft request' (duration: 124.595606ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:39.944716Z","caller":"traceutil/trace.go:171","msg":"trace[2007638530] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"143.688055ms","start":"2026-05-05T12:54:39.801006Z","end":"2026-05-05T12:54:39.944694Z","steps":["trace[2007638530] 'process raft request' (duration: 130.644925ms)","trace[2007638530] 'compare' (duration: 12.373294ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:48.633218Z","caller":"traceutil/trace.go:171","msg":"trace[1662398274] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"164.400967ms","start":"2026-05-05T12:54:48.46877Z","end":"2026-05-05T12:54:48.633171Z","steps":["trace[1662398274] 'process raft request' (duration: 164.160872ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:48.808795Z","caller":"traceutil/trace.go:171","msg":"trace[486165185] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"141.978583ms","start":"2026-05-05T12:54:48.666787Z","end":"2026-05-05T12:54:48.808766Z","steps":["trace[486165185] 'read index received' (duration: 140.970919ms)","trace[486165185] 'applied index is now lower than readState.Index' (duration: 1.006334ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:48.809003Z","caller":"traceutil/trace.go:171","msg":"trace[1013532141] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"170.916357ms","start":"2026-05-05T12:54:48.638051Z","end":"2026-05-05T12:54:48.808968Z","steps":["trace[1013532141] 'process raft request' (duration: 169.783109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:54:48.80914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.330193ms","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-05-05T12:54:48.809349Z","caller":"traceutil/trace.go:171","msg":"trace[1506603427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"142.521227ms","start":"2026-05-05T12:54:48.666694Z","end":"2026-05-05T12:54:48.809216Z","steps":["trace[1506603427] 'agreement among raft nodes before linearized reading' (duration: 142.170198ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:04.988493Z","caller":"traceutil/trace.go:171","msg":"trace[707540398] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"109.089091ms","start":"2026-05-05T12:55:04.879377Z","end":"2026-05-05T12:55:04.988466Z","steps":["trace[707540398] 'read index received' (duration: 108.849426ms)","trace[707540398] 'applied index is now lower than readState.Index' (duration: 238.355µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:55:04.988551Z","caller":"traceutil/trace.go:171","msg":"trace[1184631473] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"229.085933ms","start":"2026-05-05T12:55:04.759426Z","end":"2026-05-05T12:55:04.988512Z","steps":["trace[1184631473] 'process raft request' (duration: 228.841527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:55:04.988779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.180341ms","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-05-05T12:55:04.988829Z","caller":"traceutil/trace.go:171","msg":"trace[1556362762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"106.253223ms","start":"2026-05-05T12:55:04.882562Z","end":"2026-05-05T12:55:04.988815Z","steps":["trace[1556362762] 'agreement among raft nodes before linearized reading' (duration: 106.073878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:55:04.988981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.546133ms","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-05-05T12:55:04.989132Z","caller":"traceutil/trace.go:171","msg":"trace[314509054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"109.781839ms","start":"2026-05-05T12:55:04.879295Z","end":"2026-05-05T12:55:04.989077Z","steps":["trace[314509054] 'agreement among raft nodes before linearized reading' (duration: 109.381759ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:33.59347Z","caller":"traceutil/trace.go:171","msg":"trace[780135775] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"256.328315ms","start":"2026-05-05T12:55:33.337119Z","end":"2026-05-05T12:55:33.593448Z","steps":["trace[780135775] 'process raft request' (duration: 256.198002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:47.353944Z","caller":"traceutil/trace.go:171","msg":"trace[2058811357] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"107.486548ms","start":"2026-05-05T12:55:47.246426Z","end":"2026-05-05T12:55:47.353913Z","steps":["trace[2058811357] 'process raft request' (duration: 107.295963ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:55.559677Z","caller":"traceutil/trace.go:171","msg":"trace[324857642] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"127.443521ms","start":"2026-05-05T12:55:55.432204Z","end":"2026-05-05T12:55:55.559648Z","steps":["trace[324857642] 'process raft request' (duration: 127.161425ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:55.559985Z","caller":"traceutil/trace.go:171","msg":"trace[1475322732] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"127.424291ms","start":"2026-05-05T12:55:55.432536Z","end":"2026-05-05T12:55:55.559961Z","steps":["trace[1475322732] 'process raft request' (duration: 127.03265ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:55.560176Z","caller":"traceutil/trace.go:171","msg":"trace[1865254478] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"126.59466ms","start":"2026-05-05T12:55:55.433541Z","end":"2026-05-05T12:55:55.560136Z","steps":["trace[1865254478] 'process raft request' (duration: 126.482187ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:55.560271Z","caller":"traceutil/trace.go:171","msg":"trace[1279072700] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"127.059202ms","start":"2026-05-05T12:55:55.43309Z","end":"2026-05-05T12:55:55.560149Z","steps":["trace[1279072700] 'process raft request' (duration: 126.790936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.7472Z","caller":"traceutil/trace.go:171","msg":"trace[480488085] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"299.341672ms","start":"2026-05-05T12:56:17.447832Z","end":"2026-05-05T12:56:17.747174Z","steps":["trace[480488085] 'read index received' (duration: 299.213749ms)","trace[480488085] 'applied index is now lower than readState.Index' (duration: 126.793µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:56:17.747274Z","caller":"traceutil/trace.go:171","msg":"trace[576965327] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"315.729926ms","start":"2026-05-05T12:56:17.431519Z","end":"2026-05-05T12:56:17.747249Z","steps":["trace[576965327] 'process raft request' (duration: 315.482691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:56:17.747404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:56:17.431497Z","time spent":"315.834408ms","remote":"127.0.0.1:60244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9335,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:56:17.747511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.555277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:56:17.747714Z","caller":"traceutil/trace.go:171","msg":"trace[1316049793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1842; }","duration":"299.808442ms","start":"2026-05-05T12:56:17.447783Z","end":"2026-05-05T12:56:17.747592Z","steps":["trace[1316049793] 'agreement among raft nodes before linearized reading' (duration: 299.506135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:56:17.747892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:56:17.447756Z","time spent":"300.023629ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:56:17.754394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.124488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-05T12:56:17.754456Z","caller":"traceutil/trace.go:171","msg":"trace[1151942420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1843; }","duration":"109.241781ms","start":"2026-05-05T12:56:17.645197Z","end":"2026-05-05T12:56:17.754439Z","steps":["trace[1151942420] 'agreement among raft nodes before linearized reading' (duration: 109.095797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:56:17.754673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.598344ms","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-05-05T12:56:17.75471Z","caller":"traceutil/trace.go:171","msg":"trace[1421695795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"163.678677ms","start":"2026-05-05T12:56:17.591023Z","end":"2026-05-05T12:56:17.754702Z","steps":["trace[1421695795] 'agreement among raft nodes before linearized reading' (duration: 163.581455ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.75478Z","caller":"traceutil/trace.go:171","msg":"trace[475969907] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"224.075ms","start":"2026-05-05T12:56:17.530621Z","end":"2026-05-05T12:56:17.754696Z","steps":["trace[475969907] 'process raft request' (duration: 223.375003ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.896091Z","caller":"traceutil/trace.go:171","msg":"trace[1957568533] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"141.469069ms","start":"2026-05-05T12:56:17.754592Z","end":"2026-05-05T12:56:17.896061Z","steps":["trace[1957568533] 'process raft request' (duration: 137.687165ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.896544Z","caller":"traceutil/trace.go:171","msg":"trace[256818853] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"141.614171ms","start":"2026-05-05T12:56:17.754918Z","end":"2026-05-05T12:56:17.896532Z","steps":["trace[256818853] 'process raft request' (duration: 141.014856ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.897139Z","caller":"traceutil/trace.go:171","msg":"trace[1945455792] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"142.029192ms","start":"2026-05-05T12:56:17.755084Z","end":"2026-05-05T12:56:17.897114Z","steps":["trace[1945455792] 'process raft request' (duration: 141.368625ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.897541Z","caller":"traceutil/trace.go:171","msg":"trace[316959178] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"139.15437ms","start":"2026-05-05T12:56:17.758335Z","end":"2026-05-05T12:56:17.897489Z","steps":["trace[316959178] 'process raft request' (duration: 138.507484ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.898079Z","caller":"traceutil/trace.go:171","msg":"trace[544601517] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"139.647762ms","start":"2026-05-05T12:56:17.758416Z","end":"2026-05-05T12:56:17.898064Z","steps":["trace[544601517] 'process raft request' (duration: 139.003136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.898319Z","caller":"traceutil/trace.go:171","msg":"trace[956321767] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"139.360726ms","start":"2026-05-05T12:56:17.758942Z","end":"2026-05-05T12:56:17.898303Z","steps":["trace[956321767] 'process raft request' (duration: 139.11513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.898165Z","caller":"traceutil/trace.go:171","msg":"trace[35437753] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"139.569031ms","start":"2026-05-05T12:56:17.758555Z","end":"2026-05-05T12:56:17.898124Z","steps":["trace[35437753] 'process raft request' (duration: 139.190752ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.8986Z","caller":"traceutil/trace.go:171","msg":"trace[1976207622] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"137.987173ms","start":"2026-05-05T12:56:17.7606Z","end":"2026-05-05T12:56:17.898587Z","steps":["trace[1976207622] 'process raft request' (duration: 137.637093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:26.494349Z","caller":"traceutil/trace.go:171","msg":"trace[35194454] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"179.119199ms","start":"2026-05-05T12:56:26.315205Z","end":"2026-05-05T12:56:26.494325Z","steps":["trace[35194454] 'process raft request' (duration: 177.669883ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:57:00.26107Z","caller":"traceutil/trace.go:171","msg":"trace[1881517498] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"128.49908ms","start":"2026-05-05T12:57:00.132523Z","end":"2026-05-05T12:57:00.261022Z","steps":["trace[1881517498] 'process raft request' (duration: 78.370065ms)","trace[1881517498] 'compare' (duration: 49.820426ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:57:00.261113Z","caller":"traceutil/trace.go:171","msg":"trace[1493617208] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"125.209118ms","start":"2026-05-05T12:57:00.135867Z","end":"2026-05-05T12:57:00.261076Z","steps":["trace[1493617208] 'process raft request' (duration: 125.035474ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:57:00.2611Z","caller":"traceutil/trace.go:171","msg":"trace[1128157082] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"124.988903ms","start":"2026-05-05T12:57:00.136074Z","end":"2026-05-05T12:57:00.261063Z","steps":["trace[1128157082] 'process raft request' (duration: 124.89742ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:57:00.261594Z","caller":"traceutil/trace.go:171","msg":"trace[597354336] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"125.464414ms","start":"2026-05-05T12:57:00.136114Z","end":"2026-05-05T12:57:00.261578Z","steps":["trace[597354336] 'process raft request' (duration: 124.89683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:57:00.261779Z","caller":"traceutil/trace.go:171","msg":"trace[2143086619] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"125.366192ms","start":"2026-05-05T12:57:00.136379Z","end":"2026-05-05T12:57:00.261745Z","steps":["trace[2143086619] 'process raft request' (duration: 125.016902ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:57:00.262355Z","caller":"traceutil/trace.go:171","msg":"trace[787817677] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"124.608243ms","start":"2026-05-05T12:57:00.13773Z","end":"2026-05-05T12:57:00.262338Z","steps":["trace[787817677] 'process raft request' (duration: 123.869354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:57:00.969819Z","caller":"traceutil/trace.go:171","msg":"trace[1513313019] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"304.581839ms","start":"2026-05-05T12:57:00.665199Z","end":"2026-05-05T12:57:00.969781Z","steps":["trace[1513313019] 'process raft request' (duration: 304.368574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:57:00.970017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:57:00.665172Z","time spent":"304.744994ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:57:38.158674Z","caller":"traceutil/trace.go:171","msg":"trace[1144329534] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"106.848312ms","start":"2026-05-05T12:57:38.051797Z","end":"2026-05-05T12:57:38.158645Z","steps":["trace[1144329534] 'process raft request' (duration: 106.501793ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:35.211993Z","caller":"traceutil/trace.go:171","msg":"trace[307850851] linearizableReadLoop","detail":"{readStateIndex:4745; appliedIndex:4744; }","duration":"208.998782ms","start":"2026-05-05T13:00:35.002953Z","end":"2026-05-05T13:00:35.211952Z","steps":["trace[307850851] 'read index received' (duration: 122.737082ms)","trace[307850851] 'applied index is now lower than readState.Index' (duration: 86.25988ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:00:35.212027Z","caller":"traceutil/trace.go:171","msg":"trace[9702776] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"220.332372ms","start":"2026-05-05T13:00:34.991644Z","end":"2026-05-05T13:00:35.211976Z","steps":["trace[9702776] 'process raft request' (duration: 134.023561ms)","trace[9702776] 'compare' (duration: 86.044365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:00:35.212312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.332752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb3fa469-5f37-48a9-9a49-25683aad65e2\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-05-05T13:00:35.21242Z","caller":"traceutil/trace.go:171","msg":"trace[132721367] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb3fa469-5f37-48a9-9a49-25683aad65e2; range_end:; response_count:1; response_revision:4552; }","duration":"209.486994ms","start":"2026-05-05T13:00:35.002911Z","end":"2026-05-05T13:00:35.212398Z","steps":["trace[132721367] 'agreement among raft nodes before linearized reading' (duration: 209.131605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:00:35.212455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.363221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mvpc4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:00:35.212515Z","caller":"traceutil/trace.go:171","msg":"trace[600967816] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mvpc4; range_end:; response_count:1; response_revision:4552; }","duration":"179.490344ms","start":"2026-05-05T13:00:35.033013Z","end":"2026-05-05T13:00:35.212503Z","steps":["trace[600967816] 'agreement among raft nodes before linearized reading' (duration: 179.274909ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:48.910928Z","caller":"traceutil/trace.go:171","msg":"trace[889537592] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"166.246196ms","start":"2026-05-05T13:00:48.744651Z","end":"2026-05-05T13:00:48.910897Z","steps":["trace[889537592] 'process raft request' (duration: 166.037201ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:49.159241Z","caller":"traceutil/trace.go:171","msg":"trace[449317990] linearizableReadLoop","detail":"{readStateIndex:5006; appliedIndex:5005; }","duration":"188.789313ms","start":"2026-05-05T13:00:48.970421Z","end":"2026-05-05T13:00:49.159211Z","steps":["trace[449317990] 'read index received' (duration: 180.870857ms)","trace[449317990] 'applied index is now lower than readState.Index' (duration: 7.917396ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:00:49.159433Z","caller":"traceutil/trace.go:171","msg":"trace[95030046] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"320.130428ms","start":"2026-05-05T13:00:48.839245Z","end":"2026-05-05T13:00:49.159375Z","steps":["trace[95030046] 'process raft request' (duration: 312.055328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:00:49.159567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.131381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-5np2c\" ","response":"range_response_count:1 size:7813"} {"level":"warn","ts":"2026-05-05T13:00:49.159546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.206002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:00:49.159621Z","caller":"traceutil/trace.go:171","msg":"trace[335625531] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-5np2c; range_end:; response_count:1; response_revision:4802; }","duration":"189.243894ms","start":"2026-05-05T13:00:48.970363Z","end":"2026-05-05T13:00:49.159607Z","steps":["trace[335625531] 'agreement among raft nodes before linearized reading' (duration: 189.009309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:49.159639Z","caller":"traceutil/trace.go:171","msg":"trace[1944901978] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4802; }","duration":"159.436958ms","start":"2026-05-05T13:00:49.000182Z","end":"2026-05-05T13:00:49.159619Z","steps":["trace[1944901978] 'agreement among raft nodes before linearized reading' (duration: 159.218113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:00:49.159725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:00:48.839213Z","time spent":"320.367803ms","remote":"127.0.0.1:60310","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-05-05T13:00:55.982743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.189524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2319"} {"level":"warn","ts":"2026-05-05T13:00:55.982839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.323464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-05T13:00:55.982974Z","caller":"traceutil/trace.go:171","msg":"trace[517363270] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4971; }","duration":"180.531949ms","start":"2026-05-05T13:00:55.802413Z","end":"2026-05-05T13:00:55.982945Z","steps":["trace[517363270] 'range keys from in-memory index tree' (duration: 180.106229ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:55.982973Z","caller":"traceutil/trace.go:171","msg":"trace[787044687] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4971; }","duration":"236.467461ms","start":"2026-05-05T13:00:55.746446Z","end":"2026-05-05T13:00:55.982913Z","steps":["trace[787044687] 'range keys from in-memory index tree' (duration: 235.97717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:00:55.983011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.718607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-05T13:00:55.983155Z","caller":"traceutil/trace.go:171","msg":"trace[852883111] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4971; }","duration":"185.887572ms","start":"2026-05-05T13:00:55.797222Z","end":"2026-05-05T13:00:55.98311Z","steps":["trace[852883111] 'range keys from in-memory index tree' (duration: 185.425091ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:56.139411Z","caller":"traceutil/trace.go:171","msg":"trace[1565710019] linearizableReadLoop","detail":"{readStateIndex:5183; appliedIndex:5181; }","duration":"101.306862ms","start":"2026-05-05T13:00:56.038066Z","end":"2026-05-05T13:00:56.139373Z","steps":["trace[1565710019] 'read index received' (duration: 34.714478ms)","trace[1565710019] 'applied index is now lower than readState.Index' (duration: 66.591114ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:00:56.139561Z","caller":"traceutil/trace.go:171","msg":"trace[1170941396] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"142.421818ms","start":"2026-05-05T13:00:55.997096Z","end":"2026-05-05T13:00:56.139518Z","steps":["trace[1170941396] 'process raft request' (duration: 142.050718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:00:56.13974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.646331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e286d44-353f-48d0-a528-779889707de4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T13:00:56.13978Z","caller":"traceutil/trace.go:171","msg":"trace[1951194611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e286d44-353f-48d0-a528-779889707de4; range_end:; response_count:1; response_revision:4974; }","duration":"101.753224ms","start":"2026-05-05T13:00:56.038017Z","end":"2026-05-05T13:00:56.13977Z","steps":["trace[1951194611] 'agreement among raft nodes before linearized reading' (duration: 101.549449ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:06.948755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3270} {"level":"info","ts":"2026-05-05T13:04:07.035523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3270,"took":"84.955803ms","hash":2604479388,"current-db-size-bytes":59478016,"current-db-size":"60 MB","current-db-size-in-use-bytes":33357824,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T13:04:07.035618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2604479388,"revision":3270,"compact-revision":-1} {"level":"info","ts":"2026-05-05T13:06:51.529341Z","caller":"traceutil/trace.go:171","msg":"trace[2105679972] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"124.956513ms","start":"2026-05-05T13:06:51.404337Z","end":"2026-05-05T13:06:51.529294Z","steps":["trace[2105679972] 'process raft request' (duration: 124.691476ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:03.203789Z","caller":"traceutil/trace.go:171","msg":"trace[878460086] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"233.179894ms","start":"2026-05-05T13:07:02.97058Z","end":"2026-05-05T13:07:03.20376Z","steps":["trace[878460086] 'process raft request' (duration: 232.887967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:03.227447Z","caller":"traceutil/trace.go:171","msg":"trace[1428921256] linearizableReadLoop","detail":"{readStateIndex:8643; appliedIndex:8641; }","duration":"221.75338ms","start":"2026-05-05T13:07:03.005681Z","end":"2026-05-05T13:07:03.227434Z","steps":["trace[1428921256] 'read index received' (duration: 198.027632ms)","trace[1428921256] 'applied index is now lower than readState.Index' (duration: 23.724748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:07:03.227572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.870533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:07:03.227595Z","caller":"traceutil/trace.go:171","msg":"trace[495669408] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8273; }","duration":"221.961875ms","start":"2026-05-05T13:07:03.005626Z","end":"2026-05-05T13:07:03.227588Z","steps":["trace[495669408] 'agreement among raft nodes before linearized reading' (duration: 221.869863ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:03.227794Z","caller":"traceutil/trace.go:171","msg":"trace[496776687] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"253.420114ms","start":"2026-05-05T13:07:02.974326Z","end":"2026-05-05T13:07:03.227746Z","steps":["trace[496776687] 'process raft request' (duration: 253.041314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:07:03.429939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.199207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18202"} {"level":"info","ts":"2026-05-05T13:07:03.430008Z","caller":"traceutil/trace.go:171","msg":"trace[75787228] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8273; }","duration":"200.293419ms","start":"2026-05-05T13:07:03.2297Z","end":"2026-05-05T13:07:03.429993Z","steps":["trace[75787228] 'range keys from in-memory index tree' (duration: 200.057443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:07:04.009459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.295121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:07:04.009762Z","caller":"traceutil/trace.go:171","msg":"trace[647483447] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"327.719482ms","start":"2026-05-05T13:07:03.682019Z","end":"2026-05-05T13:07:04.009738Z","steps":["trace[647483447] 'process raft request' (duration: 327.661411ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:04.009788Z","caller":"traceutil/trace.go:171","msg":"trace[2071505005] transaction","detail":"{read_only:false; response_revision:8277; number_of_response:1; }","duration":"328.80952ms","start":"2026-05-05T13:07:03.68096Z","end":"2026-05-05T13:07:04.00977Z","steps":["trace[2071505005] 'process raft request' (duration: 328.653927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:07:04.009858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:07:03.682007Z","time spent":"327.810896ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:07:04.009986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:07:03.680942Z","time spent":"328.936085ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:07:04.009742Z","caller":"traceutil/trace.go:171","msg":"trace[1167302316] transaction","detail":"{read_only:false; response_revision:8276; number_of_response:1; }","duration":"329.153208ms","start":"2026-05-05T13:07:03.680543Z","end":"2026-05-05T13:07:04.009696Z","steps":["trace[1167302316] 'process raft request' (duration: 50.304425ms)","trace[1167302316] 'compare' (duration: 277.513551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:07:04.010473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:07:03.680521Z","time spent":"329.870017ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:07:07.19403Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021e3.wal"} {"level":"info","ts":"2026-05-05T13:07:31.966583Z","caller":"traceutil/trace.go:171","msg":"trace[1657903201] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8897; }","duration":"153.400886ms","start":"2026-05-05T13:07:31.813149Z","end":"2026-05-05T13:07:31.96655Z","steps":["trace[1657903201] 'read index received' (duration: 153.12066ms)","trace[1657903201] 'applied index is now lower than readState.Index' (duration: 279.226µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:07:31.966655Z","caller":"traceutil/trace.go:171","msg":"trace[761356829] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"154.609837ms","start":"2026-05-05T13:07:31.811998Z","end":"2026-05-05T13:07:31.966608Z","steps":["trace[761356829] 'process raft request' (duration: 154.351031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:07:31.966956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.782608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mvpc4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:07:31.967067Z","caller":"traceutil/trace.go:171","msg":"trace[119247166] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mvpc4; range_end:; response_count:1; response_revision:8516; }","duration":"153.959232ms","start":"2026-05-05T13:07:31.813077Z","end":"2026-05-05T13:07:31.967036Z","steps":["trace[119247166] 'agreement among raft nodes before linearized reading' (duration: 153.638924ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:32.188601Z","caller":"traceutil/trace.go:171","msg":"trace[2012359862] transaction","detail":"{read_only:false; response_revision:8517; number_of_response:1; }","duration":"217.966217ms","start":"2026-05-05T13:07:31.970604Z","end":"2026-05-05T13:07:32.18857Z","steps":["trace[2012359862] 'process raft request' (duration: 192.800944ms)","trace[2012359862] 'compare' (duration: 24.273291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:07:32.189051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.582847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:208181"} {"level":"info","ts":"2026-05-05T13:07:32.189118Z","caller":"traceutil/trace.go:171","msg":"trace[1147498265] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8517; }","duration":"217.71004ms","start":"2026-05-05T13:07:31.971396Z","end":"2026-05-05T13:07:32.189106Z","steps":["trace[1147498265] 'agreement among raft nodes before linearized reading' (duration: 217.32791ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:32.188449Z","caller":"traceutil/trace.go:171","msg":"trace[369972763] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"216.967802ms","start":"2026-05-05T13:07:31.971457Z","end":"2026-05-05T13:07:32.188425Z","steps":["trace[369972763] 'read index received' (duration: 191.874691ms)","trace[369972763] 'applied index is now lower than readState.Index' (duration: 25.091801ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:07:45.581977Z","caller":"traceutil/trace.go:171","msg":"trace[958495196] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9040; }","duration":"135.516513ms","start":"2026-05-05T13:07:45.44642Z","end":"2026-05-05T13:07:45.581937Z","steps":["trace[958495196] 'read index received' (duration: 91.812942ms)","trace[958495196] 'applied index is now lower than readState.Index' (duration: 43.70133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:07:45.582296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.818979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:07:45.582409Z","caller":"traceutil/trace.go:171","msg":"trace[61918828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8650; }","duration":"136.008385ms","start":"2026-05-05T13:07:45.446374Z","end":"2026-05-05T13:07:45.582382Z","steps":["trace[61918828] 'agreement among raft nodes before linearized reading' (duration: 135.779319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:46.335818Z","caller":"traceutil/trace.go:171","msg":"trace[93082379] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"255.452224ms","start":"2026-05-05T13:07:46.080328Z","end":"2026-05-05T13:07:46.33578Z","steps":["trace[93082379] 'process raft request' (duration: 255.2759ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:47.066267Z","caller":"traceutil/trace.go:171","msg":"trace[1216000803] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"113.383145ms","start":"2026-05-05T13:07:46.952832Z","end":"2026-05-05T13:07:47.066216Z","steps":["trace[1216000803] 'process raft request' (duration: 80.801231ms)","trace[1216000803] 'compare' (duration: 32.384329ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:09:06.954955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6600} {"level":"info","ts":"2026-05-05T13:09:07.055454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6600,"took":"98.659721ms","hash":3228079670,"current-db-size-bytes":59478016,"current-db-size":"60 MB","current-db-size-in-use-bytes":26779648,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T13:09:07.055545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3228079670,"revision":6600,"compact-revision":3270} {"level":"info","ts":"2026-05-05T13:09:12.961248Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b4fc23d90360acd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T13:09:12.969057Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T13:09:12.969167Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T13:12:36.028584Z","caller":"traceutil/trace.go:171","msg":"trace[739488379] transaction","detail":"{read_only:false; response_revision:11584; number_of_response:1; }","duration":"140.301408ms","start":"2026-05-05T13:12:35.888236Z","end":"2026-05-05T13:12:36.028537Z","steps":["trace[739488379] 'process raft request' (duration: 140.007351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:12:36.376835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.495164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:12:36.377154Z","caller":"traceutil/trace.go:171","msg":"trace[1732798583] transaction","detail":"{read_only:false; response_revision:11588; number_of_response:1; }","duration":"201.214255ms","start":"2026-05-05T13:12:36.175894Z","end":"2026-05-05T13:12:36.377108Z","steps":["trace[1732798583] 'process raft request' (duration: 88.320941ms)","trace[1732798583] 'compare' (duration: 112.307669ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:12:36.377199Z","caller":"traceutil/trace.go:171","msg":"trace[51536185] transaction","detail":"{read_only:false; response_revision:11589; number_of_response:1; }","duration":"201.274286ms","start":"2026-05-05T13:12:36.175893Z","end":"2026-05-05T13:12:36.377167Z","steps":["trace[51536185] 'process raft request' (duration: 201.073371ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:06.961661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9507} {"level":"info","ts":"2026-05-05T13:14:07.044981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9507,"took":"80.059997ms","hash":1045151641,"current-db-size-bytes":59478016,"current-db-size":"60 MB","current-db-size-in-use-bytes":28389376,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T13:14:07.04506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1045151641,"revision":9507,"compact-revision":6600} {"level":"warn","ts":"2026-05-05T13:18:26.186242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.284216ms","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-05-05T13:18:26.186505Z","caller":"traceutil/trace.go:171","msg":"trace[1192633254] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14454; }","duration":"151.582613ms","start":"2026-05-05T13:18:26.034888Z","end":"2026-05-05T13:18:26.18647Z","steps":["trace[1192633254] 'range keys from in-memory index tree' (duration: 151.250625ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:18:26.186678Z","caller":"traceutil/trace.go:171","msg":"trace[1518778471] linearizableReadLoop","detail":"{readStateIndex:15124; appliedIndex:15123; }","duration":"109.555909ms","start":"2026-05-05T13:18:26.077072Z","end":"2026-05-05T13:18:26.186628Z","steps":["trace[1518778471] 'read index received' (duration: 15.341279ms)","trace[1518778471] 'applied index is now lower than readState.Index' (duration: 94.21253ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:18:26.186961Z","caller":"traceutil/trace.go:171","msg":"trace[1536687282] transaction","detail":"{read_only:false; response_revision:14455; number_of_response:1; }","duration":"176.17159ms","start":"2026-05-05T13:18:26.010766Z","end":"2026-05-05T13:18:26.186937Z","steps":["trace[1536687282] 'process raft request' (duration: 81.635542ms)","trace[1536687282] 'compare' (duration: 93.964853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:18:26.187318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.197375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-05T13:18:26.187396Z","caller":"traceutil/trace.go:171","msg":"trace[2141438025] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14455; }","duration":"110.339548ms","start":"2026-05-05T13:18:26.077038Z","end":"2026-05-05T13:18:26.187377Z","steps":["trace[2141438025] 'agreement among raft nodes before linearized reading' (duration: 110.015001ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:19:06.96775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12332} {"level":"info","ts":"2026-05-05T13:19:07.059668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12332,"took":"89.356911ms","hash":3843780060,"current-db-size-bytes":59478016,"current-db-size":"60 MB","current-db-size-in-use-bytes":25202688,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-05T13:19:07.059762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3843780060,"revision":12332,"compact-revision":9507}