{"level":"warn","ts":"2026-03-09T01:40:39.976998Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:40:39.977099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-03-09T01:40:39.977171Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:40:39.977183Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-03-09T01:40:39.977201Z","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-03-09T01:40:39.977799Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-03-09T01:40:39.977874Z","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.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-03-09T01:40:39.982359Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.275865ms"} {"level":"info","ts":"2026-03-09T01:40:39.988448Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-03-09T01:40:39.988542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T01:40:39.988562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-03-09T01:40:39.988577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T01:40:39.988589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-03-09T01:40:39.988632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-03-09T01:40:39.996588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T01:40:39.998214Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T01:40:39.999203Z","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-03-09T01:40:40.001316Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T01:40:40.001485Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T01:40:40.001645Z","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-03-09T01:40:40.001696Z","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-03-09T01:40:40.001711Z","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-03-09T01:40:40.003299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-03-09T01:40:40.004222Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-03-09T01:40:40.006065Z","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-03-09T01:40:40.006269Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-03-09T01:40:40.006351Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-03-09T01:40:40.006442Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T01:40:40.006495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T01:40:40.689343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T01:40:40.68944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T01:40:40.689494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-03-09T01:40:40.68952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-03-09T01:40:40.689657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-03-09T01:40:40.689678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-03-09T01:40:40.689913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-03-09T01:40:40.691084Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T01:40:40.691318Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:40:40.691295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:40:40.691382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:40:40.691905Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T01:40:40.691993Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T01:40:40.692719Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:40:40.692805Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:40:40.692846Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:40:40.695072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T01:40:40.695251Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-03-09T01:40:52.168199Z","caller":"traceutil/trace.go:171","msg":"trace[349407447] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"173.063295ms","start":"2026-03-09T01:40:51.995099Z","end":"2026-03-09T01:40:52.168163Z","steps":["trace[349407447] 'process raft request' (duration: 172.629605ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:52.323902Z","caller":"traceutil/trace.go:171","msg":"trace[303079740] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"145.927598ms","start":"2026-03-09T01:40:52.177941Z","end":"2026-03-09T01:40:52.323869Z","steps":["trace[303079740] 'process raft request' (duration: 76.912992ms)","trace[303079740] 'compare' (duration: 68.836232ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:40:52.498214Z","caller":"traceutil/trace.go:171","msg":"trace[1177572849] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"167.091043ms","start":"2026-03-09T01:40:52.331092Z","end":"2026-03-09T01:40:52.498183Z","steps":["trace[1177572849] 'process raft request' (duration: 126.414132ms)","trace[1177572849] 'compare' (duration: 40.413435ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:40:57.95115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.197194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:40:57.951446Z","caller":"traceutil/trace.go:171","msg":"trace[1283368107] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"402.999335ms","start":"2026-03-09T01:40:57.548425Z","end":"2026-03-09T01:40:57.951424Z","steps":["trace[1283368107] 'process raft request' (duration: 402.948504ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:57.95145Z","caller":"traceutil/trace.go:171","msg":"trace[614591531] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"405.692006ms","start":"2026-03-09T01:40:57.545679Z","end":"2026-03-09T01:40:57.951371Z","steps":["trace[614591531] 'process raft request' (duration: 220.750074ms)","trace[614591531] 'compare' (duration: 184.031631ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:40:57.95151Z","caller":"traceutil/trace.go:171","msg":"trace[883298737] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"403.305842ms","start":"2026-03-09T01:40:57.54816Z","end":"2026-03-09T01:40:57.951466Z","steps":["trace[883298737] 'process raft request' (duration: 403.114978ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:57.951573Z","caller":"traceutil/trace.go:171","msg":"trace[243415495] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"403.176839ms","start":"2026-03-09T01:40:57.548349Z","end":"2026-03-09T01:40:57.951525Z","steps":["trace[243415495] 'process raft request' (duration: 402.994685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:57.951672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:57.548406Z","time spent":"403.085787ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:40:57.95169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:57.54566Z","time spent":"405.91309ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:57.951802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:57.548324Z","time spent":"403.357203ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:57.951809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:57.548148Z","time spent":"403.490297ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:41:17.300898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.324782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:41:17.301012Z","caller":"traceutil/trace.go:171","msg":"trace[243519504] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"256.640178ms","start":"2026-03-09T01:41:17.044355Z","end":"2026-03-09T01:41:17.300996Z","steps":["trace[243519504] 'read index received' (duration: 32.551µs)","trace[243519504] 'applied index is now lower than readState.Index' (duration: 256.604756ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:41:17.301143Z","caller":"traceutil/trace.go:171","msg":"trace[1310316561] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"528.271771ms","start":"2026-03-09T01:41:16.772818Z","end":"2026-03-09T01:41:17.30109Z","steps":["trace[1310316561] 'process raft request' (duration: 99.655532ms)","trace[1310316561] 'compare' (duration: 428.21669ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:41:17.30121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.849322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mntv9\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-03-09T01:41:17.301242Z","caller":"traceutil/trace.go:171","msg":"trace[351450405] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mntv9; range_end:; response_count:1; response_revision:514; }","duration":"256.917314ms","start":"2026-03-09T01:41:17.044314Z","end":"2026-03-09T01:41:17.301231Z","steps":["trace[351450405] 'agreement among raft nodes before linearized reading' (duration: 256.76461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:17.301288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:16.772787Z","time spent":"528.426774ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:41:17.301402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.334878ms","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-03-09T01:41:17.3015Z","caller":"traceutil/trace.go:171","msg":"trace[1787201276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"255.436851ms","start":"2026-03-09T01:41:17.046024Z","end":"2026-03-09T01:41:17.301461Z","steps":["trace[1787201276] 'agreement among raft nodes before linearized reading' (duration: 255.233086ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:41:17.530761Z","caller":"traceutil/trace.go:171","msg":"trace[672421293] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"225.49349ms","start":"2026-03-09T01:41:17.305217Z","end":"2026-03-09T01:41:17.530711Z","steps":["trace[672421293] 'process raft request' (duration: 225.348837ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:41:17.561105Z","caller":"traceutil/trace.go:171","msg":"trace[2128049977] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"246.330837ms","start":"2026-03-09T01:41:17.314756Z","end":"2026-03-09T01:41:17.561087Z","steps":["trace[2128049977] 'process raft request' (duration: 246.218684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:17.93422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.545943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T01:41:17.93422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.365799ms","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-03-09T01:41:17.934315Z","caller":"traceutil/trace.go:171","msg":"trace[1279033529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"280.672905ms","start":"2026-03-09T01:41:17.653615Z","end":"2026-03-09T01:41:17.934288Z","steps":["trace[1279033529] 'range keys from in-memory index tree' (duration: 280.408409ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:41:17.934335Z","caller":"traceutil/trace.go:171","msg":"trace[632011491] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:516; }","duration":"134.497321ms","start":"2026-03-09T01:41:17.799821Z","end":"2026-03-09T01:41:17.934319Z","steps":["trace[632011491] 'range keys from in-memory index tree' (duration: 134.347538ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:41:38.569832Z","caller":"traceutil/trace.go:171","msg":"trace[870775785] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"100.684013ms","start":"2026-03-09T01:41:38.469127Z","end":"2026-03-09T01:41:38.569811Z","steps":["trace[870775785] 'read index received' (duration: 100.54053ms)","trace[870775785] 'applied index is now lower than readState.Index' (duration: 142.543µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:41:38.569963Z","caller":"traceutil/trace.go:171","msg":"trace[319364910] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"360.331273ms","start":"2026-03-09T01:41:38.209621Z","end":"2026-03-09T01:41:38.569952Z","steps":["trace[319364910] 'process raft request' (duration: 360.052137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:38.570065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:38.209601Z","time spent":"360.386424ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:41:38.570328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.838317ms","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-03-09T01:41:38.57041Z","caller":"traceutil/trace.go:171","msg":"trace[1182676283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"100.943298ms","start":"2026-03-09T01:41:38.469446Z","end":"2026-03-09T01:41:38.57039Z","steps":["trace[1182676283] 'agreement among raft nodes before linearized reading' (duration: 100.741774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:38.570415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.284947ms","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-03-09T01:41:38.570481Z","caller":"traceutil/trace.go:171","msg":"trace[2083572589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"101.389338ms","start":"2026-03-09T01:41:38.469078Z","end":"2026-03-09T01:41:38.570468Z","steps":["trace[2083572589] 'agreement among raft nodes before linearized reading' (duration: 101.224945ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:41:56.832541Z","caller":"traceutil/trace.go:171","msg":"trace[1311715082] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"103.6444ms","start":"2026-03-09T01:41:56.728861Z","end":"2026-03-09T01:41:56.832505Z","steps":["trace[1311715082] 'read index received' (duration: 103.317662ms)","trace[1311715082] 'applied index is now lower than readState.Index' (duration: 325.437µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:41:56.832608Z","caller":"traceutil/trace.go:171","msg":"trace[1297564547] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"110.364529ms","start":"2026-03-09T01:41:56.722202Z","end":"2026-03-09T01:41:56.832566Z","steps":["trace[1297564547] 'process raft request' (duration: 110.069112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:56.832929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.049669ms","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-03-09T01:41:56.832981Z","caller":"traceutil/trace.go:171","msg":"trace[1820416131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"104.177882ms","start":"2026-03-09T01:41:56.728788Z","end":"2026-03-09T01:41:56.832966Z","steps":["trace[1820416131] 'agreement among raft nodes before linearized reading' (duration: 103.932416ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:42:20.857701Z","caller":"traceutil/trace.go:171","msg":"trace[891217294] linearizableReadLoop","detail":"{readStateIndex:1566; appliedIndex:1565; }","duration":"201.9833ms","start":"2026-03-09T01:42:20.655695Z","end":"2026-03-09T01:42:20.857678Z","steps":["trace[891217294] 'read index received' (duration: 201.771695ms)","trace[891217294] 'applied index is now lower than readState.Index' (duration: 210.855µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:42:20.857855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.150033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:42:20.857827Z","caller":"traceutil/trace.go:171","msg":"trace[1935770396] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"458.232204ms","start":"2026-03-09T01:42:20.399565Z","end":"2026-03-09T01:42:20.857797Z","steps":["trace[1935770396] 'process raft request' (duration: 457.970319ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:42:20.857889Z","caller":"traceutil/trace.go:171","msg":"trace[825366201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1518; }","duration":"202.222715ms","start":"2026-03-09T01:42:20.655657Z","end":"2026-03-09T01:42:20.85788Z","steps":["trace[825366201] 'agreement among raft nodes before linearized reading' (duration: 202.151374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:42:20.857995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:42:20.399546Z","time spent":"458.359857ms","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3730,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:42:21.184547Z","caller":"traceutil/trace.go:171","msg":"trace[2013820486] linearizableReadLoop","detail":"{readStateIndex:1568; appliedIndex:1567; }","duration":"142.235522ms","start":"2026-03-09T01:42:21.042278Z","end":"2026-03-09T01:42:21.184514Z","steps":["trace[2013820486] 'read index received' (duration: 92.654553ms)","trace[2013820486] 'applied index is now lower than readState.Index' (duration: 49.579269ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:42:21.184626Z","caller":"traceutil/trace.go:171","msg":"trace[768982226] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"230.15851ms","start":"2026-03-09T01:42:20.954434Z","end":"2026-03-09T01:42:21.184592Z","steps":["trace[768982226] 'process raft request' (duration: 180.592132ms)","trace[768982226] 'compare' (duration: 49.317253ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:42:21.184891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.59268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-09T01:42:21.184982Z","caller":"traceutil/trace.go:171","msg":"trace[478940333] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1520; }","duration":"142.748563ms","start":"2026-03-09T01:42:21.042214Z","end":"2026-03-09T01:42:21.184962Z","steps":["trace[478940333] 'agreement among raft nodes before linearized reading' (duration: 142.468247ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:42:26.189548Z","caller":"traceutil/trace.go:171","msg":"trace[1189899948] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"131.067588ms","start":"2026-03-09T01:42:26.05844Z","end":"2026-03-09T01:42:26.189507Z","steps":["trace[1189899948] 'process raft request' (duration: 130.837143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:42:29.30191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.791004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11270"} {"level":"info","ts":"2026-03-09T01:42:29.301992Z","caller":"traceutil/trace.go:171","msg":"trace[920251716] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1609; }","duration":"178.934037ms","start":"2026-03-09T01:42:29.123041Z","end":"2026-03-09T01:42:29.301975Z","steps":["trace[920251716] 'range keys from in-memory index tree' (duration: 178.402365ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:42:29.306378Z","caller":"traceutil/trace.go:171","msg":"trace[1056533811] linearizableReadLoop","detail":"{readStateIndex:1660; appliedIndex:1659; }","duration":"126.280064ms","start":"2026-03-09T01:42:29.180079Z","end":"2026-03-09T01:42:29.30636Z","steps":["trace[1056533811] 'read index received' (duration: 105.020573ms)","trace[1056533811] 'applied index is now lower than readState.Index' (duration: 21.258361ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:42:29.30653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.576028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:42:29.306575Z","caller":"traceutil/trace.go:171","msg":"trace[1853804302] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1609; }","duration":"116.66424ms","start":"2026-03-09T01:42:29.189899Z","end":"2026-03-09T01:42:29.306563Z","steps":["trace[1853804302] 'agreement among raft nodes before linearized reading' (duration: 116.570568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:42:29.30657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.449787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T01:42:29.306712Z","caller":"traceutil/trace.go:171","msg":"trace[1793940010] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1609; }","duration":"126.650992ms","start":"2026-03-09T01:42:29.180032Z","end":"2026-03-09T01:42:29.306683Z","steps":["trace[1793940010] 'agreement among raft nodes before linearized reading' (duration: 126.387446ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:43:43.803402Z","caller":"traceutil/trace.go:171","msg":"trace[762941930] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"136.903856ms","start":"2026-03-09T01:43:43.666468Z","end":"2026-03-09T01:43:43.803372Z","steps":["trace[762941930] 'process raft request' (duration: 136.734562ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:43:43.830478Z","caller":"traceutil/trace.go:171","msg":"trace[97682830] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"161.140831ms","start":"2026-03-09T01:43:43.669313Z","end":"2026-03-09T01:43:43.830454Z","steps":["trace[97682830] 'process raft request' (duration: 161.025598ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:20.240549Z","caller":"traceutil/trace.go:171","msg":"trace[1220759193] linearizableReadLoop","detail":"{readStateIndex:3950; appliedIndex:3948; }","duration":"109.832911ms","start":"2026-03-09T01:46:20.130693Z","end":"2026-03-09T01:46:20.240526Z","steps":["trace[1220759193] 'read index received' (duration: 39.737042ms)","trace[1220759193] 'applied index is now lower than readState.Index' (duration: 70.095029ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:46:20.240659Z","caller":"traceutil/trace.go:171","msg":"trace[216270596] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"109.997515ms","start":"2026-03-09T01:46:20.13063Z","end":"2026-03-09T01:46:20.240627Z","steps":["trace[216270596] 'process raft request' (duration: 109.832121ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:20.240729Z","caller":"traceutil/trace.go:171","msg":"trace[896869507] transaction","detail":"{read_only:false; response_revision:3777; number_of_response:1; }","duration":"110.141697ms","start":"2026-03-09T01:46:20.13056Z","end":"2026-03-09T01:46:20.240701Z","steps":["trace[896869507] 'process raft request' (duration: 39.747212ms)","trace[896869507] 'compare' (duration: 70.001237ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:46:20.240979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.26135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-03-09T01:46:20.241049Z","caller":"traceutil/trace.go:171","msg":"trace[1433303358] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:3778; }","duration":"110.388612ms","start":"2026-03-09T01:46:20.130637Z","end":"2026-03-09T01:46:20.241026Z","steps":["trace[1433303358] 'agreement among raft nodes before linearized reading' (duration: 110.000344ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:25.791411Z","caller":"traceutil/trace.go:171","msg":"trace[2029211398] transaction","detail":"{read_only:false; response_revision:3986; number_of_response:1; }","duration":"119.182872ms","start":"2026-03-09T01:46:25.672173Z","end":"2026-03-09T01:46:25.791356Z","steps":["trace[2029211398] 'process raft request' (duration: 113.943198ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:31.190077Z","caller":"traceutil/trace.go:171","msg":"trace[594811082] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"111.029696ms","start":"2026-03-09T01:46:31.079013Z","end":"2026-03-09T01:46:31.190042Z","steps":["trace[594811082] 'process raft request' (duration: 50.471794ms)","trace[594811082] 'compare' (duration: 60.338027ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:46:31.454638Z","caller":"traceutil/trace.go:171","msg":"trace[28018447] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"235.39702ms","start":"2026-03-09T01:46:31.219214Z","end":"2026-03-09T01:46:31.454611Z","steps":["trace[28018447] 'process raft request' (duration: 138.01928ms)","trace[28018447] 'compare' (duration: 97.239077ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:46:40.19607Z","caller":"traceutil/trace.go:171","msg":"trace[1906688158] transaction","detail":"{read_only:false; response_revision:4259; number_of_response:1; }","duration":"147.555048ms","start":"2026-03-09T01:46:40.048499Z","end":"2026-03-09T01:46:40.196054Z","steps":["trace[1906688158] 'process raft request' (duration: 78.392289ms)","trace[1906688158] 'compare' (duration: 68.933644ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:46:40.196061Z","caller":"traceutil/trace.go:171","msg":"trace[623807198] transaction","detail":"{read_only:false; response_revision:4260; number_of_response:1; }","duration":"146.492134ms","start":"2026-03-09T01:46:40.049544Z","end":"2026-03-09T01:46:40.196037Z","steps":["trace[623807198] 'process raft request' (duration: 146.421973ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:40.401649Z","caller":"traceutil/trace.go:171","msg":"trace[1469672931] transaction","detail":"{read_only:false; response_revision:4261; number_of_response:1; }","duration":"200.240229ms","start":"2026-03-09T01:46:40.201374Z","end":"2026-03-09T01:46:40.401614Z","steps":["trace[1469672931] 'process raft request' (duration: 103.007142ms)","trace[1469672931] 'compare' (duration: 97.066023ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:46:40.401993Z","caller":"traceutil/trace.go:171","msg":"trace[851642225] transaction","detail":"{read_only:false; response_revision:4263; number_of_response:1; }","duration":"199.608276ms","start":"2026-03-09T01:46:40.202372Z","end":"2026-03-09T01:46:40.40198Z","steps":["trace[851642225] 'process raft request' (duration: 199.460263ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:40.401986Z","caller":"traceutil/trace.go:171","msg":"trace[2105295370] transaction","detail":"{read_only:false; response_revision:4262; number_of_response:1; }","duration":"200.377842ms","start":"2026-03-09T01:46:40.201579Z","end":"2026-03-09T01:46:40.401957Z","steps":["trace[2105295370] 'process raft request' (duration: 199.988094ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:40.402232Z","caller":"traceutil/trace.go:171","msg":"trace[1314641075] transaction","detail":"{read_only:false; response_revision:4264; number_of_response:1; }","duration":"198.768247ms","start":"2026-03-09T01:46:40.203446Z","end":"2026-03-09T01:46:40.402215Z","steps":["trace[1314641075] 'process raft request' (duration: 198.475251ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:40.942515Z","caller":"traceutil/trace.go:171","msg":"trace[2014533515] linearizableReadLoop","detail":"{readStateIndex:4458; appliedIndex:4457; }","duration":"109.440532ms","start":"2026-03-09T01:46:40.833054Z","end":"2026-03-09T01:46:40.942494Z","steps":["trace[2014533515] 'read index received' (duration: 109.241677ms)","trace[2014533515] 'applied index is now lower than readState.Index' (duration: 198.015µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:46:40.942582Z","caller":"traceutil/trace.go:171","msg":"trace[1430412070] transaction","detail":"{read_only:false; response_revision:4268; number_of_response:1; }","duration":"271.583825ms","start":"2026-03-09T01:46:40.670972Z","end":"2026-03-09T01:46:40.942556Z","steps":["trace[1430412070] 'process raft request' (duration: 271.388981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:46:40.94302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.945772ms","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-03-09T01:46:40.943125Z","caller":"traceutil/trace.go:171","msg":"trace[1178944281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4268; }","duration":"110.098345ms","start":"2026-03-09T01:46:40.832999Z","end":"2026-03-09T01:46:40.943097Z","steps":["trace[1178944281] 'agreement among raft nodes before linearized reading' (duration: 109.586395ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:46:47.866029Z","caller":"traceutil/trace.go:171","msg":"trace[731751840] transaction","detail":"{read_only:false; response_revision:4434; number_of_response:1; }","duration":"114.972008ms","start":"2026-03-09T01:46:47.751031Z","end":"2026-03-09T01:46:47.866003Z","steps":["trace[731751840] 'process raft request' (duration: 114.823684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:46:48.080633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.797654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-387a3fdb-8341-4daf-a2a2-887f3e559353\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-03-09T01:46:48.080726Z","caller":"traceutil/trace.go:171","msg":"trace[885714372] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-387a3fdb-8341-4daf-a2a2-887f3e559353; range_end:; response_count:1; response_revision:4434; }","duration":"105.998978ms","start":"2026-03-09T01:46:47.974706Z","end":"2026-03-09T01:46:48.080705Z","steps":["trace[885714372] 'range keys from in-memory index tree' (duration: 105.650811ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:50:40.80861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3119} {"level":"info","ts":"2026-03-09T01:50:40.892067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3119,"took":"81.906941ms","hash":3376881753,"current-db-size-bytes":44900352,"current-db-size":"45 MB","current-db-size-in-use-bytes":32702464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-09T01:50:40.892136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3376881753,"revision":3119,"compact-revision":-1} {"level":"info","ts":"2026-03-09T01:52:49.166687Z","caller":"traceutil/trace.go:171","msg":"trace[337123542] transaction","detail":"{read_only:false; response_revision:7524; number_of_response:1; }","duration":"299.325846ms","start":"2026-03-09T01:52:48.86733Z","end":"2026-03-09T01:52:49.166656Z","steps":["trace[337123542] 'process raft request' (duration: 299.145622ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:52:49.356178Z","caller":"traceutil/trace.go:171","msg":"trace[1176966484] linearizableReadLoop","detail":"{readStateIndex:7879; appliedIndex:7878; }","duration":"288.387403ms","start":"2026-03-09T01:52:49.067774Z","end":"2026-03-09T01:52:49.356161Z","steps":["trace[1176966484] 'read index received' (duration: 99.009281ms)","trace[1176966484] 'applied index is now lower than readState.Index' (duration: 189.377252ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:52:49.356335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.549988ms","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-03-09T01:52:49.356299Z","caller":"traceutil/trace.go:171","msg":"trace[1054293136] transaction","detail":"{read_only:false; response_revision:7525; number_of_response:1; }","duration":"488.910444ms","start":"2026-03-09T01:52:48.867358Z","end":"2026-03-09T01:52:49.356269Z","steps":["trace[1054293136] 'process raft request' (duration: 486.415129ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:52:49.356367Z","caller":"traceutil/trace.go:171","msg":"trace[1596720154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7525; }","duration":"288.686171ms","start":"2026-03-09T01:52:49.067673Z","end":"2026-03-09T01:52:49.356359Z","steps":["trace[1596720154] 'agreement among raft nodes before linearized reading' (duration: 288.593169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:49.356446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:48.867334Z","time spent":"489.044106ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:52:56.899736Z","caller":"traceutil/trace.go:171","msg":"trace[769825616] linearizableReadLoop","detail":"{readStateIndex:7926; appliedIndex:7925; }","duration":"425.663249ms","start":"2026-03-09T01:52:56.474047Z","end":"2026-03-09T01:52:56.89971Z","steps":["trace[769825616] 'read index received' (duration: 425.564247ms)","trace[769825616] 'applied index is now lower than readState.Index' (duration: 97.682µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:52:56.899901Z","caller":"traceutil/trace.go:171","msg":"trace[1783909720] transaction","detail":"{read_only:false; response_revision:7568; number_of_response:1; }","duration":"449.589506ms","start":"2026-03-09T01:52:56.450272Z","end":"2026-03-09T01:52:56.899861Z","steps":["trace[1783909720] 'process raft request' (duration: 449.274439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:56.899992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.931545ms","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-03-09T01:52:56.90006Z","caller":"traceutil/trace.go:171","msg":"trace[1266869498] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7568; }","duration":"426.049618ms","start":"2026-03-09T01:52:56.473997Z","end":"2026-03-09T01:52:56.900046Z","steps":["trace[1266869498] 'agreement among raft nodes before linearized reading' (duration: 425.871354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:56.900086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:56.473985Z","time spent":"426.097278ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-09T01:52:56.90009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:56.450239Z","time spent":"449.751279ms","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:52:57.103223Z","caller":"traceutil/trace.go:171","msg":"trace[363399044] linearizableReadLoop","detail":"{readStateIndex:7927; appliedIndex:7926; }","duration":"203.329461ms","start":"2026-03-09T01:52:56.899866Z","end":"2026-03-09T01:52:57.103195Z","steps":["trace[363399044] 'read index received' (duration: 203.089875ms)","trace[363399044] 'applied index is now lower than readState.Index' (duration: 238.116µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:52:57.103322Z","caller":"traceutil/trace.go:171","msg":"trace[745294292] transaction","detail":"{read_only:false; response_revision:7569; number_of_response:1; }","duration":"453.132535ms","start":"2026-03-09T01:52:56.650155Z","end":"2026-03-09T01:52:57.103288Z","steps":["trace[745294292] 'process raft request' (duration: 452.781227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.10344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.195082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T01:52:57.103474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:56.65013Z","time spent":"453.267518ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:52:57.103507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.622782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-03-09T01:52:57.103483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.922764ms","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-03-09T01:52:57.103569Z","caller":"traceutil/trace.go:171","msg":"trace[2075907984] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7569; }","duration":"200.740713ms","start":"2026-03-09T01:52:56.90281Z","end":"2026-03-09T01:52:57.103551Z","steps":["trace[2075907984] 'agreement among raft nodes before linearized reading' (duration: 200.543659ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:52:57.103584Z","caller":"traceutil/trace.go:171","msg":"trace[673016440] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7569; }","duration":"627.046807ms","start":"2026-03-09T01:52:56.476518Z","end":"2026-03-09T01:52:57.103565Z","steps":["trace[673016440] 'agreement among raft nodes before linearized reading' (duration: 626.788581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.103626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:56.476513Z","time spent":"627.103849ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-09T01:52:57.103524Z","caller":"traceutil/trace.go:171","msg":"trace[1610315829] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:7569; }","duration":"195.280354ms","start":"2026-03-09T01:52:56.908199Z","end":"2026-03-09T01:52:57.103479Z","steps":["trace[1610315829] 'agreement among raft nodes before linearized reading' (duration: 195.184192ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:52:57.226021Z","caller":"traceutil/trace.go:171","msg":"trace[453452488] transaction","detail":"{read_only:false; response_revision:7570; number_of_response:1; }","duration":"117.296854ms","start":"2026-03-09T01:52:57.108689Z","end":"2026-03-09T01:52:57.225986Z","steps":["trace[453452488] 'process raft request' (duration: 117.020218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.783145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.204964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:52:57.783229Z","caller":"traceutil/trace.go:171","msg":"trace[1831998081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7575; }","duration":"127.337887ms","start":"2026-03-09T01:52:57.655872Z","end":"2026-03-09T01:52:57.78321Z","steps":["trace[1831998081] 'range keys from in-memory index tree' (duration: 127.075721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.783336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.008269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-09T01:52:57.783362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.929478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bhq5r\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-03-09T01:52:57.783413Z","caller":"traceutil/trace.go:171","msg":"trace[1947642760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7575; }","duration":"343.138382ms","start":"2026-03-09T01:52:57.440256Z","end":"2026-03-09T01:52:57.783395Z","steps":["trace[1947642760] 'range keys from in-memory index tree' (duration: 342.814105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.783422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.808528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-09T01:52:57.783464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:57.440235Z","time spent":"343.220103ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-09T01:52:57.783471Z","caller":"traceutil/trace.go:171","msg":"trace[1769037841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7575; }","duration":"177.90463ms","start":"2026-03-09T01:52:57.605553Z","end":"2026-03-09T01:52:57.783458Z","steps":["trace[1769037841] 'range keys from in-memory index tree' (duration: 177.662216ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:52:57.783501Z","caller":"traceutil/trace.go:171","msg":"trace[491962652] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bhq5r; range_end:; response_count:1; response_revision:7575; }","duration":"334.078081ms","start":"2026-03-09T01:52:57.449371Z","end":"2026-03-09T01:52:57.783449Z","steps":["trace[491962652] 'range keys from in-memory index tree' (duration: 333.726953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.783529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.447003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-03-09T01:52:57.78356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:57.449362Z","time spent":"334.185723ms","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-bhq5r\" "} {"level":"info","ts":"2026-03-09T01:52:57.783609Z","caller":"traceutil/trace.go:171","msg":"trace[1183103021] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7575; }","duration":"159.573776ms","start":"2026-03-09T01:52:57.624016Z","end":"2026-03-09T01:52:57.78359Z","steps":["trace[1183103021] 'range keys from in-memory index tree' (duration: 159.170657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.783638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.440494ms","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-03-09T01:52:57.783678Z","caller":"traceutil/trace.go:171","msg":"trace[1372988255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7575; }","duration":"351.536927ms","start":"2026-03-09T01:52:57.432128Z","end":"2026-03-09T01:52:57.783665Z","steps":["trace[1372988255] 'range keys from in-memory index tree' (duration: 350.822811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:52:57.783707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:52:57.432098Z","time spent":"351.601518ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-09T01:53:13.610145Z","caller":"traceutil/trace.go:171","msg":"trace[415408340] transaction","detail":"{read_only:false; response_revision:7677; number_of_response:1; }","duration":"142.815263ms","start":"2026-03-09T01:53:13.467305Z","end":"2026-03-09T01:53:13.61012Z","steps":["trace[415408340] 'process raft request' (duration: 142.745881ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:13.610202Z","caller":"traceutil/trace.go:171","msg":"trace[415857814] transaction","detail":"{read_only:false; response_revision:7676; number_of_response:1; }","duration":"144.579832ms","start":"2026-03-09T01:53:13.465586Z","end":"2026-03-09T01:53:13.610165Z","steps":["trace[415857814] 'process raft request' (duration: 135.856117ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:21.300236Z","caller":"traceutil/trace.go:171","msg":"trace[1832430304] transaction","detail":"{read_only:false; response_revision:7770; number_of_response:1; }","duration":"101.655724ms","start":"2026-03-09T01:53:21.198548Z","end":"2026-03-09T01:53:21.300203Z","steps":["trace[1832430304] 'process raft request' (duration: 101.4579ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:23.039936Z","caller":"traceutil/trace.go:171","msg":"trace[949530418] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7784; }","duration":"133.338522ms","start":"2026-03-09T01:53:22.906573Z","end":"2026-03-09T01:53:23.039911Z","steps":["trace[949530418] 'process raft request' (duration: 133.054195ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:24.847591Z","caller":"traceutil/trace.go:171","msg":"trace[817970214] linearizableReadLoop","detail":"{readStateIndex:8168; appliedIndex:8167; }","duration":"192.991331ms","start":"2026-03-09T01:53:24.654571Z","end":"2026-03-09T01:53:24.847562Z","steps":["trace[817970214] 'read index received' (duration: 192.817217ms)","trace[817970214] 'applied index is now lower than readState.Index' (duration: 172.784µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:53:24.847714Z","caller":"traceutil/trace.go:171","msg":"trace[1997866186] transaction","detail":"{read_only:false; response_revision:7797; number_of_response:1; }","duration":"294.477553ms","start":"2026-03-09T01:53:24.553199Z","end":"2026-03-09T01:53:24.847677Z","steps":["trace[1997866186] 'process raft request' (duration: 294.196846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:53:24.847784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.206675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:53:24.847819Z","caller":"traceutil/trace.go:171","msg":"trace[1289908197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7797; }","duration":"193.292888ms","start":"2026-03-09T01:53:24.654516Z","end":"2026-03-09T01:53:24.847809Z","steps":["trace[1289908197] 'agreement among raft nodes before linearized reading' (duration: 193.181555ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:24.970918Z","caller":"traceutil/trace.go:171","msg":"trace[1420678393] linearizableReadLoop","detail":"{readStateIndex:8169; appliedIndex:8168; }","duration":"123.220467ms","start":"2026-03-09T01:53:24.847671Z","end":"2026-03-09T01:53:24.970891Z","steps":["trace[1420678393] 'read index received' (duration: 123.013672ms)","trace[1420678393] 'applied index is now lower than readState.Index' (duration: 205.605µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:53:24.971321Z","caller":"traceutil/trace.go:171","msg":"trace[1958325229] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"246.864172ms","start":"2026-03-09T01:53:24.724441Z","end":"2026-03-09T01:53:24.971305Z","steps":["trace[1958325229] 'process raft request' (duration: 246.250718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:53:24.971524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.803384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:53:24.971638Z","caller":"traceutil/trace.go:171","msg":"trace[745591368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7798; }","duration":"121.927187ms","start":"2026-03-09T01:53:24.849662Z","end":"2026-03-09T01:53:24.97159Z","steps":["trace[745591368] 'agreement among raft nodes before linearized reading' (duration: 121.787744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:53:24.972039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.479472ms","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-03-09T01:53:24.972089Z","caller":"traceutil/trace.go:171","msg":"trace[839203820] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7798; }","duration":"259.575875ms","start":"2026-03-09T01:53:24.712502Z","end":"2026-03-09T01:53:24.972078Z","steps":["trace[839203820] 'agreement among raft nodes before linearized reading' (duration: 259.120045ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:36.667441Z","caller":"traceutil/trace.go:171","msg":"trace[2042065336] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"107.318762ms","start":"2026-03-09T01:53:36.560048Z","end":"2026-03-09T01:53:36.667367Z","steps":["trace[2042065336] 'process raft request' (duration: 107.133868ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:37.055324Z","caller":"traceutil/trace.go:171","msg":"trace[1794189950] transaction","detail":"{read_only:false; response_revision:7912; number_of_response:1; }","duration":"108.57685ms","start":"2026-03-09T01:53:36.946709Z","end":"2026-03-09T01:53:37.055286Z","steps":["trace[1794189950] 'process raft request' (duration: 104.10338ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:37.085992Z","caller":"traceutil/trace.go:171","msg":"trace[2072540762] transaction","detail":"{read_only:false; response_revision:7913; number_of_response:1; }","duration":"130.770645ms","start":"2026-03-09T01:53:36.95519Z","end":"2026-03-09T01:53:37.085961Z","steps":["trace[2072540762] 'process raft request' (duration: 130.664341ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:37.655571Z","caller":"traceutil/trace.go:171","msg":"trace[2059161369] transaction","detail":"{read_only:false; response_revision:7914; number_of_response:1; }","duration":"128.114286ms","start":"2026-03-09T01:53:37.527416Z","end":"2026-03-09T01:53:37.65553Z","steps":["trace[2059161369] 'process raft request' (duration: 127.86065ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:38.725173Z","caller":"traceutil/trace.go:171","msg":"trace[244210989] linearizableReadLoop","detail":"{readStateIndex:8303; appliedIndex:8302; }","duration":"185.866383ms","start":"2026-03-09T01:53:38.539283Z","end":"2026-03-09T01:53:38.725149Z","steps":["trace[244210989] 'read index received' (duration: 185.625657ms)","trace[244210989] 'applied index is now lower than readState.Index' (duration: 239.706µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:53:38.725336Z","caller":"traceutil/trace.go:171","msg":"trace[176435492] transaction","detail":"{read_only:false; response_revision:7925; number_of_response:1; }","duration":"257.078879ms","start":"2026-03-09T01:53:38.468223Z","end":"2026-03-09T01:53:38.725302Z","steps":["trace[176435492] 'process raft request' (duration: 256.720521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:53:38.725412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.084917ms","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-03-09T01:53:38.725477Z","caller":"traceutil/trace.go:171","msg":"trace[1939605699] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:7925; }","duration":"186.241161ms","start":"2026-03-09T01:53:38.539221Z","end":"2026-03-09T01:53:38.725462Z","steps":["trace[1939605699] 'agreement among raft nodes before linearized reading' (duration: 186.095247ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:46.595122Z","caller":"traceutil/trace.go:171","msg":"trace[1402364120] linearizableReadLoop","detail":"{readStateIndex:9033; appliedIndex:9031; }","duration":"154.380361ms","start":"2026-03-09T01:54:46.440718Z","end":"2026-03-09T01:54:46.595098Z","steps":["trace[1402364120] 'read index received' (duration: 93.661987ms)","trace[1402364120] 'applied index is now lower than readState.Index' (duration: 60.717104ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:54:46.595309Z","caller":"traceutil/trace.go:171","msg":"trace[994475915] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"154.504473ms","start":"2026-03-09T01:54:46.44078Z","end":"2026-03-09T01:54:46.595284Z","steps":["trace[994475915] 'process raft request' (duration: 154.264398ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:46.595456Z","caller":"traceutil/trace.go:171","msg":"trace[789889060] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"154.786259ms","start":"2026-03-09T01:54:46.440663Z","end":"2026-03-09T01:54:46.59545Z","steps":["trace[789889060] 'process raft request' (duration: 154.33562ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:46.595557Z","caller":"traceutil/trace.go:171","msg":"trace[1436994101] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"155.003104ms","start":"2026-03-09T01:54:46.440456Z","end":"2026-03-09T01:54:46.595459Z","steps":["trace[1436994101] 'process raft request' (duration: 93.82967ms)","trace[1436994101] 'compare' (duration: 60.55442ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:54:46.596204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.458224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:241112"} {"level":"info","ts":"2026-03-09T01:54:46.596378Z","caller":"traceutil/trace.go:171","msg":"trace[81923265] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:8624; }","duration":"155.638498ms","start":"2026-03-09T01:54:46.44071Z","end":"2026-03-09T01:54:46.596349Z","steps":["trace[81923265] 'agreement among raft nodes before linearized reading' (duration: 154.864041ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:40.815244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6343} {"level":"info","ts":"2026-03-09T01:55:40.908998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6343,"took":"91.731188ms","hash":87891702,"current-db-size-bytes":44900352,"current-db-size":"45 MB","current-db-size-in-use-bytes":24244224,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-09T01:55:40.909053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":87891702,"revision":6343,"compact-revision":3119} {"level":"info","ts":"2026-03-09T01:56:31.779018Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-09T01:56:31.784272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-09T01:56:31.784379Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-09T02:00:40.821882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9130} {"level":"info","ts":"2026-03-09T02:00:40.910111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9130,"took":"85.168387ms","hash":527813912,"current-db-size-bytes":44900352,"current-db-size":"45 MB","current-db-size-in-use-bytes":21676032,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-09T02:00:40.910196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":527813912,"revision":9130,"compact-revision":6343}