{"level":"warn","ts":"2026-07-02T05:10:20.347119Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:10:20.347235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07-02T05:10:20.347312Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:10:20.347326Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-02T05:10:20.34735Z","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-07-02T05:10:20.347961Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-02T05:10:20.348358Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07-02T05:10:20.352692Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.001661ms"} {"level":"info","ts":"2026-07-02T05:10:20.363741Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-02T05:10:20.363811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:10:20.363859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:10:20.363875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:10:20.363887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:10:20.363934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-02T05:10:20.36928Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:10:20.372455Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:10:20.374035Z","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-07-02T05:10:20.37785Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:10:20.378015Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:10:20.378142Z","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-07-02T05:10:20.378262Z","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-07-02T05:10:20.378275Z","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-07-02T05:10:20.379573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-02T05:10:20.379693Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-02T05:10:20.382071Z","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-07-02T05:10:20.382358Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:10:20.382406Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:10:20.382627Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-02T05:10:20.382652Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-02T05:10:20.664708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:10:20.66477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:10:20.664819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-02T05:10:20.664837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:10:20.664849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-02T05:10:20.664869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:10:20.664884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-02T05:10:20.670173Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:10:20.670331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:10:20.670411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:10:20.670791Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:20.673136Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:10:20.673231Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:10:20.674191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-02T05:10:20.675361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:10:20.687289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:20.687509Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:20.687563Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:22.33061Z","caller":"traceutil/trace.go:171","msg":"trace[1223966832] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"129.851959ms","start":"2026-07-02T05:10:22.200725Z","end":"2026-07-02T05:10:22.330577Z","steps":["trace[1223966832] 'process raft request' (duration: 125.823318ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:22.330968Z","caller":"traceutil/trace.go:171","msg":"trace[1328980900] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"117.267057ms","start":"2026-07-02T05:10:22.213672Z","end":"2026-07-02T05:10:22.330939Z","steps":["trace[1328980900] 'process raft request' (duration: 117.081504ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:22.331506Z","caller":"traceutil/trace.go:171","msg":"trace[1864263399] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"124.883699ms","start":"2026-07-02T05:10:22.205932Z","end":"2026-07-02T05:10:22.330816Z","steps":["trace[1864263399] 'process raft request' (duration: 124.570623ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:33.756276Z","caller":"traceutil/trace.go:171","msg":"trace[774589508] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"196.795368ms","start":"2026-07-02T05:10:33.559456Z","end":"2026-07-02T05:10:33.756251Z","steps":["trace[774589508] 'process raft request' (duration: 196.654015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:56.878253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.440161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:10:56.878518Z","caller":"traceutil/trace.go:171","msg":"trace[1021587865] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"212.702817ms","start":"2026-07-02T05:10:56.665754Z","end":"2026-07-02T05:10:56.878457Z","steps":["trace[1021587865] 'process raft request' (duration: 79.623553ms)","trace[1021587865] 'compare' (duration: 132.230828ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:56.878571Z","caller":"traceutil/trace.go:171","msg":"trace[2093925689] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"202.543613ms","start":"2026-07-02T05:10:56.676002Z","end":"2026-07-02T05:10:56.878546Z","steps":["trace[2093925689] 'process raft request' (duration: 202.38726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:56.87883Z","caller":"traceutil/trace.go:171","msg":"trace[1909929277] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"139.520633ms","start":"2026-07-02T05:10:56.739259Z","end":"2026-07-02T05:10:56.87878Z","steps":["trace[1909929277] 'process raft request' (duration: 139.192516ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:02.709472Z","caller":"traceutil/trace.go:171","msg":"trace[1529444815] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"110.674765ms","start":"2026-07-02T05:11:02.598771Z","end":"2026-07-02T05:11:02.709446Z","steps":["trace[1529444815] 'process raft request' (duration: 110.176346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.168684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.091355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:11:03.168769Z","caller":"traceutil/trace.go:171","msg":"trace[1494116615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"251.229698ms","start":"2026-07-02T05:11:02.917521Z","end":"2026-07-02T05:11:03.168751Z","steps":["trace[1494116615] 'range keys from in-memory index tree' (duration: 250.909081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.169048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.596786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:11:03.169087Z","caller":"traceutil/trace.go:171","msg":"trace[886408721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"259.673496ms","start":"2026-07-02T05:11:02.909401Z","end":"2026-07-02T05:11:03.169075Z","steps":["trace[886408721] 'range keys from in-memory index tree' (duration: 259.454613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.16932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.695137ms","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-07-02T05:11:03.170001Z","caller":"traceutil/trace.go:171","msg":"trace[2078007153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"255.84106ms","start":"2026-07-02T05:11:02.91354Z","end":"2026-07-02T05:11:03.169381Z","steps":["trace[2078007153] 'range keys from in-memory index tree' (duration: 255.491274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:03.518131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.368509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:03.518261Z","caller":"traceutil/trace.go:171","msg":"trace[343599140] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"176.234997ms","start":"2026-07-02T05:11:03.342006Z","end":"2026-07-02T05:11:03.518241Z","steps":["trace[343599140] 'process raft request' (duration: 66.536891ms)","trace[343599140] 'compare' (duration: 109.236066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:04.04925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.858841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:11:04.049523Z","caller":"traceutil/trace.go:171","msg":"trace[842942433] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"538.5961ms","start":"2026-07-02T05:11:03.510905Z","end":"2026-07-02T05:11:04.049501Z","steps":["trace[842942433] 'process raft request' (duration: 538.494708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:11:04.049635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.510876Z","time spent":"538.702262ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:11:04.049659Z","caller":"traceutil/trace.go:171","msg":"trace[1856435573] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"704.017961ms","start":"2026-07-02T05:11:03.34559Z","end":"2026-07-02T05:11:04.049608Z","steps":["trace[1856435573] 'process raft request' (duration: 457.725091ms)","trace[1856435573] 'compare' (duration: 245.667378ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:11:04.049815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:11:03.345566Z","time spent":"704.183513ms","remote":"127.0.0.1:48622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:12:30.199124Z","caller":"traceutil/trace.go:171","msg":"trace[363773455] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"222.315764ms","start":"2026-07-02T05:12:29.976767Z","end":"2026-07-02T05:12:30.199083Z","steps":["trace[363773455] 'process raft request' (duration: 221.987186ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:15.755689Z","caller":"traceutil/trace.go:171","msg":"trace[409800141] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"105.979826ms","start":"2026-07-02T05:13:15.64967Z","end":"2026-07-02T05:13:15.75565Z","steps":["trace[409800141] 'process raft request' (duration: 105.635999ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:16.246003Z","caller":"traceutil/trace.go:171","msg":"trace[81760140] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"215.231855ms","start":"2026-07-02T05:13:16.030747Z","end":"2026-07-02T05:13:16.245978Z","steps":["trace[81760140] 'read index received' (duration: 215.093443ms)","trace[81760140] 'applied index is now lower than readState.Index' (duration: 137.282µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:16.246058Z","caller":"traceutil/trace.go:171","msg":"trace[2077743041] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"218.650972ms","start":"2026-07-02T05:13:16.027382Z","end":"2026-07-02T05:13:16.246033Z","steps":["trace[2077743041] 'process raft request' (duration: 218.434078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:16.246282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.771261ms","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-07-02T05:13:16.246327Z","caller":"traceutil/trace.go:171","msg":"trace[1376297413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2103; }","duration":"176.858794ms","start":"2026-07-02T05:13:16.069456Z","end":"2026-07-02T05:13:16.246315Z","steps":["trace[1376297413] 'agreement among raft nodes before linearized reading' (duration: 176.66513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:16.246458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.911237ms","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-07-02T05:13:16.246616Z","caller":"traceutil/trace.go:171","msg":"trace[1595776181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2103; }","duration":"169.106062ms","start":"2026-07-02T05:13:16.077481Z","end":"2026-07-02T05:13:16.246587Z","steps":["trace[1595776181] 'agreement among raft nodes before linearized reading' (duration: 168.720953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:16.246406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.581811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:16.246835Z","caller":"traceutil/trace.go:171","msg":"trace[508360510] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2103; }","duration":"216.074272ms","start":"2026-07-02T05:13:16.030703Z","end":"2026-07-02T05:13:16.246777Z","steps":["trace[508360510] 'agreement among raft nodes before linearized reading' (duration: 215.392918ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:09.059987Z","caller":"traceutil/trace.go:171","msg":"trace[1187361976] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"169.231674ms","start":"2026-07-02T05:14:08.890722Z","end":"2026-07-02T05:14:09.059953Z","steps":["trace[1187361976] 'process raft request' (duration: 169.065531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:43.005784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.595705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:14:43.005949Z","caller":"traceutil/trace.go:171","msg":"trace[291223408] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2878; }","duration":"106.496044ms","start":"2026-07-02T05:14:42.899431Z","end":"2026-07-02T05:14:43.005927Z","steps":["trace[291223408] 'read index received' (duration: 49.382µs)","trace[291223408] 'applied index is now lower than readState.Index' (duration: 106.445102ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:43.00608Z","caller":"traceutil/trace.go:171","msg":"trace[1866456775] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"256.644507ms","start":"2026-07-02T05:14:42.74934Z","end":"2026-07-02T05:14:43.005984Z","steps":["trace[1866456775] 'process raft request' (duration: 122.727245ms)","trace[1866456775] 'compare' (duration: 133.383641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:14:43.00617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.739288ms","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-07-02T05:14:43.006208Z","caller":"traceutil/trace.go:171","msg":"trace[957199779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2768; }","duration":"106.812339ms","start":"2026-07-02T05:14:42.899382Z","end":"2026-07-02T05:14:43.006195Z","steps":["trace[957199779] 'agreement among raft nodes before linearized reading' (duration: 106.605166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:48.339619Z","caller":"traceutil/trace.go:171","msg":"trace[633313765] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"102.588347ms","start":"2026-07-02T05:14:48.237002Z","end":"2026-07-02T05:14:48.339591Z","steps":["trace[633313765] 'process raft request' (duration: 102.321772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.405919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.133494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:18:38.406194Z","caller":"traceutil/trace.go:171","msg":"trace[1489210437] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5151; }","duration":"341.818699ms","start":"2026-07-02T05:18:38.064356Z","end":"2026-07-02T05:18:38.406174Z","steps":["trace[1489210437] 'read index received' (duration: 214.298446ms)","trace[1489210437] 'applied index is now lower than readState.Index' (duration: 127.519183ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:38.406462Z","caller":"traceutil/trace.go:171","msg":"trace[1738615362] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"497.622486ms","start":"2026-07-02T05:18:37.908819Z","end":"2026-07-02T05:18:38.406441Z","steps":["trace[1738615362] 'process raft request' (duration: 369.821898ms)","trace[1738615362] 'compare' (duration: 126.599584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:18:38.406594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:18:37.908803Z","time spent":"497.750898ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:18:38.406818Z","caller":"traceutil/trace.go:171","msg":"trace[1368611688] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"497.923811ms","start":"2026-07-02T05:18:37.908881Z","end":"2026-07-02T05:18:38.406805Z","steps":["trace[1368611688] 'process raft request' (duration: 497.190997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.406899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:18:37.908861Z","time spent":"498.002252ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:18:38.407126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.765256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1673e684-db5f-4e3b-9152-180a5d694b9f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-02T05:18:38.407167Z","caller":"traceutil/trace.go:171","msg":"trace[1597316165] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1673e684-db5f-4e3b-9152-180a5d694b9f; range_end:; response_count:1; response_revision:4962; }","duration":"342.836578ms","start":"2026-07-02T05:18:38.064319Z","end":"2026-07-02T05:18:38.407155Z","steps":["trace[1597316165] 'agreement among raft nodes before linearized reading' (duration: 342.706055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.407198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:18:38.064301Z","time spent":"342.890589ms","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1673e684-db5f-4e3b-9152-180a5d694b9f\" "} {"level":"warn","ts":"2026-07-02T05:18:38.407481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.831273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:18:38.407524Z","caller":"traceutil/trace.go:171","msg":"trace[581838867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4962; }","duration":"134.917864ms","start":"2026-07-02T05:18:38.272597Z","end":"2026-07-02T05:18:38.407515Z","steps":["trace[581838867] 'agreement among raft nodes before linearized reading' (duration: 134.810692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.407852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.15341ms","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-07-02T05:18:38.407895Z","caller":"traceutil/trace.go:171","msg":"trace[1329637807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4962; }","duration":"135.249821ms","start":"2026-07-02T05:18:38.272637Z","end":"2026-07-02T05:18:38.407886Z","steps":["trace[1329637807] 'agreement among raft nodes before linearized reading' (duration: 135.135369ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:50.113987Z","caller":"traceutil/trace.go:171","msg":"trace[2041746606] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"145.554581ms","start":"2026-07-02T05:18:49.968395Z","end":"2026-07-02T05:18:50.11395Z","steps":["trace[2041746606] 'process raft request' (duration: 70.757492ms)","trace[2041746606] 'compare' (duration: 74.412172ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:18:50.423087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.623679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:18:50.42318Z","caller":"traceutil/trace.go:171","msg":"trace[1861669147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5197; }","duration":"151.777241ms","start":"2026-07-02T05:18:50.271366Z","end":"2026-07-02T05:18:50.423144Z","steps":["trace[1861669147] 'range keys from in-memory index tree' (duration: 151.506077ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:50.575637Z","caller":"traceutil/trace.go:171","msg":"trace[109433690] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"114.709709ms","start":"2026-07-02T05:18:50.460897Z","end":"2026-07-02T05:18:50.575607Z","steps":["trace[109433690] 'process raft request' (duration: 65.875518ms)","trace[109433690] 'compare' (duration: 48.372132ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:18:50.800246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.830023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:18:50.800403Z","caller":"traceutil/trace.go:171","msg":"trace[1087446446] linearizableReadLoop","detail":"{readStateIndex:5401; appliedIndex:5400; }","duration":"184.486552ms","start":"2026-07-02T05:18:50.615889Z","end":"2026-07-02T05:18:50.800375Z","steps":["trace[1087446446] 'read index received' (duration: 73.337331ms)","trace[1087446446] 'applied index is now lower than readState.Index' (duration: 111.14766ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:50.800457Z","caller":"traceutil/trace.go:171","msg":"trace[242624618] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"219.084147ms","start":"2026-07-02T05:18:50.581335Z","end":"2026-07-02T05:18:50.800419Z","steps":["trace[242624618] 'process raft request' (duration: 107.940587ms)","trace[242624618] 'compare' (duration: 110.63507ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:18:50.80066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.766537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-bqzjd\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-02T05:18:50.800711Z","caller":"traceutil/trace.go:171","msg":"trace[1736580556] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-bqzjd; range_end:; response_count:1; response_revision:5202; }","duration":"184.847218ms","start":"2026-07-02T05:18:50.615846Z","end":"2026-07-02T05:18:50.800693Z","steps":["trace[1736580556] 'agreement among raft nodes before linearized reading' (duration: 184.631814ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:50.803648Z","caller":"traceutil/trace.go:171","msg":"trace[1400536403] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"170.531653ms","start":"2026-07-02T05:18:50.633094Z","end":"2026-07-02T05:18:50.803626Z","steps":["trace[1400536403] 'process raft request' (duration: 169.935042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:20:01.083281Z","caller":"traceutil/trace.go:171","msg":"trace[64486416] transaction","detail":"{read_only:false; response_revision:5964; number_of_response:1; }","duration":"100.48907ms","start":"2026-07-02T05:20:00.982764Z","end":"2026-07-02T05:20:01.083253Z","steps":["trace[64486416] 'process raft request' (duration: 44.448523ms)","trace[64486416] 'compare' (duration: 55.839633ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:20:20.823946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"info","ts":"2026-07-02T05:20:20.895556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"70.138697ms","hash":2962455610,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":34500608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T05:20:20.895677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2962455610,"revision":2973,"compact-revision":-1} {"level":"warn","ts":"2026-07-02T05:24:49.062325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.869921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:24:49.062521Z","caller":"traceutil/trace.go:171","msg":"trace[862657677] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8124; }","duration":"120.124326ms","start":"2026-07-02T05:24:48.942331Z","end":"2026-07-02T05:24:49.062455Z","steps":["trace[862657677] 'count revisions from in-memory index tree' (duration: 119.76806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:24:49.196842Z","caller":"traceutil/trace.go:171","msg":"trace[662499740] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"127.530448ms","start":"2026-07-02T05:24:49.069289Z","end":"2026-07-02T05:24:49.19682Z","steps":["trace[662499740] 'process raft request' (duration: 127.396175ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:00.65859Z","caller":"traceutil/trace.go:171","msg":"trace[1986039150] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"179.96316ms","start":"2026-07-02T05:25:00.4786Z","end":"2026-07-02T05:25:00.658563Z","steps":["trace[1986039150] 'process raft request' (duration: 179.760307ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:00.668884Z","caller":"traceutil/trace.go:171","msg":"trace[1888253235] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"185.76819ms","start":"2026-07-02T05:25:00.483085Z","end":"2026-07-02T05:25:00.668853Z","steps":["trace[1888253235] 'process raft request' (duration: 185.570096ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:01.415459Z","caller":"traceutil/trace.go:171","msg":"trace[735719700] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"114.489877ms","start":"2026-07-02T05:25:01.30095Z","end":"2026-07-02T05:25:01.41544Z","steps":["trace[735719700] 'process raft request' (duration: 114.215752ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:20.829938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6121} {"level":"info","ts":"2026-07-02T05:25:20.930742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6121,"took":"97.901062ms","hash":4011728616,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":23339008,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-02T05:25:20.93081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4011728616,"revision":6121,"compact-revision":2973} {"level":"warn","ts":"2026-07-02T05:25:29.778851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.054016ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-02T05:25:29.77894Z","caller":"traceutil/trace.go:171","msg":"trace[1737839961] linearizableReadLoop","detail":"{readStateIndex:8694; appliedIndex:8693; }","duration":"291.715625ms","start":"2026-07-02T05:25:29.487211Z","end":"2026-07-02T05:25:29.778926Z","steps":["trace[1737839961] 'read index received' (duration: 44.99µs)","trace[1737839961] 'applied index is now lower than readState.Index' (duration: 291.669495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:25:29.779291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.072663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:25:29.779315Z","caller":"traceutil/trace.go:171","msg":"trace[1414726607] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8394; }","duration":"292.142043ms","start":"2026-07-02T05:25:29.487165Z","end":"2026-07-02T05:25:29.779307Z","steps":["trace[1414726607] 'agreement among raft nodes before linearized reading' (duration: 292.085452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:29.779459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.908402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:25:29.779509Z","caller":"traceutil/trace.go:171","msg":"trace[861033674] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8394; }","duration":"210.989614ms","start":"2026-07-02T05:25:29.568503Z","end":"2026-07-02T05:25:29.779493Z","steps":["trace[861033674] 'agreement among raft nodes before linearized reading' (duration: 210.815569ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:42.071182Z","caller":"traceutil/trace.go:171","msg":"trace[1144203124] linearizableReadLoop","detail":"{readStateIndex:8802; appliedIndex:8801; }","duration":"159.252343ms","start":"2026-07-02T05:25:41.9119Z","end":"2026-07-02T05:25:42.071152Z","steps":["trace[1144203124] 'read index received' (duration: 65.382549ms)","trace[1144203124] 'applied index is now lower than readState.Index' (duration: 93.868674ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:42.071223Z","caller":"traceutil/trace.go:171","msg":"trace[1069683623] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"160.05693ms","start":"2026-07-02T05:25:41.911122Z","end":"2026-07-02T05:25:42.071179Z","steps":["trace[1069683623] 'process raft request' (duration: 66.245787ms)","trace[1069683623] 'compare' (duration: 93.642659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:25:42.071622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.695672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-cgssw\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-07-02T05:25:42.071724Z","caller":"traceutil/trace.go:171","msg":"trace[290855309] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-cgssw; range_end:; response_count:1; response_revision:8498; }","duration":"159.814385ms","start":"2026-07-02T05:25:41.911885Z","end":"2026-07-02T05:25:42.071699Z","steps":["trace[290855309] 'agreement among raft nodes before linearized reading' (duration: 159.374527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:42.072057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.410883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:25:42.072143Z","caller":"traceutil/trace.go:171","msg":"trace[359647656] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8498; }","duration":"118.524535ms","start":"2026-07-02T05:25:41.953597Z","end":"2026-07-02T05:25:42.072121Z","steps":["trace[359647656] 'agreement among raft nodes before linearized reading' (duration: 118.069726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:42.977286Z","caller":"traceutil/trace.go:171","msg":"trace[143919375] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"131.695277ms","start":"2026-07-02T05:25:42.845559Z","end":"2026-07-02T05:25:42.977255Z","steps":["trace[143919375] 'process raft request' (duration: 102.699983ms)","trace[143919375] 'compare' (duration: 28.755189ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:42.99801Z","caller":"traceutil/trace.go:171","msg":"trace[260425782] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"100.97375ms","start":"2026-07-02T05:25:42.896972Z","end":"2026-07-02T05:25:42.997946Z","steps":["trace[260425782] 'process raft request' (duration: 100.786877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:43.627948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.46592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-07-02T05:25:43.628041Z","caller":"traceutil/trace.go:171","msg":"trace[1426659626] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8516; }","duration":"122.596333ms","start":"2026-07-02T05:25:43.50542Z","end":"2026-07-02T05:25:43.628017Z","steps":["trace[1426659626] 'range keys from in-memory index tree' (duration: 122.231706ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:26:19.200683Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d3.wal"} {"level":"info","ts":"2026-07-02T05:26:55.358869Z","caller":"traceutil/trace.go:171","msg":"trace[209846037] transaction","detail":"{read_only:false; response_revision:9234; number_of_response:1; }","duration":"226.550659ms","start":"2026-07-02T05:26:55.132294Z","end":"2026-07-02T05:26:55.358845Z","steps":["trace[209846037] 'process raft request' (duration: 221.563593ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:26:55.365256Z","caller":"traceutil/trace.go:171","msg":"trace[647014571] transaction","detail":"{read_only:false; response_revision:9235; number_of_response:1; }","duration":"132.698305ms","start":"2026-07-02T05:26:55.23253Z","end":"2026-07-02T05:26:55.365229Z","steps":["trace[647014571] 'process raft request' (duration: 132.479821ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:55.589561Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:27:55.596115Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T05:27:55.596264Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T05:30:20.839636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8299} {"level":"info","ts":"2026-07-02T05:30:20.925794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8299,"took":"82.847351ms","hash":3381915345,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":26673152,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T05:30:20.925921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381915345,"revision":8299,"compact-revision":6121} {"level":"info","ts":"2026-07-02T05:35:20.84691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10978} {"level":"info","ts":"2026-07-02T05:35:20.925759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10978,"took":"76.637417ms","hash":2559745267,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-02T05:35:20.925838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2559745267,"revision":10978,"compact-revision":8299} {"level":"warn","ts":"2026-07-02T05:37:07.325237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.010346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:37:07.325505Z","caller":"traceutil/trace.go:171","msg":"trace[654922976] transaction","detail":"{read_only:false; response_revision:13686; number_of_response:1; }","duration":"558.481127ms","start":"2026-07-02T05:37:06.766994Z","end":"2026-07-02T05:37:07.325475Z","steps":["trace[654922976] 'process raft request' (duration: 306.129794ms)","trace[654922976] 'compare' (duration: 251.479977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:37:07.325705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:37:06.766977Z","time spent":"558.66615ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:37:07.421216Z","caller":"traceutil/trace.go:171","msg":"trace[521863064] transaction","detail":"{read_only:false; response_revision:13687; number_of_response:1; }","duration":"653.910708ms","start":"2026-07-02T05:37:06.767277Z","end":"2026-07-02T05:37:07.421188Z","steps":["trace[521863064] 'process raft request' (duration: 653.706803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:37:07.421406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:37:06.76726Z","time spent":"654.0531ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:37:07.421445Z","caller":"traceutil/trace.go:171","msg":"trace[981539597] linearizableReadLoop","detail":"{readStateIndex:14159; appliedIndex:14157; }","duration":"608.914824ms","start":"2026-07-02T05:37:06.81249Z","end":"2026-07-02T05:37:07.421405Z","steps":["trace[981539597] 'read index received' (duration: 260.60874ms)","trace[981539597] 'applied index is now lower than readState.Index' (duration: 348.304424ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:37:07.421596Z","caller":"traceutil/trace.go:171","msg":"trace[1053904882] transaction","detail":"{read_only:false; response_revision:13688; number_of_response:1; }","duration":"374.15202ms","start":"2026-07-02T05:37:07.047406Z","end":"2026-07-02T05:37:07.421558Z","steps":["trace[1053904882] 'process raft request' (duration: 373.736542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:37:07.421638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.128278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6tzhv\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-07-02T05:37:07.42167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.502923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:37:07.421711Z","caller":"traceutil/trace.go:171","msg":"trace[1244113693] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:13688; }","duration":"550.612755ms","start":"2026-07-02T05:37:06.871086Z","end":"2026-07-02T05:37:07.421699Z","steps":["trace[1244113693] 'agreement among raft nodes before linearized reading' (duration: 550.504223ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:37:07.421709Z","caller":"traceutil/trace.go:171","msg":"trace[1681086519] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6tzhv; range_end:; response_count:1; response_revision:13688; }","duration":"609.24816ms","start":"2026-07-02T05:37:06.812437Z","end":"2026-07-02T05:37:07.421686Z","steps":["trace[1681086519] 'agreement among raft nodes before linearized reading' (duration: 609.087087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:37:07.42171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.150223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:37:07.42176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:37:06.871058Z","time spent":"550.692816ms","remote":"127.0.0.1:48886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":11,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:37:07.421768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:37:06.812415Z","time spent":"609.340592ms","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-6tzhv\" "} {"level":"warn","ts":"2026-07-02T05:37:07.421797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:37:07.047374Z","time spent":"374.331062ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:37:07.421819Z","caller":"traceutil/trace.go:171","msg":"trace[1751638944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13688; }","duration":"148.296896ms","start":"2026-07-02T05:37:07.273495Z","end":"2026-07-02T05:37:07.421792Z","steps":["trace[1751638944] 'agreement among raft nodes before linearized reading' (duration: 148.137303ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:43.397022Z","caller":"traceutil/trace.go:171","msg":"trace[2062538344] transaction","detail":"{read_only:false; response_revision:14742; number_of_response:1; }","duration":"192.667006ms","start":"2026-07-02T05:38:43.204271Z","end":"2026-07-02T05:38:43.396938Z","steps":["trace[2062538344] 'process raft request' (duration: 115.798761ms)","trace[2062538344] 'compare' (duration: 76.524029ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:38:43.435582Z","caller":"traceutil/trace.go:171","msg":"trace[1655184304] linearizableReadLoop","detail":"{readStateIndex:15251; appliedIndex:15249; }","duration":"161.922528ms","start":"2026-07-02T05:38:43.273629Z","end":"2026-07-02T05:38:43.435552Z","steps":["trace[1655184304] 'read index received' (duration: 46.451751ms)","trace[1655184304] 'applied index is now lower than readState.Index' (duration: 115.469407ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:38:43.435661Z","caller":"traceutil/trace.go:171","msg":"trace[969199764] transaction","detail":"{read_only:false; response_revision:14743; number_of_response:1; }","duration":"230.33254ms","start":"2026-07-02T05:38:43.20529Z","end":"2026-07-02T05:38:43.435622Z","steps":["trace[969199764] 'process raft request' (duration: 230.092996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:43.435753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.102801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:43.435837Z","caller":"traceutil/trace.go:171","msg":"trace[756135001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14743; }","duration":"162.255024ms","start":"2026-07-02T05:38:43.273558Z","end":"2026-07-02T05:38:43.435813Z","steps":["trace[756135001] 'agreement among raft nodes before linearized reading' (duration: 162.107751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:43.435861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.97034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:38:43.435867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.245875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:43.435948Z","caller":"traceutil/trace.go:171","msg":"trace[437690642] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14743; }","duration":"148.072212ms","start":"2026-07-02T05:38:43.287856Z","end":"2026-07-02T05:38:43.435929Z","steps":["trace[437690642] 'agreement among raft nodes before linearized reading' (duration: 147.929979ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:43.436006Z","caller":"traceutil/trace.go:171","msg":"trace[1127511519] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14743; }","duration":"148.434789ms","start":"2026-07-02T05:38:43.287541Z","end":"2026-07-02T05:38:43.435976Z","steps":["trace[1127511519] 'agreement among raft nodes before linearized reading' (duration: 148.201794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:43.436023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.987443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T05:38:43.43607Z","caller":"traceutil/trace.go:171","msg":"trace[138649180] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14743; }","duration":"144.057995ms","start":"2026-07-02T05:38:43.291994Z","end":"2026-07-02T05:38:43.436052Z","steps":["trace[138649180] 'agreement among raft nodes before linearized reading' (duration: 143.877252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:43.435907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.141103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:43.43613Z","caller":"traceutil/trace.go:171","msg":"trace[2099838507] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14743; }","duration":"148.377857ms","start":"2026-07-02T05:38:43.287731Z","end":"2026-07-02T05:38:43.436109Z","steps":["trace[2099838507] 'agreement among raft nodes before linearized reading' (duration: 148.120502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.077462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.765696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-c2nkr\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-07-02T05:38:44.077518Z","caller":"traceutil/trace.go:171","msg":"trace[1088198609] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-c2nkr; range_end:; response_count:1; response_revision:14746; }","duration":"135.878547ms","start":"2026-07-02T05:38:43.941627Z","end":"2026-07-02T05:38:44.077506Z","steps":["trace[1088198609] 'range keys from in-memory index tree' (duration: 135.596002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.077733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.483134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-c2nkr\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-07-02T05:38:44.077859Z","caller":"traceutil/trace.go:171","msg":"trace[1001960338] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-c2nkr; range_end:; response_count:1; response_revision:14746; }","duration":"153.659718ms","start":"2026-07-02T05:38:43.924159Z","end":"2026-07-02T05:38:44.077819Z","steps":["trace[1001960338] 'range keys from in-memory index tree' (duration: 153.164689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.077916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.961693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-96dzr\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-07-02T05:38:44.078011Z","caller":"traceutil/trace.go:171","msg":"trace[1543925121] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-96dzr; range_end:; response_count:1; response_revision:14746; }","duration":"143.113127ms","start":"2026-07-02T05:38:43.934875Z","end":"2026-07-02T05:38:44.077988Z","steps":["trace[1543925121] 'range keys from in-memory index tree' (duration: 142.80708ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:44.189717Z","caller":"traceutil/trace.go:171","msg":"trace[286884397] transaction","detail":"{read_only:false; response_revision:14747; number_of_response:1; }","duration":"111.356477ms","start":"2026-07-02T05:38:44.07833Z","end":"2026-07-02T05:38:44.189687Z","steps":["trace[286884397] 'process raft request' (duration: 111.192264ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:44.328581Z","caller":"traceutil/trace.go:171","msg":"trace[785054393] linearizableReadLoop","detail":"{readStateIndex:15256; appliedIndex:15254; }","duration":"243.734058ms","start":"2026-07-02T05:38:44.084822Z","end":"2026-07-02T05:38:44.328556Z","steps":["trace[785054393] 'read index received' (duration: 104.74775ms)","trace[785054393] 'applied index is now lower than readState.Index' (duration: 138.984908ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:38:44.328902Z","caller":"traceutil/trace.go:171","msg":"trace[1880726951] transaction","detail":"{read_only:false; response_revision:14748; number_of_response:1; }","duration":"247.112812ms","start":"2026-07-02T05:38:44.081775Z","end":"2026-07-02T05:38:44.328888Z","steps":["trace[1880726951] 'process raft request' (duration: 234.230205ms)","trace[1880726951] 'compare' (duration: 12.433258ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:38:44.329451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.848589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:44.32954Z","caller":"traceutil/trace.go:171","msg":"trace[1360821830] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14748; }","duration":"119.986752ms","start":"2026-07-02T05:38:44.209533Z","end":"2026-07-02T05:38:44.329519Z","steps":["trace[1360821830] 'agreement among raft nodes before linearized reading' (duration: 119.841969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.329607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.051263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:44.329679Z","caller":"traceutil/trace.go:171","msg":"trace[1466898272] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14748; }","duration":"120.176036ms","start":"2026-07-02T05:38:44.209488Z","end":"2026-07-02T05:38:44.329664Z","steps":["trace[1466898272] 'agreement among raft nodes before linearized reading' (duration: 120.078445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.329686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.282073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T05:38:44.329781Z","caller":"traceutil/trace.go:171","msg":"trace[259460881] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14748; }","duration":"175.445036ms","start":"2026-07-02T05:38:44.154315Z","end":"2026-07-02T05:38:44.32976Z","steps":["trace[259460881] 'agreement among raft nodes before linearized reading' (duration: 175.231442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.33005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.121529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:38:44.330108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.801505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-02T05:38:44.330297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.422241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-02T05:38:44.330372Z","caller":"traceutil/trace.go:171","msg":"trace[823750081] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14748; }","duration":"114.09657ms","start":"2026-07-02T05:38:44.216259Z","end":"2026-07-02T05:38:44.330355Z","steps":["trace[823750081] 'agreement among raft nodes before linearized reading' (duration: 113.502968ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:44.330438Z","caller":"traceutil/trace.go:171","msg":"trace[1774860520] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14748; }","duration":"121.569593ms","start":"2026-07-02T05:38:44.208836Z","end":"2026-07-02T05:38:44.330405Z","steps":["trace[1774860520] 'agreement among raft nodes before linearized reading' (duration: 121.004472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.330573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.73309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-02T05:38:44.330629Z","caller":"traceutil/trace.go:171","msg":"trace[29526404] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14748; }","duration":"178.808841ms","start":"2026-07-02T05:38:44.151808Z","end":"2026-07-02T05:38:44.330616Z","steps":["trace[29526404] 'agreement among raft nodes before linearized reading' (duration: 178.266971ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:44.330825Z","caller":"traceutil/trace.go:171","msg":"trace[1092426505] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14748; }","duration":"178.283942ms","start":"2026-07-02T05:38:44.151877Z","end":"2026-07-02T05:38:44.330161Z","steps":["trace[1092426505] 'agreement among raft nodes before linearized reading' (duration: 178.044776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.33006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.327919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:44.331031Z","caller":"traceutil/trace.go:171","msg":"trace[26885599] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14748; }","duration":"121.367538ms","start":"2026-07-02T05:38:44.209648Z","end":"2026-07-02T05:38:44.331016Z","steps":["trace[26885599] 'agreement among raft nodes before linearized reading' (duration: 120.300469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.331388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.442701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737293"} {"level":"info","ts":"2026-07-02T05:38:44.331485Z","caller":"traceutil/trace.go:171","msg":"trace[2017097758] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14748; }","duration":"244.573984ms","start":"2026-07-02T05:38:44.086892Z","end":"2026-07-02T05:38:44.331466Z","steps":["trace[2017097758] 'agreement among raft nodes before linearized reading' (duration: 242.995563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.331066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.596883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737293"} {"level":"info","ts":"2026-07-02T05:38:44.331567Z","caller":"traceutil/trace.go:171","msg":"trace[1651862761] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14748; }","duration":"246.141883ms","start":"2026-07-02T05:38:44.08541Z","end":"2026-07-02T05:38:44.331552Z","steps":["trace[1651862761] 'agreement among raft nodes before linearized reading' (duration: 243.938041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:44.33131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.48207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737293"} {"level":"info","ts":"2026-07-02T05:38:44.33197Z","caller":"traceutil/trace.go:171","msg":"trace[1168064323] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14748; }","duration":"247.184044ms","start":"2026-07-02T05:38:44.084757Z","end":"2026-07-02T05:38:44.331941Z","steps":["trace[1168064323] 'agreement among raft nodes before linearized reading' (duration: 244.38276ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:40:20.852875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13013} {"level":"info","ts":"2026-07-02T05:40:20.929136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13013,"took":"72.845317ms","hash":3167298283,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T05:40:20.929193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3167298283,"revision":13013,"compact-revision":10978} {"level":"info","ts":"2026-07-02T05:44:11.821729Z","caller":"traceutil/trace.go:171","msg":"trace[2048263483] transaction","detail":"{read_only:false; response_revision:17223; number_of_response:1; }","duration":"392.821706ms","start":"2026-07-02T05:44:11.428874Z","end":"2026-07-02T05:44:11.821696Z","steps":["trace[2048263483] 'process raft request' (duration: 386.670327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:44:11.821969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:44:11.428861Z","time spent":"392.970768ms","remote":"127.0.0.1:48680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:44:11.856007Z","caller":"traceutil/trace.go:171","msg":"trace[1527443007] linearizableReadLoop","detail":"{readStateIndex:17813; appliedIndex:17811; }","duration":"358.65191ms","start":"2026-07-02T05:44:11.49733Z","end":"2026-07-02T05:44:11.855982Z","steps":["trace[1527443007] 'read index received' (duration: 318.232835ms)","trace[1527443007] 'applied index is now lower than readState.Index' (duration: 40.417825ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:44:11.856082Z","caller":"traceutil/trace.go:171","msg":"trace[28316929] transaction","detail":"{read_only:false; response_revision:17224; number_of_response:1; }","duration":"400.368249ms","start":"2026-07-02T05:44:11.45568Z","end":"2026-07-02T05:44:11.856048Z","steps":["trace[28316929] 'process raft request' (duration: 400.112394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:44:11.856569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.154544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:44:11.856631Z","caller":"traceutil/trace.go:171","msg":"trace[1744243805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17224; }","duration":"283.237165ms","start":"2026-07-02T05:44:11.573377Z","end":"2026-07-02T05:44:11.856614Z","steps":["trace[1744243805] 'agreement among raft nodes before linearized reading' (duration: 283.095793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:44:11.85664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:44:11.455642Z","time spent":"400.92036ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:44:11.856279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.925806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-07-02T05:44:11.856688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.97534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T05:44:11.856802Z","caller":"traceutil/trace.go:171","msg":"trace[1449490903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17224; }","duration":"284.148873ms","start":"2026-07-02T05:44:11.572621Z","end":"2026-07-02T05:44:11.85677Z","steps":["trace[1449490903] 'agreement among raft nodes before linearized reading' (duration: 283.804427ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:44:11.856828Z","caller":"traceutil/trace.go:171","msg":"trace[1873420224] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17224; }","duration":"359.499556ms","start":"2026-07-02T05:44:11.497284Z","end":"2026-07-02T05:44:11.856783Z","steps":["trace[1873420224] 'agreement among raft nodes before linearized reading' (duration: 358.844873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:44:11.856512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.482023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-96dzr\" ","response":"range_response_count:1 size:20763"} {"level":"warn","ts":"2026-07-02T05:44:11.856955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:44:11.497266Z","time spent":"359.668809ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-02T05:44:11.856988Z","caller":"traceutil/trace.go:171","msg":"trace[1429261358] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-96dzr; range_end:; response_count:1; response_revision:17224; }","duration":"267.980033ms","start":"2026-07-02T05:44:11.588995Z","end":"2026-07-02T05:44:11.856975Z","steps":["trace[1429261358] 'agreement among raft nodes before linearized reading' (duration: 267.439883ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:45:20.861556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15372} {"level":"info","ts":"2026-07-02T05:45:20.948111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15372,"took":"84.165192ms","hash":1075994917,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":34004992,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T05:45:20.948186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1075994917,"revision":15372,"compact-revision":13013} {"level":"info","ts":"2026-07-02T05:48:14.981694Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:48:14.988944Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T05:48:14.98907Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-02T05:49:30.420419Z","caller":"traceutil/trace.go:171","msg":"trace[161935121] transaction","detail":"{read_only:false; response_revision:19761; number_of_response:1; }","duration":"124.555314ms","start":"2026-07-02T05:49:30.295834Z","end":"2026-07-02T05:49:30.420389Z","steps":["trace[161935121] 'process raft request' (duration: 124.35717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:49:30.733722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.03215ms","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:8"} {"level":"info","ts":"2026-07-02T05:49:30.733845Z","caller":"traceutil/trace.go:171","msg":"trace[817581643] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19761; }","duration":"162.226305ms","start":"2026-07-02T05:49:30.571587Z","end":"2026-07-02T05:49:30.733814Z","steps":["trace[817581643] 'count revisions from in-memory index tree' (duration: 161.890428ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:49:30.987117Z","caller":"traceutil/trace.go:171","msg":"trace[368986727] linearizableReadLoop","detail":"{readStateIndex:20435; appliedIndex:20434; }","duration":"300.642414ms","start":"2026-07-02T05:49:30.686446Z","end":"2026-07-02T05:49:30.987088Z","steps":["trace[368986727] 'read index received' (duration: 300.484661ms)","trace[368986727] 'applied index is now lower than readState.Index' (duration: 156.463µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:49:30.987284Z","caller":"traceutil/trace.go:171","msg":"trace[275281247] transaction","detail":"{read_only:false; response_revision:19762; number_of_response:1; }","duration":"374.977258ms","start":"2026-07-02T05:49:30.612233Z","end":"2026-07-02T05:49:30.98721Z","steps":["trace[275281247] 'process raft request' (duration: 374.680971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:49:30.98743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.959651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:49:30.987476Z","caller":"traceutil/trace.go:171","msg":"trace[1070818975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19762; }","duration":"301.067393ms","start":"2026-07-02T05:49:30.686397Z","end":"2026-07-02T05:49:30.987465Z","steps":["trace[1070818975] 'agreement among raft nodes before linearized reading' (duration: 300.87294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:49:30.987522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:49:30.686378Z","time spent":"301.130904ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:49:30.987565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:49:30.612197Z","time spent":"375.210642ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:49:30.987662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.919592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:49:30.987762Z","caller":"traceutil/trace.go:171","msg":"trace[171040549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19762; }","duration":"299.084275ms","start":"2026-07-02T05:49:30.688655Z","end":"2026-07-02T05:49:30.98774Z","steps":["trace[171040549] 'agreement among raft nodes before linearized reading' (duration: 298.81389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:49:30.988277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.377386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:49:30.988328Z","caller":"traceutil/trace.go:171","msg":"trace[770245438] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:19762; }","duration":"199.487618ms","start":"2026-07-02T05:49:30.788832Z","end":"2026-07-02T05:49:30.988319Z","steps":["trace[770245438] 'agreement among raft nodes before linearized reading' (duration: 199.376736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:49:31.203557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.12963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:49:31.20375Z","caller":"traceutil/trace.go:171","msg":"trace[393461511] transaction","detail":"{read_only:false; response_revision:19763; number_of_response:1; }","duration":"211.693762ms","start":"2026-07-02T05:49:30.992038Z","end":"2026-07-02T05:49:31.203731Z","steps":["trace[393461511] 'process raft request' (duration: 57.301327ms)","trace[393461511] 'compare' (duration: 153.694962ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:49:31.204237Z","caller":"traceutil/trace.go:171","msg":"trace[1248866559] transaction","detail":"{read_only:false; response_revision:19764; number_of_response:1; }","duration":"211.502339ms","start":"2026-07-02T05:49:30.992687Z","end":"2026-07-02T05:49:31.20419Z","steps":["trace[1248866559] 'process raft request' (duration: 210.993359ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:49:31.225646Z","caller":"traceutil/trace.go:171","msg":"trace[327354935] transaction","detail":"{read_only:false; response_revision:19765; number_of_response:1; }","duration":"169.437043ms","start":"2026-07-02T05:49:31.056172Z","end":"2026-07-02T05:49:31.225609Z","steps":["trace[327354935] 'process raft request' (duration: 168.993464ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:49:31.902714Z","caller":"traceutil/trace.go:171","msg":"trace[135595778] linearizableReadLoop","detail":"{readStateIndex:20441; appliedIndex:20440; }","duration":"110.955644ms","start":"2026-07-02T05:49:31.791726Z","end":"2026-07-02T05:49:31.902681Z","steps":["trace[135595778] 'read index received' (duration: 110.612947ms)","trace[135595778] 'applied index is now lower than readState.Index' (duration: 341.657µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:49:31.902824Z","caller":"traceutil/trace.go:171","msg":"trace[256805658] transaction","detail":"{read_only:false; response_revision:19768; number_of_response:1; }","duration":"199.918756ms","start":"2026-07-02T05:49:31.702851Z","end":"2026-07-02T05:49:31.90277Z","steps":["trace[256805658] 'process raft request' (duration: 199.56628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:49:31.903018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.26902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:49:31.90308Z","caller":"traceutil/trace.go:171","msg":"trace[509321409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19768; }","duration":"111.397083ms","start":"2026-07-02T05:49:31.791659Z","end":"2026-07-02T05:49:31.903056Z","steps":["trace[509321409] 'agreement among raft nodes before linearized reading' (duration: 111.154117ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:49:32.101497Z","caller":"traceutil/trace.go:171","msg":"trace[858682614] transaction","detail":"{read_only:false; response_revision:19770; number_of_response:1; }","duration":"141.034569ms","start":"2026-07-02T05:49:31.96042Z","end":"2026-07-02T05:49:32.101455Z","steps":["trace[858682614] 'process raft request' (duration: 132.887173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:49:32.32908Z","caller":"traceutil/trace.go:171","msg":"trace[269610067] transaction","detail":"{read_only:false; response_revision:19771; number_of_response:1; }","duration":"151.701503ms","start":"2026-07-02T05:49:32.177334Z","end":"2026-07-02T05:49:32.329035Z","steps":["trace[269610067] 'process raft request' (duration: 95.110829ms)","trace[269610067] 'compare' (duration: 56.347999ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:50:20.920318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18002} {"level":"info","ts":"2026-07-02T05:50:21.044035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18002,"took":"120.708148ms","hash":1097775473,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T05:50:21.044121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1097775473,"revision":18002,"compact-revision":15372} {"level":"info","ts":"2026-07-02T05:54:37.796058Z","caller":"traceutil/trace.go:171","msg":"trace[668785295] transaction","detail":"{read_only:false; response_revision:23034; number_of_response:1; }","duration":"112.159185ms","start":"2026-07-02T05:54:37.683844Z","end":"2026-07-02T05:54:37.796003Z","steps":["trace[668785295] 'process raft request' (duration: 111.836009ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:20.925974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20313} {"level":"info","ts":"2026-07-02T05:55:21.048844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20313,"took":"118.990725ms","hash":3831794739,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":44339200,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-02T05:55:21.048925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831794739,"revision":20313,"compact-revision":18002} {"level":"info","ts":"2026-07-02T05:56:36.272665Z","caller":"traceutil/trace.go:171","msg":"trace[1358814654] linearizableReadLoop","detail":"{readStateIndex:25014; appliedIndex:25013; }","duration":"208.64734ms","start":"2026-07-02T05:56:36.063998Z","end":"2026-07-02T05:56:36.272646Z","steps":["trace[1358814654] 'read index received' (duration: 208.591759ms)","trace[1358814654] 'applied index is now lower than readState.Index' (duration: 54.271µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:36.272781Z","caller":"traceutil/trace.go:171","msg":"trace[38679946] transaction","detail":"{read_only:false; response_revision:24216; number_of_response:1; }","duration":"246.523804ms","start":"2026-07-02T05:56:36.026245Z","end":"2026-07-02T05:56:36.272769Z","steps":["trace[38679946] 'process raft request' (duration: 246.26455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:36.272909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.898295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T05:56:36.272952Z","caller":"traceutil/trace.go:171","msg":"trace[381507526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24216; }","duration":"208.982527ms","start":"2026-07-02T05:56:36.063959Z","end":"2026-07-02T05:56:36.272941Z","steps":["trace[381507526] 'agreement among raft nodes before linearized reading' (duration: 208.829534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:36.278581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.235789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T05:56:36.278646Z","caller":"traceutil/trace.go:171","msg":"trace[443194370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24218; }","duration":"110.35093ms","start":"2026-07-02T05:56:36.168279Z","end":"2026-07-02T05:56:36.27863Z","steps":["trace[443194370] 'agreement among raft nodes before linearized reading' (duration: 110.174277ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:36.279046Z","caller":"traceutil/trace.go:171","msg":"trace[871458581] transaction","detail":"{read_only:false; response_revision:24217; number_of_response:1; }","duration":"113.805686ms","start":"2026-07-02T05:56:36.165216Z","end":"2026-07-02T05:56:36.279022Z","steps":["trace[871458581] 'process raft request' (duration: 113.106504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:36.563735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.761539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:36.564081Z","caller":"traceutil/trace.go:171","msg":"trace[1414929365] transaction","detail":"{read_only:false; response_revision:24220; number_of_response:1; }","duration":"280.980554ms","start":"2026-07-02T05:56:36.283068Z","end":"2026-07-02T05:56:36.564049Z","steps":["trace[1414929365] 'process raft request' (duration: 32.797318ms)","trace[1414929365] 'compare' (duration: 247.626495ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:36.564105Z","caller":"traceutil/trace.go:171","msg":"trace[1428999272] transaction","detail":"{read_only:false; response_revision:24221; number_of_response:1; }","duration":"280.943254ms","start":"2026-07-02T05:56:36.283112Z","end":"2026-07-02T05:56:36.564055Z","steps":["trace[1428999272] 'process raft request' (duration: 280.75148ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:36.858629Z","caller":"traceutil/trace.go:171","msg":"trace[574104218] transaction","detail":"{read_only:false; response_revision:24222; number_of_response:1; }","duration":"290.74936ms","start":"2026-07-02T05:56:36.567847Z","end":"2026-07-02T05:56:36.858596Z","steps":["trace[574104218] 'process raft request' (duration: 290.477925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.09383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.077987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:37.094075Z","caller":"traceutil/trace.go:171","msg":"trace[9513745] transaction","detail":"{read_only:false; response_revision:24223; number_of_response:1; }","duration":"525.344487ms","start":"2026-07-02T05:56:36.56869Z","end":"2026-07-02T05:56:37.094035Z","steps":["trace[9513745] 'process raft request' (duration: 417.964064ms)","trace[9513745] 'compare' (duration: 106.752111ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:37.094099Z","caller":"traceutil/trace.go:171","msg":"trace[62954734] transaction","detail":"{read_only:false; response_revision:24224; number_of_response:1; }","duration":"523.43212ms","start":"2026-07-02T05:56:36.570644Z","end":"2026-07-02T05:56:37.094077Z","steps":["trace[62954734] 'process raft request' (duration: 523.297677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.094238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.568656Z","time spent":"525.49319ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:37.094233Z","caller":"traceutil/trace.go:171","msg":"trace[1965192899] transaction","detail":"{read_only:false; response_revision:24225; number_of_response:1; }","duration":"249.272377ms","start":"2026-07-02T05:56:36.844929Z","end":"2026-07-02T05:56:37.094202Z","steps":["trace[1965192899] 'process raft request' (duration: 249.103314ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.094225Z","caller":"traceutil/trace.go:171","msg":"trace[1450859364] linearizableReadLoop","detail":"{readStateIndex:25022; appliedIndex:25019; }","duration":"480.340776ms","start":"2026-07-02T05:56:36.613838Z","end":"2026-07-02T05:56:37.094178Z","steps":["trace[1450859364] 'read index received' (duration: 244.593667ms)","trace[1450859364] 'applied index is now lower than readState.Index' (duration: 235.743299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:37.094352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.496369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:37.094408Z","caller":"traceutil/trace.go:171","msg":"trace[312720172] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:24225; }","duration":"480.603021ms","start":"2026-07-02T05:56:36.613792Z","end":"2026-07-02T05:56:37.094395Z","steps":["trace[312720172] 'agreement among raft nodes before linearized reading' (duration: 480.491809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.094443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.613774Z","time spent":"480.659653ms","remote":"127.0.0.1:44698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:37.094492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.570628Z","time spent":"523.608633ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:37.284286Z","caller":"traceutil/trace.go:171","msg":"trace[196934516] transaction","detail":"{read_only:false; response_revision:24226; number_of_response:1; }","duration":"388.640381ms","start":"2026-07-02T05:56:36.895614Z","end":"2026-07-02T05:56:37.284254Z","steps":["trace[196934516] 'process raft request' (duration: 373.024303ms)","trace[196934516] 'compare' (duration: 15.351483ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:37.284445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.895591Z","time spent":"388.776334ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:37.284462Z","caller":"traceutil/trace.go:171","msg":"trace[1369503829] linearizableReadLoop","detail":"{readStateIndex:25025; appliedIndex:25023; }","duration":"190.098254ms","start":"2026-07-02T05:56:37.094326Z","end":"2026-07-02T05:56:37.284424Z","steps":["trace[1369503829] 'read index received' (duration: 174.318923ms)","trace[1369503829] 'applied index is now lower than readState.Index' (duration: 15.777461ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:37.284734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.859322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-02T05:56:37.284792Z","caller":"traceutil/trace.go:171","msg":"trace[347682846] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24227; }","duration":"313.962214ms","start":"2026-07-02T05:56:36.970816Z","end":"2026-07-02T05:56:37.284778Z","steps":["trace[347682846] 'agreement among raft nodes before linearized reading' (duration: 313.799021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.284822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.970794Z","time spent":"314.020586ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-07-02T05:56:37.284818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.388604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T05:56:37.284859Z","caller":"traceutil/trace.go:171","msg":"trace[85111514] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24227; }","duration":"472.479606ms","start":"2026-07-02T05:56:36.812368Z","end":"2026-07-02T05:56:37.284847Z","steps":["trace[85111514] 'agreement among raft nodes before linearized reading' (duration: 472.296713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.284875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.040921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:37.284908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.81235Z","time spent":"472.547267ms","remote":"127.0.0.1:48490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":141,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:37.284935Z","caller":"traceutil/trace.go:171","msg":"trace[1209653486] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24227; }","duration":"160.129433ms","start":"2026-07-02T05:56:37.124792Z","end":"2026-07-02T05:56:37.284922Z","steps":["trace[1209653486] 'agreement among raft nodes before linearized reading' (duration: 160.040811ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.284969Z","caller":"traceutil/trace.go:171","msg":"trace[797763908] transaction","detail":"{read_only:false; response_revision:24227; number_of_response:1; }","duration":"362.547853ms","start":"2026-07-02T05:56:36.922411Z","end":"2026-07-02T05:56:37.284959Z","steps":["trace[797763908] 'process raft request' (duration: 361.783758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.285034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:36.922382Z","time spent":"362.606415ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:37.285197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.003892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:56:37.285221Z","caller":"traceutil/trace.go:171","msg":"trace[1901174554] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24227; }","duration":"203.092844ms","start":"2026-07-02T05:56:37.08212Z","end":"2026-07-02T05:56:37.285213Z","steps":["trace[1901174554] 'agreement among raft nodes before linearized reading' (duration: 203.010412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.285354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.002464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:37.285375Z","caller":"traceutil/trace.go:171","msg":"trace[678483889] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24227; }","duration":"280.057996ms","start":"2026-07-02T05:56:37.005309Z","end":"2026-07-02T05:56:37.285367Z","steps":["trace[678483889] 'agreement among raft nodes before linearized reading' (duration: 280.013495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.688138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.883154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:56:37.688208Z","caller":"traceutil/trace.go:171","msg":"trace[1661959105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24227; }","duration":"289.987595ms","start":"2026-07-02T05:56:37.398203Z","end":"2026-07-02T05:56:37.688191Z","steps":["trace[1661959105] 'range keys from in-memory index tree' (duration: 289.761231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.688377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.987443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T05:56:37.688464Z","caller":"traceutil/trace.go:171","msg":"trace[1940950716] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24227; }","duration":"236.112086ms","start":"2026-07-02T05:56:37.452334Z","end":"2026-07-02T05:56:37.688446Z","steps":["trace[1940950716] 'count revisions from in-memory index tree' (duration: 235.918142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.688533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.801525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:56:37.688668Z","caller":"traceutil/trace.go:171","msg":"trace[540526219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24227; }","duration":"263.979787ms","start":"2026-07-02T05:56:37.424647Z","end":"2026-07-02T05:56:37.688626Z","steps":["trace[540526219] 'range keys from in-memory index tree' (duration: 263.55561ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.769402Z","caller":"traceutil/trace.go:171","msg":"trace[1674392555] linearizableReadLoop","detail":"{readStateIndex:25026; appliedIndex:25025; }","duration":"132.986683ms","start":"2026-07-02T05:56:37.636383Z","end":"2026-07-02T05:56:37.76937Z","steps":["trace[1674392555] 'read index received' (duration: 132.750889ms)","trace[1674392555] 'applied index is now lower than readState.Index' (duration: 234.214µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:37.769541Z","caller":"traceutil/trace.go:171","msg":"trace[43961391] transaction","detail":"{read_only:false; response_revision:24228; number_of_response:1; }","duration":"177.284671ms","start":"2026-07-02T05:56:37.592227Z","end":"2026-07-02T05:56:37.769512Z","steps":["trace[43961391] 'process raft request' (duration: 176.932994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:37.769715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.3137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T05:56:37.769758Z","caller":"traceutil/trace.go:171","msg":"trace[1218253450] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24228; }","duration":"133.417621ms","start":"2026-07-02T05:56:37.63633Z","end":"2026-07-02T05:56:37.769747Z","steps":["trace[1218253450] 'agreement among raft nodes before linearized reading' (duration: 133.214677ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.840478Z","caller":"traceutil/trace.go:171","msg":"trace[1225859814] transaction","detail":"{read_only:false; response_revision:24229; number_of_response:1; }","duration":"146.671805ms","start":"2026-07-02T05:56:37.693778Z","end":"2026-07-02T05:56:37.84045Z","steps":["trace[1225859814] 'process raft request' (duration: 122.515523ms)","trace[1225859814] 'compare' (duration: 23.866906ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:37.840658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.687436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:37.840729Z","caller":"traceutil/trace.go:171","msg":"trace[345100107] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24230; }","duration":"100.805118ms","start":"2026-07-02T05:56:37.739901Z","end":"2026-07-02T05:56:37.840706Z","steps":["trace[345100107] 'agreement among raft nodes before linearized reading' (duration: 100.694546ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.840913Z","caller":"traceutil/trace.go:171","msg":"trace[1631300709] transaction","detail":"{read_only:false; response_revision:24230; number_of_response:1; }","duration":"146.609554ms","start":"2026-07-02T05:56:37.694292Z","end":"2026-07-02T05:56:37.840902Z","steps":["trace[1631300709] 'process raft request' (duration: 146.031843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:38.125999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.877041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-02T05:56:38.126052Z","caller":"traceutil/trace.go:171","msg":"trace[634002415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24230; }","duration":"178.938172ms","start":"2026-07-02T05:56:37.947101Z","end":"2026-07-02T05:56:38.126039Z","steps":["trace[634002415] 'range keys from in-memory index tree' (duration: 178.695847ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:38.422144Z","caller":"traceutil/trace.go:171","msg":"trace[308792752] transaction","detail":"{read_only:false; response_revision:24231; number_of_response:1; }","duration":"143.79826ms","start":"2026-07-02T05:56:38.278322Z","end":"2026-07-02T05:56:38.42212Z","steps":["trace[308792752] 'process raft request' (duration: 143.660377ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:38.724622Z","caller":"traceutil/trace.go:171","msg":"trace[646287602] linearizableReadLoop","detail":"{readStateIndex:25031; appliedIndex:25028; }","duration":"440.624556ms","start":"2026-07-02T05:56:38.283979Z","end":"2026-07-02T05:56:38.724604Z","steps":["trace[646287602] 'read index received' (duration: 138.103381ms)","trace[646287602] 'applied index is now lower than readState.Index' (duration: 302.520255ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:38.724666Z","caller":"traceutil/trace.go:171","msg":"trace[2109217640] transaction","detail":"{read_only:false; response_revision:24232; number_of_response:1; }","duration":"442.033964ms","start":"2026-07-02T05:56:38.282599Z","end":"2026-07-02T05:56:38.724633Z","steps":["trace[2109217640] 'process raft request' (duration: 441.845921ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:38.724703Z","caller":"traceutil/trace.go:171","msg":"trace[1042138988] transaction","detail":"{read_only:false; response_revision:24233; number_of_response:1; }","duration":"441.274039ms","start":"2026-07-02T05:56:38.283398Z","end":"2026-07-02T05:56:38.724672Z","steps":["trace[1042138988] 'process raft request' (duration: 441.173397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:38.724799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.81431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-02T05:56:38.724797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:38.282556Z","time spent":"442.162186ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:38.724825Z","caller":"traceutil/trace.go:171","msg":"trace[1310932595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24233; }","duration":"440.868461ms","start":"2026-07-02T05:56:38.28395Z","end":"2026-07-02T05:56:38.724818Z","steps":["trace[1310932595] 'agreement among raft nodes before linearized reading' (duration: 440.748579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:38.724847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:38.28394Z","time spent":"440.902532ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:38.724842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:38.283383Z","time spent":"441.386701ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:38.724984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.103084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T05:56:38.725004Z","caller":"traceutil/trace.go:171","msg":"trace[1600392525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24233; }","duration":"157.163976ms","start":"2026-07-02T05:56:38.567833Z","end":"2026-07-02T05:56:38.724997Z","steps":["trace[1600392525] 'agreement among raft nodes before linearized reading' (duration: 157.104545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:38.72502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.668368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T05:56:38.725059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.314458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:56:38.7251Z","caller":"traceutil/trace.go:171","msg":"trace[1088180236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24233; }","duration":"157.387889ms","start":"2026-07-02T05:56:38.567702Z","end":"2026-07-02T05:56:38.725089Z","steps":["trace[1088180236] 'agreement among raft nodes before linearized reading' (duration: 157.284067ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:38.725099Z","caller":"traceutil/trace.go:171","msg":"trace[1143381061] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24233; }","duration":"244.805741ms","start":"2026-07-02T05:56:38.480273Z","end":"2026-07-02T05:56:38.725079Z","steps":["trace[1143381061] 'agreement among raft nodes before linearized reading' (duration: 244.577497ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:38.887113Z","caller":"traceutil/trace.go:171","msg":"trace[2001737037] transaction","detail":"{read_only:false; response_revision:24235; number_of_response:1; }","duration":"157.789358ms","start":"2026-07-02T05:56:38.7293Z","end":"2026-07-02T05:56:38.887089Z","steps":["trace[2001737037] 'process raft request' (duration: 157.683446ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:38.887132Z","caller":"traceutil/trace.go:171","msg":"trace[196997966] transaction","detail":"{read_only:false; response_revision:24234; number_of_response:1; }","duration":"157.821869ms","start":"2026-07-02T05:56:38.729286Z","end":"2026-07-02T05:56:38.887108Z","steps":["trace[196997966] 'process raft request' (duration: 145.385301ms)","trace[196997966] 'compare' (duration: 12.00187ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:38.899306Z","caller":"traceutil/trace.go:171","msg":"trace[597286028] transaction","detail":"{read_only:false; response_revision:24236; number_of_response:1; }","duration":"168.950121ms","start":"2026-07-02T05:56:38.730339Z","end":"2026-07-02T05:56:38.899289Z","steps":["trace[597286028] 'process raft request' (duration: 168.819409ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:39.225394Z","caller":"traceutil/trace.go:171","msg":"trace[2007661238] linearizableReadLoop","detail":"{readStateIndex:25037; appliedIndex:25036; }","duration":"131.114568ms","start":"2026-07-02T05:56:39.094248Z","end":"2026-07-02T05:56:39.225362Z","steps":["trace[2007661238] 'read index received' (duration: 37.297114ms)","trace[2007661238] 'applied index is now lower than readState.Index' (duration: 93.815654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:39.225732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.463284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418384"} {"level":"info","ts":"2026-07-02T05:56:39.225803Z","caller":"traceutil/trace.go:171","msg":"trace[461870496] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24238; }","duration":"131.566786ms","start":"2026-07-02T05:56:39.094218Z","end":"2026-07-02T05:56:39.225785Z","steps":["trace[461870496] 'agreement among raft nodes before linearized reading' (duration: 131.278121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:39.484729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.366964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:39.484831Z","caller":"traceutil/trace.go:171","msg":"trace[1477380655] linearizableReadLoop","detail":"{readStateIndex:25038; appliedIndex:25037; }","duration":"259.348089ms","start":"2026-07-02T05:56:39.225467Z","end":"2026-07-02T05:56:39.484815Z","steps":["trace[1477380655] 'read index received' (duration: 107.915064ms)","trace[1477380655] 'applied index is now lower than readState.Index' (duration: 151.431645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:39.484928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.726855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:39.484921Z","caller":"traceutil/trace.go:171","msg":"trace[663193960] transaction","detail":"{read_only:false; response_revision:24239; number_of_response:1; }","duration":"383.919302ms","start":"2026-07-02T05:56:39.10097Z","end":"2026-07-02T05:56:39.484889Z","steps":["trace[663193960] 'process raft request' (duration: 232.307633ms)","trace[663193960] 'compare' (duration: 151.044218ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:39.484966Z","caller":"traceutil/trace.go:171","msg":"trace[584243804] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24239; }","duration":"353.798506ms","start":"2026-07-02T05:56:39.131154Z","end":"2026-07-02T05:56:39.484952Z","steps":["trace[584243804] 'agreement among raft nodes before linearized reading' (duration: 353.728455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:39.485008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:39.131132Z","time spent":"353.866158ms","remote":"127.0.0.1:45150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:39.485076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:39.100949Z","time spent":"384.037594ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:39.48534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.406459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-02T05:56:39.485414Z","caller":"traceutil/trace.go:171","msg":"trace[1324921452] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24239; }","duration":"386.496131ms","start":"2026-07-02T05:56:39.098901Z","end":"2026-07-02T05:56:39.485397Z","steps":["trace[1324921452] 'agreement among raft nodes before linearized reading' (duration: 386.232026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:39.485448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:39.098855Z","time spent":"386.587053ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-02T05:56:39.498346Z","caller":"traceutil/trace.go:171","msg":"trace[348848908] transaction","detail":"{read_only:false; response_revision:24240; number_of_response:1; }","duration":"272.203816ms","start":"2026-07-02T05:56:39.226122Z","end":"2026-07-02T05:56:39.498326Z","steps":["trace[348848908] 'process raft request' (duration: 272.005443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:39.498566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.728396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:39.498568Z","caller":"traceutil/trace.go:171","msg":"trace[687926246] transaction","detail":"{read_only:false; response_revision:24241; number_of_response:1; }","duration":"208.926465ms","start":"2026-07-02T05:56:39.289596Z","end":"2026-07-02T05:56:39.498522Z","steps":["trace[687926246] 'process raft request' (duration: 208.67547ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:39.498622Z","caller":"traceutil/trace.go:171","msg":"trace[47918095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24241; }","duration":"225.791938ms","start":"2026-07-02T05:56:39.272819Z","end":"2026-07-02T05:56:39.498611Z","steps":["trace[47918095] 'agreement among raft nodes before linearized reading' (duration: 225.639485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:39.498825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.123157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T05:56:39.498921Z","caller":"traceutil/trace.go:171","msg":"trace[498023175] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24241; }","duration":"143.27965ms","start":"2026-07-02T05:56:39.355621Z","end":"2026-07-02T05:56:39.4989Z","steps":["trace[498023175] 'agreement among raft nodes before linearized reading' (duration: 142.894673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:39.764794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.60264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:39.76491Z","caller":"traceutil/trace.go:171","msg":"trace[645802718] linearizableReadLoop","detail":"{readStateIndex:25041; appliedIndex:25040; }","duration":"265.565369ms","start":"2026-07-02T05:56:39.499326Z","end":"2026-07-02T05:56:39.764891Z","steps":["trace[645802718] 'read index received' (duration: 125.804806ms)","trace[645802718] 'applied index is now lower than readState.Index' (duration: 139.758993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:39.764993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.66131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:39.765032Z","caller":"traceutil/trace.go:171","msg":"trace[1208094261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24242; }","duration":"265.730882ms","start":"2026-07-02T05:56:39.499288Z","end":"2026-07-02T05:56:39.765019Z","steps":["trace[1208094261] 'agreement among raft nodes before linearized reading' (duration: 265.657071ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:39.765091Z","caller":"traceutil/trace.go:171","msg":"trace[847865797] transaction","detail":"{read_only:false; response_revision:24242; number_of_response:1; }","duration":"274.695872ms","start":"2026-07-02T05:56:39.490298Z","end":"2026-07-02T05:56:39.764994Z","steps":["trace[847865797] 'process raft request' (duration: 134.794977ms)","trace[847865797] 'compare' (duration: 139.406896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:40.016655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.0137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:40.017011Z","caller":"traceutil/trace.go:171","msg":"trace[809595494] transaction","detail":"{read_only:false; response_revision:24243; number_of_response:1; }","duration":"354.258595ms","start":"2026-07-02T05:56:39.662721Z","end":"2026-07-02T05:56:40.016979Z","steps":["trace[809595494] 'process raft request' (duration: 226.836879ms)","trace[809595494] 'compare' (duration: 126.740484ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:40.017048Z","caller":"traceutil/trace.go:171","msg":"trace[913095974] transaction","detail":"{read_only:false; response_revision:24244; number_of_response:1; }","duration":"242.820423ms","start":"2026-07-02T05:56:39.774203Z","end":"2026-07-02T05:56:40.017024Z","steps":["trace[913095974] 'process raft request' (duration: 242.63247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:40.017188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:39.662687Z","time spent":"354.419088ms","remote":"127.0.0.1:38858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418442,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:40.220579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.648775ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:56:40.220703Z","caller":"traceutil/trace.go:171","msg":"trace[279098995] linearizableReadLoop","detail":"{readStateIndex:25044; appliedIndex:25041; }","duration":"375.921449ms","start":"2026-07-02T05:56:39.844758Z","end":"2026-07-02T05:56:40.22068Z","steps":["trace[279098995] 'read index received' (duration: 44.657844ms)","trace[279098995] 'applied index is now lower than readState.Index' (duration: 331.261705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:40.221074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.297096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:56:40.221147Z","caller":"traceutil/trace.go:171","msg":"trace[1579834125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24244; }","duration":"376.420599ms","start":"2026-07-02T05:56:39.844704Z","end":"2026-07-02T05:56:40.221125Z","steps":["trace[1579834125] 'agreement among raft nodes before linearized reading' (duration: 376.198134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:40.221209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:39.844683Z","time spent":"376.514221ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:56:40.221265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.254566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:56:40.22134Z","caller":"traceutil/trace.go:171","msg":"trace[731726773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24244; }","duration":"376.362187ms","start":"2026-07-02T05:56:39.844961Z","end":"2026-07-02T05:56:40.221323Z","steps":["trace[731726773] 'agreement among raft nodes before linearized reading' (duration: 376.123873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:40.221398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:39.84495Z","time spent":"376.434759ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T05:56:40.350308Z","caller":"traceutil/trace.go:171","msg":"trace[39211780] transaction","detail":"{read_only:false; response_revision:24245; number_of_response:1; }","duration":"123.649874ms","start":"2026-07-02T05:56:40.226635Z","end":"2026-07-02T05:56:40.350285Z","steps":["trace[39211780] 'process raft request' (duration: 123.517592ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:40.591094Z","caller":"traceutil/trace.go:171","msg":"trace[1958048369] transaction","detail":"{read_only:false; response_revision:24246; number_of_response:1; }","duration":"364.239236ms","start":"2026-07-02T05:56:40.226825Z","end":"2026-07-02T05:56:40.591064Z","steps":["trace[1958048369] 'process raft request' (duration: 351.034994ms)","trace[1958048369] 'compare' (duration: 13.016748ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:40.591272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:40.226794Z","time spent":"364.374968ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:40.59198Z","caller":"traceutil/trace.go:171","msg":"trace[957800736] transaction","detail":"{read_only:false; response_revision:24247; number_of_response:1; }","duration":"163.898804ms","start":"2026-07-02T05:56:40.428056Z","end":"2026-07-02T05:56:40.591955Z","steps":["trace[957800736] 'process raft request' (duration: 163.632149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:40.912128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.759505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:40.912415Z","caller":"traceutil/trace.go:171","msg":"trace[1857450242] transaction","detail":"{read_only:false; response_revision:24248; number_of_response:1; }","duration":"244.335253ms","start":"2026-07-02T05:56:40.668033Z","end":"2026-07-02T05:56:40.912368Z","steps":["trace[1857450242] 'process raft request' (duration: 129.250641ms)","trace[1857450242] 'compare' (duration: 114.579802ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:40.91249Z","caller":"traceutil/trace.go:171","msg":"trace[1604791829] transaction","detail":"{read_only:false; response_revision:24250; number_of_response:1; }","duration":"181.612294ms","start":"2026-07-02T05:56:40.73086Z","end":"2026-07-02T05:56:40.912473Z","steps":["trace[1604791829] 'process raft request' (duration: 181.434681ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:40.912462Z","caller":"traceutil/trace.go:171","msg":"trace[1796615297] transaction","detail":"{read_only:false; response_revision:24249; number_of_response:1; }","duration":"181.721386ms","start":"2026-07-02T05:56:40.730726Z","end":"2026-07-02T05:56:40.912447Z","steps":["trace[1796615297] 'process raft request' (duration: 181.505712ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:41.283866Z","caller":"traceutil/trace.go:171","msg":"trace[823084580] linearizableReadLoop","detail":"{readStateIndex:25051; appliedIndex:25050; }","duration":"371.371792ms","start":"2026-07-02T05:56:40.912476Z","end":"2026-07-02T05:56:41.283848Z","steps":["trace[823084580] 'read index received' (duration: 371.324031ms)","trace[823084580] 'applied index is now lower than readState.Index' (duration: 46.481µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:41.283987Z","caller":"traceutil/trace.go:171","msg":"trace[1255795223] transaction","detail":"{read_only:false; response_revision:24251; number_of_response:1; }","duration":"378.988898ms","start":"2026-07-02T05:56:40.904937Z","end":"2026-07-02T05:56:41.283926Z","steps":["trace[1255795223] 'process raft request' (duration: 378.767994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:41.284112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.670881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:56:41.284174Z","caller":"traceutil/trace.go:171","msg":"trace[400924607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24251; }","duration":"391.796193ms","start":"2026-07-02T05:56:40.892364Z","end":"2026-07-02T05:56:41.28416Z","steps":["trace[400924607] 'agreement among raft nodes before linearized reading' (duration: 391.591449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:41.284176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:40.904922Z","time spent":"379.157941ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:41.284217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:40.892338Z","time spent":"391.869134ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:41.284325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.067227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T05:56:41.284399Z","caller":"traceutil/trace.go:171","msg":"trace[1971788839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24251; }","duration":"381.169069ms","start":"2026-07-02T05:56:40.903211Z","end":"2026-07-02T05:56:41.28438Z","steps":["trace[1971788839] 'agreement among raft nodes before linearized reading' (duration: 380.732231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:41.284614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:40.903199Z","time spent":"381.23053ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:41.579017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.288459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:41.579177Z","caller":"traceutil/trace.go:171","msg":"trace[1238803392] transaction","detail":"{read_only:false; response_revision:24252; number_of_response:1; }","duration":"662.103072ms","start":"2026-07-02T05:56:40.917029Z","end":"2026-07-02T05:56:41.579132Z","steps":["trace[1238803392] 'process raft request' (duration: 390.60304ms)","trace[1238803392] 'compare' (duration: 271.155676ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:41.579331Z","caller":"traceutil/trace.go:171","msg":"trace[676030178] transaction","detail":"{read_only:false; response_revision:24254; number_of_response:1; }","duration":"289.588808ms","start":"2026-07-02T05:56:41.289685Z","end":"2026-07-02T05:56:41.579274Z","steps":["trace[676030178] 'process raft request' (duration: 289.535457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:41.579348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:40.917006Z","time spent":"662.246055ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:41.579519Z","caller":"traceutil/trace.go:171","msg":"trace[305027379] transaction","detail":"{read_only:false; response_revision:24253; number_of_response:1; }","duration":"290.129228ms","start":"2026-07-02T05:56:41.289372Z","end":"2026-07-02T05:56:41.579501Z","steps":["trace[305027379] 'process raft request' (duration: 289.767091ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:41.579607Z","caller":"traceutil/trace.go:171","msg":"trace[1530650085] linearizableReadLoop","detail":"{readStateIndex:25052; appliedIndex:25051; }","duration":"295.651744ms","start":"2026-07-02T05:56:41.283934Z","end":"2026-07-02T05:56:41.579586Z","steps":["trace[1530650085] 'read index received' (duration: 23.691983ms)","trace[1530650085] 'applied index is now lower than readState.Index' (duration: 271.957991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:41.579721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.183967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:41.579743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.685265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:41.579801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.136085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-02T05:56:41.579793Z","caller":"traceutil/trace.go:171","msg":"trace[1224743986] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24254; }","duration":"383.28724ms","start":"2026-07-02T05:56:41.196484Z","end":"2026-07-02T05:56:41.579771Z","steps":["trace[1224743986] 'agreement among raft nodes before linearized reading' (duration: 383.170097ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:41.579835Z","caller":"traceutil/trace.go:171","msg":"trace[2117824623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24254; }","duration":"306.796877ms","start":"2026-07-02T05:56:41.273008Z","end":"2026-07-02T05:56:41.579805Z","steps":["trace[2117824623] 'agreement among raft nodes before linearized reading' (duration: 306.643644ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:41.579864Z","caller":"traceutil/trace.go:171","msg":"trace[578790367] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24254; }","duration":"579.238446ms","start":"2026-07-02T05:56:41.00061Z","end":"2026-07-02T05:56:41.579848Z","steps":["trace[578790367] 'agreement among raft nodes before linearized reading' (duration: 579.047742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:41.579882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.196466Z","time spent":"383.394851ms","remote":"127.0.0.1:38938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:41.579887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.272998Z","time spent":"306.879459ms","remote":"127.0.0.1:48344","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-07-02T05:56:41.57991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.000585Z","time spent":"579.312508ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-02T05:56:41.57984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.087654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-02T05:56:41.580095Z","caller":"traceutil/trace.go:171","msg":"trace[204582538] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24254; }","duration":"659.39154ms","start":"2026-07-02T05:56:40.920686Z","end":"2026-07-02T05:56:41.580077Z","steps":["trace[204582538] 'agreement among raft nodes before linearized reading' (duration: 659.011392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:41.580145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:40.920673Z","time spent":"659.457741ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18251,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-02T05:56:41.905937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.497016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:41.906045Z","caller":"traceutil/trace.go:171","msg":"trace[835445381] transaction","detail":"{read_only:false; response_revision:24255; number_of_response:1; }","duration":"414.66991ms","start":"2026-07-02T05:56:41.491356Z","end":"2026-07-02T05:56:41.906026Z","steps":["trace[835445381] 'process raft request' (duration: 228.00688ms)","trace[835445381] 'compare' (duration: 186.362884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:41.906104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.491328Z","time spent":"414.748782ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:42.34855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.473245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:42.348765Z","caller":"traceutil/trace.go:171","msg":"trace[1645561842] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"326.727009ms","start":"2026-07-02T05:56:42.02202Z","end":"2026-07-02T05:56:42.348747Z","steps":["trace[1645561842] 'process raft request' (duration: 326.687187ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:42.348788Z","caller":"traceutil/trace.go:171","msg":"trace[330834630] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"763.041362ms","start":"2026-07-02T05:56:41.585716Z","end":"2026-07-02T05:56:42.348757Z","steps":["trace[330834630] 'process raft request' (duration: 762.926329ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:42.348797Z","caller":"traceutil/trace.go:171","msg":"trace[1314255810] transaction","detail":"{read_only:false; response_revision:24256; number_of_response:1; }","duration":"845.147482ms","start":"2026-07-02T05:56:41.503608Z","end":"2026-07-02T05:56:42.348756Z","steps":["trace[1314255810] 'process raft request' (duration: 543.380391ms)","trace[1314255810] 'compare' (duration: 301.139479ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:42.348835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:42.021988Z","time spent":"326.80985ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:42.348919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.219188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:42.34893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.585694Z","time spent":"763.160354ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:42.348955Z","caller":"traceutil/trace.go:171","msg":"trace[712001287] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24258; }","duration":"990.277419ms","start":"2026-07-02T05:56:41.358669Z","end":"2026-07-02T05:56:42.348947Z","steps":["trace[712001287] 'agreement among raft nodes before linearized reading' (duration: 990.171087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.348927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.532272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:42.348953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.503579Z","time spent":"845.291055ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:42.348981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.358615Z","time spent":"990.36023ms","remote":"127.0.0.1:35182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:42.34901Z","caller":"traceutil/trace.go:171","msg":"trace[296729030] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24258; }","duration":"924.671453ms","start":"2026-07-02T05:56:41.42432Z","end":"2026-07-02T05:56:42.348991Z","steps":["trace[296729030] 'agreement among raft nodes before linearized reading' (duration: 924.47024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.349055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.424298Z","time spent":"924.746375ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:42.348789Z","caller":"traceutil/trace.go:171","msg":"trace[1224802919] linearizableReadLoop","detail":"{readStateIndex:25056; appliedIndex:25054; }","duration":"769.096618ms","start":"2026-07-02T05:56:41.579665Z","end":"2026-07-02T05:56:42.348762Z","steps":["trace[1224802919] 'read index received' (duration: 139.712702ms)","trace[1224802919] 'applied index is now lower than readState.Index' (duration: 629.380136ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:42.371189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.766078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-02T05:56:42.37125Z","caller":"traceutil/trace.go:171","msg":"trace[1914741496] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24259; }","duration":"784.8592ms","start":"2026-07-02T05:56:41.586375Z","end":"2026-07-02T05:56:42.371234Z","steps":["trace[1914741496] 'agreement among raft nodes before linearized reading' (duration: 784.676716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.371265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.466404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:42.37126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.415944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-02T05:56:42.371282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.844516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:42.371311Z","caller":"traceutil/trace.go:171","msg":"trace[2059815350] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24259; }","duration":"599.552336ms","start":"2026-07-02T05:56:41.771746Z","end":"2026-07-02T05:56:42.371298Z","steps":["trace[2059815350] 'agreement among raft nodes before linearized reading' (duration: 599.457395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.371352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.771725Z","time spent":"599.613738ms","remote":"127.0.0.1:33840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:42.371287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.586362Z","time spent":"784.916711ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18251,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-02T05:56:42.371338Z","caller":"traceutil/trace.go:171","msg":"trace[1021589315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24259; }","duration":"789.931248ms","start":"2026-07-02T05:56:41.581392Z","end":"2026-07-02T05:56:42.371323Z","steps":["trace[1021589315] 'agreement among raft nodes before linearized reading' (duration: 789.833436ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:42.371333Z","caller":"traceutil/trace.go:171","msg":"trace[1338879602] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24259; }","duration":"599.543747ms","start":"2026-07-02T05:56:41.771769Z","end":"2026-07-02T05:56:42.371313Z","steps":["trace[1338879602] 'agreement among raft nodes before linearized reading' (duration: 599.309901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.371462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.581371Z","time spent":"790.06702ms","remote":"127.0.0.1:48354","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-07-02T05:56:42.371461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:41.771756Z","time spent":"599.692759ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-02T05:56:42.687393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.501589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:42.687821Z","caller":"traceutil/trace.go:171","msg":"trace[1278377810] linearizableReadLoop","detail":"{readStateIndex:25061; appliedIndex:25059; }","duration":"309.471358ms","start":"2026-07-02T05:56:42.378332Z","end":"2026-07-02T05:56:42.687803Z","steps":["trace[1278377810] 'read index received' (duration: 125.460678ms)","trace[1278377810] 'applied index is now lower than readState.Index' (duration: 184.0097ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:42.687975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.647531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:42.687957Z","caller":"traceutil/trace.go:171","msg":"trace[449462061] transaction","detail":"{read_only:false; response_revision:24260; number_of_response:1; }","duration":"312.127799ms","start":"2026-07-02T05:56:42.375745Z","end":"2026-07-02T05:56:42.687873Z","steps":["trace[449462061] 'process raft request' (duration: 128.035418ms)","trace[449462061] 'compare' (duration: 183.256765ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:42.688011Z","caller":"traceutil/trace.go:171","msg":"trace[1209738749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24261; }","duration":"309.713833ms","start":"2026-07-02T05:56:42.378286Z","end":"2026-07-02T05:56:42.688Z","steps":["trace[1209738749] 'agreement among raft nodes before linearized reading' (duration: 309.59739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.688046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:42.378274Z","time spent":"309.765413ms","remote":"127.0.0.1:48344","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-07-02T05:56:42.688111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:42.375724Z","time spent":"312.309332ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:42.688013Z","caller":"traceutil/trace.go:171","msg":"trace[676355570] transaction","detail":"{read_only:false; response_revision:24261; number_of_response:1; }","duration":"311.419706ms","start":"2026-07-02T05:56:42.376448Z","end":"2026-07-02T05:56:42.687867Z","steps":["trace[676355570] 'process raft request' (duration: 311.195112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.688347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:42.376428Z","time spent":"311.800053ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:42.844957Z","caller":"traceutil/trace.go:171","msg":"trace[1598815916] linearizableReadLoop","detail":"{readStateIndex:25062; appliedIndex:25061; }","duration":"157.024033ms","start":"2026-07-02T05:56:42.68786Z","end":"2026-07-02T05:56:42.844884Z","steps":["trace[1598815916] 'read index received' (duration: 156.954441ms)","trace[1598815916] 'applied index is now lower than readState.Index' (duration: 68.952µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:42.845Z","caller":"traceutil/trace.go:171","msg":"trace[767849208] transaction","detail":"{read_only:false; response_revision:24262; number_of_response:1; }","duration":"247.844619ms","start":"2026-07-02T05:56:42.597132Z","end":"2026-07-02T05:56:42.844977Z","steps":["trace[767849208] 'process raft request' (duration: 247.631266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.845154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.519338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-02T05:56:42.845189Z","caller":"traceutil/trace.go:171","msg":"trace[151632624] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24262; }","duration":"344.60023ms","start":"2026-07-02T05:56:42.500578Z","end":"2026-07-02T05:56:42.845178Z","steps":["trace[151632624] 'agreement among raft nodes before linearized reading' (duration: 344.453667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.845169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.640096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-02T05:56:42.845166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.825549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:42.845218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:42.500561Z","time spent":"344.648651ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-02T05:56:42.845236Z","caller":"traceutil/trace.go:171","msg":"trace[72150786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24262; }","duration":"248.749078ms","start":"2026-07-02T05:56:42.59647Z","end":"2026-07-02T05:56:42.845219Z","steps":["trace[72150786] 'agreement among raft nodes before linearized reading' (duration: 248.552394ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:42.845251Z","caller":"traceutil/trace.go:171","msg":"trace[1046143160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24262; }","duration":"155.954012ms","start":"2026-07-02T05:56:42.689277Z","end":"2026-07-02T05:56:42.845231Z","steps":["trace[1046143160] 'agreement among raft nodes before linearized reading' (duration: 155.804989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.845813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.100348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:42.845931Z","caller":"traceutil/trace.go:171","msg":"trace[52032706] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:24262; }","duration":"415.320122ms","start":"2026-07-02T05:56:42.430585Z","end":"2026-07-02T05:56:42.845905Z","steps":["trace[52032706] 'agreement among raft nodes before linearized reading' (duration: 414.628779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:42.846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:42.430561Z","time spent":"415.425115ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:53.030933Z","caller":"traceutil/trace.go:171","msg":"trace[1818153499] linearizableReadLoop","detail":"{readStateIndex:25137; appliedIndex:25136; }","duration":"102.954219ms","start":"2026-07-02T05:56:52.927951Z","end":"2026-07-02T05:56:53.030905Z","steps":["trace[1818153499] 'read index received' (duration: 85.988135ms)","trace[1818153499] 'applied index is now lower than readState.Index' (duration: 16.964444ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:53.031395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.438138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:56:53.031469Z","caller":"traceutil/trace.go:171","msg":"trace[216314296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24335; }","duration":"103.56753ms","start":"2026-07-02T05:56:52.927887Z","end":"2026-07-02T05:56:53.031455Z","steps":["trace[216314296] 'agreement among raft nodes before linearized reading' (duration: 103.194464ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:53.031481Z","caller":"traceutil/trace.go:171","msg":"trace[318797861] transaction","detail":"{read_only:false; response_revision:24335; number_of_response:1; }","duration":"149.171682ms","start":"2026-07-02T05:56:52.882269Z","end":"2026-07-02T05:56:53.03144Z","steps":["trace[318797861] 'process raft request' (duration: 131.612787ms)","trace[318797861] 'compare' (duration: 16.72041ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:53.153243Z","caller":"traceutil/trace.go:171","msg":"trace[876956192] transaction","detail":"{read_only:false; response_revision:24338; number_of_response:1; }","duration":"117.470677ms","start":"2026-07-02T05:56:53.035745Z","end":"2026-07-02T05:56:53.153216Z","steps":["trace[876956192] 'process raft request' (duration: 116.771154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:54.578673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.611864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:54.578732Z","caller":"traceutil/trace.go:171","msg":"trace[2109275513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24343; }","duration":"305.716746ms","start":"2026-07-02T05:56:54.273002Z","end":"2026-07-02T05:56:54.578718Z","steps":["trace[2109275513] 'range keys from in-memory index tree' (duration: 305.460471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:54.578755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:54.272982Z","time spent":"305.767907ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:56:54.579111Z","caller":"traceutil/trace.go:171","msg":"trace[1732029458] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"177.136168ms","start":"2026-07-02T05:56:54.401966Z","end":"2026-07-02T05:56:54.579103Z","steps":["trace[1732029458] 'process raft request' (duration: 177.043086ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:54.590704Z","caller":"traceutil/trace.go:171","msg":"trace[466166327] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"174.191121ms","start":"2026-07-02T05:56:54.416492Z","end":"2026-07-02T05:56:54.590684Z","steps":["trace[466166327] 'process raft request' (duration: 173.957177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.274993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.9259ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-02T05:56:55.275162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:54.591832Z","time spent":"683.323148ms","remote":"127.0.0.1:48380","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-02T05:56:55.275244Z","caller":"traceutil/trace.go:171","msg":"trace[426140048] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"238.533892ms","start":"2026-07-02T05:56:55.036691Z","end":"2026-07-02T05:56:55.275225Z","steps":["trace[426140048] 'process raft request' (duration: 238.40452ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:55.275254Z","caller":"traceutil/trace.go:171","msg":"trace[1789229636] linearizableReadLoop","detail":"{readStateIndex:25148; appliedIndex:25147; }","duration":"435.921977ms","start":"2026-07-02T05:56:54.839298Z","end":"2026-07-02T05:56:55.27522Z","steps":["trace[1789229636] 'read index received' (duration: 15.704501ms)","trace[1789229636] 'applied index is now lower than readState.Index' (duration: 420.207666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:55.275511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.204512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-02T05:56:55.275561Z","caller":"traceutil/trace.go:171","msg":"trace[1546753003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24346; }","duration":"436.293174ms","start":"2026-07-02T05:56:54.839251Z","end":"2026-07-02T05:56:55.275544Z","steps":["trace[1546753003] 'agreement among raft nodes before linearized reading' (duration: 436.109031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.275596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:54.83923Z","time spent":"436.357755ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-02T05:56:55.745189Z","caller":"traceutil/trace.go:171","msg":"trace[1885013357] linearizableReadLoop","detail":"{readStateIndex:25151; appliedIndex:25149; }","duration":"469.836605ms","start":"2026-07-02T05:56:55.275325Z","end":"2026-07-02T05:56:55.745162Z","steps":["trace[1885013357] 'read index received' (duration: 452.235469ms)","trace[1885013357] 'applied index is now lower than readState.Index' (duration: 17.599656ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:55.745358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.426622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:55.745391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.349171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:55.745431Z","caller":"traceutil/trace.go:171","msg":"trace[167795806] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24348; }","duration":"566.437552ms","start":"2026-07-02T05:56:55.17898Z","end":"2026-07-02T05:56:55.745417Z","steps":["trace[167795806] 'agreement among raft nodes before linearized reading' (duration: 566.346661ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:55.745436Z","caller":"traceutil/trace.go:171","msg":"trace[15224624] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24348; }","duration":"510.553404ms","start":"2026-07-02T05:56:55.234861Z","end":"2026-07-02T05:56:55.745414Z","steps":["trace[15224624] 'agreement among raft nodes before linearized reading' (duration: 510.432472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.745465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.178959Z","time spent":"566.497324ms","remote":"127.0.0.1:46958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:55.74548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.234839Z","time spent":"510.630915ms","remote":"127.0.0.1:46950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:55.745541Z","caller":"traceutil/trace.go:171","msg":"trace[255144837] transaction","detail":"{read_only:false; response_revision:24348; number_of_response:1; }","duration":"661.702505ms","start":"2026-07-02T05:56:55.083806Z","end":"2026-07-02T05:56:55.745509Z","steps":["trace[255144837] 'process raft request' (duration: 661.254126ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:55.745541Z","caller":"traceutil/trace.go:171","msg":"trace[603404303] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"663.433708ms","start":"2026-07-02T05:56:55.082071Z","end":"2026-07-02T05:56:55.745504Z","steps":["trace[603404303] 'process raft request' (duration: 645.474104ms)","trace[603404303] 'compare' (duration: 17.000115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:55.745604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.494122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:55.74567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.086522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-02T05:56:55.745561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.975566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:55.745684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.578247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-02T05:56:55.745692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.082048Z","time spent":"663.56428ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:55.745695Z","caller":"traceutil/trace.go:171","msg":"trace[243804461] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:24348; }","duration":"591.634105ms","start":"2026-07-02T05:56:55.154039Z","end":"2026-07-02T05:56:55.745673Z","steps":["trace[243804461] 'agreement among raft nodes before linearized reading' (duration: 591.470262ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:55.745722Z","caller":"traceutil/trace.go:171","msg":"trace[200586255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24348; }","duration":"891.161933ms","start":"2026-07-02T05:56:54.854545Z","end":"2026-07-02T05:56:55.745707Z","steps":["trace[200586255] 'agreement among raft nodes before linearized reading' (duration: 890.967389ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:55.745748Z","caller":"traceutil/trace.go:171","msg":"trace[1500176394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24348; }","duration":"588.673018ms","start":"2026-07-02T05:56:55.157055Z","end":"2026-07-02T05:56:55.745728Z","steps":["trace[1500176394] 'agreement among raft nodes before linearized reading' (duration: 588.464684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.745762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.154021Z","time spent":"591.728426ms","remote":"127.0.0.1:48458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:55.745761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:54.854535Z","time spent":"891.218274ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:56:55.745784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.157024Z","time spent":"588.75274ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:56:55.745691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.08379Z","time spent":"661.816667ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:55.745776Z","caller":"traceutil/trace.go:171","msg":"trace[1683058012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24348; }","duration":"472.23643ms","start":"2026-07-02T05:56:55.273499Z","end":"2026-07-02T05:56:55.745736Z","steps":["trace[1683058012] 'agreement among raft nodes before linearized reading' (duration: 472.002636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.745878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.273476Z","time spent":"472.367473ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:56:55.770543Z","caller":"traceutil/trace.go:171","msg":"trace[479750747] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"493.719413ms","start":"2026-07-02T05:56:55.276801Z","end":"2026-07-02T05:56:55.770521Z","steps":["trace[479750747] 'process raft request' (duration: 493.529998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.770656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.276781Z","time spent":"493.814344ms","remote":"127.0.0.1:48380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:55.770723Z","caller":"traceutil/trace.go:171","msg":"trace[1954197816] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"433.230736ms","start":"2026-07-02T05:56:55.337464Z","end":"2026-07-02T05:56:55.770695Z","steps":["trace[1954197816] 'process raft request' (duration: 432.983481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.770746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.133312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:55.770815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.337443Z","time spent":"433.323407ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:55.770829Z","caller":"traceutil/trace.go:171","msg":"trace[1370857746] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24351; }","duration":"310.282635ms","start":"2026-07-02T05:56:55.460521Z","end":"2026-07-02T05:56:55.770804Z","steps":["trace[1370857746] 'agreement among raft nodes before linearized reading' (duration: 310.114412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.77088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.4605Z","time spent":"310.367836ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:55.770648Z","caller":"traceutil/trace.go:171","msg":"trace[1180649324] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"380.68875ms","start":"2026-07-02T05:56:55.389924Z","end":"2026-07-02T05:56:55.770612Z","steps":["trace[1180649324] 'process raft request' (duration: 380.600549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.771789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.3899Z","time spent":"381.80694ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:56.732134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.846194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:56.7325Z","caller":"traceutil/trace.go:171","msg":"trace[39210498] linearizableReadLoop","detail":"{readStateIndex:25156; appliedIndex:25154; }","duration":"961.900305ms","start":"2026-07-02T05:56:55.770579Z","end":"2026-07-02T05:56:56.73248Z","steps":["trace[39210498] 'read index received' (duration: 319.663632ms)","trace[39210498] 'applied index is now lower than readState.Index' (duration: 642.230033ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:56.732575Z","caller":"traceutil/trace.go:171","msg":"trace[297298580] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"982.121682ms","start":"2026-07-02T05:56:55.750387Z","end":"2026-07-02T05:56:56.732509Z","steps":["trace[297298580] 'process raft request' (duration: 981.840116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:56.732696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.82756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:56:56.732734Z","caller":"traceutil/trace.go:171","msg":"trace[742218216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24353; }","duration":"966.917961ms","start":"2026-07-02T05:56:55.765804Z","end":"2026-07-02T05:56:56.732722Z","steps":["trace[742218216] 'agreement among raft nodes before linearized reading' (duration: 966.764078ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:56.732725Z","caller":"traceutil/trace.go:171","msg":"trace[1714962370] transaction","detail":"{read_only:false; response_revision:24352; number_of_response:1; }","duration":"982.823416ms","start":"2026-07-02T05:56:55.749863Z","end":"2026-07-02T05:56:56.732686Z","steps":["trace[1714962370] 'process raft request' (duration: 340.346039ms)","trace[1714962370] 'compare' (duration: 641.371375ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:56.732756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.750368Z","time spent":"982.296195ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:56.732775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.765785Z","time spent":"966.981293ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:56.732901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.653259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:56.732936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.749843Z","time spent":"982.948957ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:56.732974Z","caller":"traceutil/trace.go:171","msg":"trace[1565390769] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"750.238717ms","start":"2026-07-02T05:56:55.982716Z","end":"2026-07-02T05:56:56.732954Z","steps":["trace[1565390769] 'process raft request' (duration: 750.121025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:56.732974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.907812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:56:56.732991Z","caller":"traceutil/trace.go:171","msg":"trace[1462262683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24354; }","duration":"985.773002ms","start":"2026-07-02T05:56:55.747196Z","end":"2026-07-02T05:56:56.732969Z","steps":["trace[1462262683] 'agreement among raft nodes before linearized reading' (duration: 985.485476ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:56.73298Z","caller":"traceutil/trace.go:171","msg":"trace[1296188754] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"958.225214ms","start":"2026-07-02T05:56:55.774728Z","end":"2026-07-02T05:56:56.732953Z","steps":["trace[1296188754] 'process raft request' (duration: 957.705305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:56.733051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.747179Z","time spent":"985.863603ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:56:56.733049Z","caller":"traceutil/trace.go:171","msg":"trace[1319244765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24354; }","duration":"977.016605ms","start":"2026-07-02T05:56:55.756012Z","end":"2026-07-02T05:56:56.733028Z","steps":["trace[1319244765] 'agreement among raft nodes before linearized reading' (duration: 976.668658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:56.733077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.982689Z","time spent":"750.328559ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:56.733096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.755998Z","time spent":"977.086236ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:56.733139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.774698Z","time spent":"958.366227ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:56.745382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.806764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-02T05:56:56.74538Z","caller":"traceutil/trace.go:171","msg":"trace[1639551445] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"160.218395ms","start":"2026-07-02T05:56:56.585144Z","end":"2026-07-02T05:56:56.745363Z","steps":["trace[1639551445] 'process raft request' (duration: 159.95158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:56.74538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.873994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:56:56.74542Z","caller":"traceutil/trace.go:171","msg":"trace[1227354066] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24357; }","duration":"972.880315ms","start":"2026-07-02T05:56:55.772531Z","end":"2026-07-02T05:56:56.745411Z","steps":["trace[1227354066] 'agreement among raft nodes before linearized reading' (duration: 972.800903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:56.745443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.772514Z","time spent":"972.923946ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-02T05:56:56.745443Z","caller":"traceutil/trace.go:171","msg":"trace[502714943] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24357; }","duration":"130.987415ms","start":"2026-07-02T05:56:56.614439Z","end":"2026-07-02T05:56:56.745427Z","steps":["trace[502714943] 'agreement among raft nodes before linearized reading' (duration: 130.859713ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:56.745611Z","caller":"traceutil/trace.go:171","msg":"trace[1433016828] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"147.206555ms","start":"2026-07-02T05:56:56.598099Z","end":"2026-07-02T05:56:56.745306Z","steps":["trace[1433016828] 'process raft request' (duration: 147.128783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.085213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.613096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:03.085449Z","caller":"traceutil/trace.go:171","msg":"trace[588498932] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"308.916598ms","start":"2026-07-02T05:57:02.776516Z","end":"2026-07-02T05:57:03.085432Z","steps":["trace[588498932] 'process raft request' (duration: 308.838666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.085515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:02.776506Z","time spent":"308.9742ms","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:03.085533Z","caller":"traceutil/trace.go:171","msg":"trace[1793209656] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"313.139109ms","start":"2026-07-02T05:57:02.772344Z","end":"2026-07-02T05:57:03.085483Z","steps":["trace[1793209656] 'process raft request' (duration: 192.180366ms)","trace[1793209656] 'compare' (duration: 120.492624ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:03.08571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:02.772334Z","time spent":"313.285711ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:03.38826Z","caller":"traceutil/trace.go:171","msg":"trace[1456050142] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"593.937798ms","start":"2026-07-02T05:57:02.794299Z","end":"2026-07-02T05:57:03.388237Z","steps":["trace[1456050142] 'process raft request' (duration: 588.846041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.388379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:02.794265Z","time spent":"594.04956ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:03.388624Z","caller":"traceutil/trace.go:171","msg":"trace[1741385201] transaction","detail":"{read_only:false; response_revision:24461; number_of_response:1; }","duration":"594.188174ms","start":"2026-07-02T05:57:02.794405Z","end":"2026-07-02T05:57:03.388593Z","steps":["trace[1741385201] 'process raft request' (duration: 593.5275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.388815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:02.794399Z","time spent":"594.320306ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:03.389038Z","caller":"traceutil/trace.go:171","msg":"trace[2046991408] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"594.461119ms","start":"2026-07-02T05:57:02.794567Z","end":"2026-07-02T05:57:03.389028Z","steps":["trace[2046991408] 'process raft request' (duration: 593.868607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.389087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:02.794559Z","time spent":"594.504969ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:03.389285Z","caller":"traceutil/trace.go:171","msg":"trace[1710688377] transaction","detail":"{read_only:false; response_revision:24463; number_of_response:1; }","duration":"594.568331ms","start":"2026-07-02T05:57:02.794686Z","end":"2026-07-02T05:57:03.389254Z","steps":["trace[1710688377] 'process raft request' (duration: 594.211774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.389338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.555809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:03.389366Z","caller":"traceutil/trace.go:171","msg":"trace[2133099024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24463; }","duration":"115.625351ms","start":"2026-07-02T05:57:03.273735Z","end":"2026-07-02T05:57:03.38936Z","steps":["trace[2133099024] 'agreement among raft nodes before linearized reading' (duration: 115.56611ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:03.389329Z","caller":"traceutil/trace.go:171","msg":"trace[496796879] linearizableReadLoop","detail":"{readStateIndex:25268; appliedIndex:25264; }","duration":"115.403596ms","start":"2026-07-02T05:57:03.273779Z","end":"2026-07-02T05:57:03.389182Z","steps":["trace[496796879] 'read index received' (duration: 109.228809ms)","trace[496796879] 'applied index is now lower than readState.Index' (duration: 6.173937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:03.389432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:02.794677Z","time spent":"594.678133ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:03.400248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.572794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-07-02T05:57:03.400309Z","caller":"traceutil/trace.go:171","msg":"trace[1737697312] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24464; }","duration":"100.665385ms","start":"2026-07-02T05:57:03.299628Z","end":"2026-07-02T05:57:03.400293Z","steps":["trace[1737697312] 'agreement among raft nodes before linearized reading' (duration: 100.521762ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:03.603676Z","caller":"traceutil/trace.go:171","msg":"trace[2027942634] linearizableReadLoop","detail":"{readStateIndex:25271; appliedIndex:25270; }","duration":"128.807784ms","start":"2026-07-02T05:57:03.474842Z","end":"2026-07-02T05:57:03.60365Z","steps":["trace[2027942634] 'read index received' (duration: 128.563339ms)","trace[2027942634] 'applied index is now lower than readState.Index' (duration: 243.615µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:03.603758Z","caller":"traceutil/trace.go:171","msg":"trace[1640301148] transaction","detail":"{read_only:false; response_revision:24466; number_of_response:1; }","duration":"152.362234ms","start":"2026-07-02T05:57:03.451345Z","end":"2026-07-02T05:57:03.603708Z","steps":["trace[1640301148] 'process raft request' (duration: 152.122349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.603939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.074799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-07-02T05:57:03.603988Z","caller":"traceutil/trace.go:171","msg":"trace[750300076] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24466; }","duration":"129.17924ms","start":"2026-07-02T05:57:03.474795Z","end":"2026-07-02T05:57:03.603974Z","steps":["trace[750300076] 'agreement among raft nodes before linearized reading' (duration: 128.989576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:03.830757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.354452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:03.830817Z","caller":"traceutil/trace.go:171","msg":"trace[35122870] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24467; }","duration":"119.463865ms","start":"2026-07-02T05:57:03.71134Z","end":"2026-07-02T05:57:03.830804Z","steps":["trace[35122870] 'range keys from in-memory index tree' (duration: 119.283671ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:10.003589Z","caller":"traceutil/trace.go:171","msg":"trace[380819277] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"138.163623ms","start":"2026-07-02T05:57:09.865401Z","end":"2026-07-02T05:57:10.003564Z","steps":["trace[380819277] 'process raft request' (duration: 138.112792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:10.003884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.462711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-07-02T05:57:10.003926Z","caller":"traceutil/trace.go:171","msg":"trace[1401378535] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"138.612791ms","start":"2026-07-02T05:57:09.865301Z","end":"2026-07-02T05:57:10.003914Z","steps":["trace[1401378535] 'process raft request' (duration: 138.123712ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:10.003966Z","caller":"traceutil/trace.go:171","msg":"trace[2036273790] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24531; }","duration":"123.583884ms","start":"2026-07-02T05:57:09.880361Z","end":"2026-07-02T05:57:10.003945Z","steps":["trace[2036273790] 'agreement among raft nodes before linearized reading' (duration: 123.261968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:10.004339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.113526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-02T05:57:10.004375Z","caller":"traceutil/trace.go:171","msg":"trace[2114281173] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24531; }","duration":"110.151686ms","start":"2026-07-02T05:57:09.89421Z","end":"2026-07-02T05:57:10.004361Z","steps":["trace[2114281173] 'agreement among raft nodes before linearized reading' (duration: 110.042154ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:10.095803Z","caller":"traceutil/trace.go:171","msg":"trace[311808926] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"172.616841ms","start":"2026-07-02T05:57:09.923144Z","end":"2026-07-02T05:57:10.095761Z","steps":["trace[311808926] 'process raft request' (duration: 153.400833ms)","trace[311808926] 'compare' (duration: 18.736449ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:10.32776Z","caller":"traceutil/trace.go:171","msg":"trace[1949934272] linearizableReadLoop","detail":"{readStateIndex:25339; appliedIndex:25338; }","duration":"227.220776ms","start":"2026-07-02T05:57:10.1005Z","end":"2026-07-02T05:57:10.327721Z","steps":["trace[1949934272] 'read index received' (duration: 185.277924ms)","trace[1949934272] 'applied index is now lower than readState.Index' (duration: 41.941372ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:10.327924Z","caller":"traceutil/trace.go:171","msg":"trace[618798526] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"311.13055ms","start":"2026-07-02T05:57:10.016727Z","end":"2026-07-02T05:57:10.327858Z","steps":["trace[618798526] 'process raft request' (duration: 268.989034ms)","trace[618798526] 'compare' (duration: 41.588575ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:10.328115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.582622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-07-02T05:57:10.328125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:10.016716Z","time spent":"311.304723ms","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10634,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:10.328171Z","caller":"traceutil/trace.go:171","msg":"trace[343450157] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24533; }","duration":"227.712825ms","start":"2026-07-02T05:57:10.100443Z","end":"2026-07-02T05:57:10.328155Z","steps":["trace[343450157] 'agreement among raft nodes before linearized reading' (duration: 227.474811ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:32.311927Z","caller":"traceutil/trace.go:171","msg":"trace[1563676210] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"227.844128ms","start":"2026-07-02T05:57:32.084063Z","end":"2026-07-02T05:57:32.311907Z","steps":["trace[1563676210] 'process raft request' (duration: 129.463507ms)","trace[1563676210] 'compare' (duration: 98.252419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:32.607645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.15767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:32.607771Z","caller":"traceutil/trace.go:171","msg":"trace[812753732] linearizableReadLoop","detail":"{readStateIndex:25533; appliedIndex:25530; }","duration":"405.758759ms","start":"2026-07-02T05:57:32.202002Z","end":"2026-07-02T05:57:32.60776Z","steps":["trace[812753732] 'read index received' (duration: 11.771385ms)","trace[812753732] 'applied index is now lower than readState.Index' (duration: 393.986434ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:32.607818Z","caller":"traceutil/trace.go:171","msg":"trace[223480885] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"523.705315ms","start":"2026-07-02T05:57:32.084105Z","end":"2026-07-02T05:57:32.60781Z","steps":["trace[223480885] 'process raft request' (duration: 349.298821ms)","trace[223480885] 'compare' (duration: 174.057418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:32.60785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:32.084077Z","time spent":"523.756676ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:32.608069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.067756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-02T05:57:32.608091Z","caller":"traceutil/trace.go:171","msg":"trace[1183412009] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24722; }","duration":"406.118886ms","start":"2026-07-02T05:57:32.201962Z","end":"2026-07-02T05:57:32.608081Z","steps":["trace[1183412009] 'agreement among raft nodes before linearized reading' (duration: 406.027305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:32.608107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:32.201928Z","time spent":"406.175078ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-02T05:57:32.608079Z","caller":"traceutil/trace.go:171","msg":"trace[478541391] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"522.243387ms","start":"2026-07-02T05:57:32.085754Z","end":"2026-07-02T05:57:32.607998Z","steps":["trace[478541391] 'process raft request' (duration: 521.966481ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:32.608238Z","caller":"traceutil/trace.go:171","msg":"trace[1485625221] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"101.083263ms","start":"2026-07-02T05:57:32.507146Z","end":"2026-07-02T05:57:32.60823Z","steps":["trace[1485625221] 'process raft request' (duration: 100.862309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:32.608283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.515037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:57:32.608318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:32.085739Z","time spent":"522.451751ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:32.608336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.013735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:57:32.608446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.84035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:57:32.608435Z","caller":"traceutil/trace.go:171","msg":"trace[2030798400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24723; }","duration":"334.716931ms","start":"2026-07-02T05:57:32.273692Z","end":"2026-07-02T05:57:32.608409Z","steps":["trace[2030798400] 'agreement among raft nodes before linearized reading' (duration: 334.491447ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:32.608469Z","caller":"traceutil/trace.go:171","msg":"trace[349274779] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24723; }","duration":"201.89601ms","start":"2026-07-02T05:57:32.406567Z","end":"2026-07-02T05:57:32.608463Z","steps":["trace[349274779] 'agreement among raft nodes before linearized reading' (duration: 201.85527ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:32.608473Z","caller":"traceutil/trace.go:171","msg":"trace[1595378931] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:24723; }","duration":"120.213869ms","start":"2026-07-02T05:57:32.488237Z","end":"2026-07-02T05:57:32.608451Z","steps":["trace[1595378931] 'agreement among raft nodes before linearized reading' (duration: 119.992105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:32.608486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:32.273661Z","time spent":"334.813923ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:57:33.088295Z","caller":"traceutil/trace.go:171","msg":"trace[2119282352] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"152.357714ms","start":"2026-07-02T05:57:32.935902Z","end":"2026-07-02T05:57:33.08826Z","steps":["trace[2119282352] 'process raft request' (duration: 152.299943ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.08881Z","caller":"traceutil/trace.go:171","msg":"trace[406785133] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"189.341931ms","start":"2026-07-02T05:57:32.899426Z","end":"2026-07-02T05:57:33.088768Z","steps":["trace[406785133] 'process raft request' (duration: 188.536915ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.793163Z","caller":"traceutil/trace.go:171","msg":"trace[1533109290] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25541; }","duration":"108.687859ms","start":"2026-07-02T05:57:33.684453Z","end":"2026-07-02T05:57:33.793141Z","steps":["trace[1533109290] 'read index received' (duration: 33.730845ms)","trace[1533109290] 'applied index is now lower than readState.Index' (duration: 74.955784ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:33.793347Z","caller":"traceutil/trace.go:171","msg":"trace[688252492] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"109.718658ms","start":"2026-07-02T05:57:33.683609Z","end":"2026-07-02T05:57:33.793327Z","steps":["trace[688252492] 'process raft request' (duration: 109.492864ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.793354Z","caller":"traceutil/trace.go:171","msg":"trace[424505902] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"110.793229ms","start":"2026-07-02T05:57:33.682521Z","end":"2026-07-02T05:57:33.793314Z","steps":["trace[424505902] 'process raft request' (duration: 110.434822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:33.793664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.189478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-02T05:57:33.793781Z","caller":"traceutil/trace.go:171","msg":"trace[712562088] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24733; }","duration":"109.31082ms","start":"2026-07-02T05:57:33.684437Z","end":"2026-07-02T05:57:33.793748Z","steps":["trace[712562088] 'agreement among raft nodes before linearized reading' (duration: 109.064995ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:34.732666Z","caller":"traceutil/trace.go:171","msg":"trace[2117850735] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"156.204277ms","start":"2026-07-02T05:57:34.576423Z","end":"2026-07-02T05:57:34.732627Z","steps":["trace[2117850735] 'process raft request' (duration: 93.955467ms)","trace[2117850735] 'compare' (duration: 62.047287ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:34.732731Z","caller":"traceutil/trace.go:171","msg":"trace[177723454] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25547; }","duration":"156.128105ms","start":"2026-07-02T05:57:34.576584Z","end":"2026-07-02T05:57:34.732712Z","steps":["trace[177723454] 'read index received' (duration: 93.808114ms)","trace[177723454] 'applied index is now lower than readState.Index' (duration: 62.317641ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:34.732795Z","caller":"traceutil/trace.go:171","msg":"trace[172374667] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"118.414394ms","start":"2026-07-02T05:57:34.614349Z","end":"2026-07-02T05:57:34.732763Z","steps":["trace[172374667] 'process raft request' (duration: 118.270591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:34.732902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.322799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:34.732935Z","caller":"traceutil/trace.go:171","msg":"trace[1710262132] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24737; }","duration":"156.36875ms","start":"2026-07-02T05:57:34.576559Z","end":"2026-07-02T05:57:34.732928Z","steps":["trace[1710262132] 'agreement among raft nodes before linearized reading' (duration: 156.249258ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:34.874158Z","caller":"traceutil/trace.go:171","msg":"trace[341991509] linearizableReadLoop","detail":"{readStateIndex:25550; appliedIndex:25549; }","duration":"125.812336ms","start":"2026-07-02T05:57:34.748317Z","end":"2026-07-02T05:57:34.874129Z","steps":["trace[341991509] 'read index received' (duration: 125.601492ms)","trace[341991509] 'applied index is now lower than readState.Index' (duration: 209.824µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:34.874425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.093881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-02T05:57:34.874465Z","caller":"traceutil/trace.go:171","msg":"trace[1612673946] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"135.42329ms","start":"2026-07-02T05:57:34.738995Z","end":"2026-07-02T05:57:34.874418Z","steps":["trace[1612673946] 'process raft request' (duration: 134.978791ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:34.874517Z","caller":"traceutil/trace.go:171","msg":"trace[821832145] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24738; }","duration":"126.411487ms","start":"2026-07-02T05:57:34.748038Z","end":"2026-07-02T05:57:34.874449Z","steps":["trace[821832145] 'agreement among raft nodes before linearized reading' (duration: 126.198193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:35.237807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.097342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:35.237926Z","caller":"traceutil/trace.go:171","msg":"trace[2041629198] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"142.099267ms","start":"2026-07-02T05:57:35.095816Z","end":"2026-07-02T05:57:35.237915Z","steps":["trace[2041629198] 'process raft request' (duration: 142.062687ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:35.2381Z","caller":"traceutil/trace.go:171","msg":"trace[491843035] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"273.106803ms","start":"2026-07-02T05:57:34.964985Z","end":"2026-07-02T05:57:35.238092Z","steps":["trace[491843035] 'process raft request' (duration: 112.667814ms)","trace[491843035] 'compare' (duration: 159.875728ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:35.279805Z","caller":"traceutil/trace.go:171","msg":"trace[575139620] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"181.658315ms","start":"2026-07-02T05:57:35.098098Z","end":"2026-07-02T05:57:35.279756Z","steps":["trace[575139620] 'process raft request' (duration: 181.339249ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:35.436411Z","caller":"traceutil/trace.go:171","msg":"trace[1029701187] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"104.180913ms","start":"2026-07-02T05:57:35.332197Z","end":"2026-07-02T05:57:35.436378Z","steps":["trace[1029701187] 'process raft request' (duration: 78.94392ms)","trace[1029701187] 'compare' (duration: 24.951797ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:35.841096Z","caller":"traceutil/trace.go:171","msg":"trace[937132331] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25555; }","duration":"117.582799ms","start":"2026-07-02T05:57:35.723459Z","end":"2026-07-02T05:57:35.841042Z","steps":["trace[937132331] 'read index received' (duration: 46.178023ms)","trace[937132331] 'applied index is now lower than readState.Index' (duration: 71.403076ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:35.841291Z","caller":"traceutil/trace.go:171","msg":"trace[1896259725] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"154.25946ms","start":"2026-07-02T05:57:35.687016Z","end":"2026-07-02T05:57:35.841276Z","steps":["trace[1896259725] 'process raft request' (duration: 82.61637ms)","trace[1896259725] 'compare' (duration: 70.878765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:35.841633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.145709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T05:57:35.841682Z","caller":"traceutil/trace.go:171","msg":"trace[2087255062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24744; }","duration":"118.260681ms","start":"2026-07-02T05:57:35.72341Z","end":"2026-07-02T05:57:35.84167Z","steps":["trace[2087255062] 'agreement among raft nodes before linearized reading' (duration: 118.061568ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:39.785054Z","caller":"traceutil/trace.go:171","msg":"trace[1745382489] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"103.635793ms","start":"2026-07-02T05:57:39.681384Z","end":"2026-07-02T05:57:39.78502Z","steps":["trace[1745382489] 'process raft request' (duration: 103.377798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:39.980314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.492207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:39.980403Z","caller":"traceutil/trace.go:171","msg":"trace[362802258] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24772; }","duration":"118.630099ms","start":"2026-07-02T05:57:39.861752Z","end":"2026-07-02T05:57:39.980382Z","steps":["trace[362802258] 'count revisions from in-memory index tree' (duration: 118.433335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:39.980555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.092999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T05:57:39.980652Z","caller":"traceutil/trace.go:171","msg":"trace[835710873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24772; }","duration":"106.330943ms","start":"2026-07-02T05:57:39.874299Z","end":"2026-07-02T05:57:39.98063Z","steps":["trace[835710873] 'range keys from in-memory index tree' (duration: 105.932816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:39.981452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.756406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:57:39.981604Z","caller":"traceutil/trace.go:171","msg":"trace[1746886441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24772; }","duration":"124.956969ms","start":"2026-07-02T05:57:39.856629Z","end":"2026-07-02T05:57:39.981586Z","steps":["trace[1746886441] 'range keys from in-memory index tree' (duration: 124.184245ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:39.983072Z","caller":"traceutil/trace.go:171","msg":"trace[804651811] linearizableReadLoop","detail":"{readStateIndex:25585; appliedIndex:25584; }","duration":"106.269553ms","start":"2026-07-02T05:57:39.876763Z","end":"2026-07-02T05:57:39.983033Z","steps":["trace[804651811] 'read index received' (duration: 60.08432ms)","trace[804651811] 'applied index is now lower than readState.Index' (duration: 46.183503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:39.983152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.381305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:57:39.983197Z","caller":"traceutil/trace.go:171","msg":"trace[920221872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24772; }","duration":"106.448716ms","start":"2026-07-02T05:57:39.876737Z","end":"2026-07-02T05:57:39.983186Z","steps":["trace[920221872] 'agreement among raft nodes before linearized reading' (duration: 106.323153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:39.983335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.550958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:57:39.983378Z","caller":"traceutil/trace.go:171","msg":"trace[112602178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24772; }","duration":"106.622569ms","start":"2026-07-02T05:57:39.876744Z","end":"2026-07-02T05:57:39.983367Z","steps":["trace[112602178] 'agreement among raft nodes before linearized reading' (duration: 106.447666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:55.537625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.902464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T05:57:55.537947Z","caller":"traceutil/trace.go:171","msg":"trace[910577218] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24898; }","duration":"141.097908ms","start":"2026-07-02T05:57:55.396663Z","end":"2026-07-02T05:57:55.537761Z","steps":["trace[910577218] 'range keys from in-memory index tree' (duration: 140.722931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:55.753452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.110144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:55.753559Z","caller":"traceutil/trace.go:171","msg":"trace[1264844687] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24899; }","duration":"100.340829ms","start":"2026-07-02T05:57:55.653192Z","end":"2026-07-02T05:57:55.753532Z","steps":["trace[1264844687] 'count revisions from in-memory index tree' (duration: 99.925671ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.238139Z","caller":"traceutil/trace.go:171","msg":"trace[1537420908] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"129.896684ms","start":"2026-07-02T05:57:56.108216Z","end":"2026-07-02T05:57:56.238113Z","steps":["trace[1537420908] 'process raft request' (duration: 129.71514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.532402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.872649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:56.532718Z","caller":"traceutil/trace.go:171","msg":"trace[862270117] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"257.81608ms","start":"2026-07-02T05:57:56.274884Z","end":"2026-07-02T05:57:56.5327Z","steps":["trace[862270117] 'process raft request' (duration: 257.776379ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.532797Z","caller":"traceutil/trace.go:171","msg":"trace[1543284695] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"423.812975ms","start":"2026-07-02T05:57:56.108937Z","end":"2026-07-02T05:57:56.53275Z","steps":["trace[1543284695] 'process raft request' (duration: 423.566161ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.532809Z","caller":"traceutil/trace.go:171","msg":"trace[641949473] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"424.192602ms","start":"2026-07-02T05:57:56.108579Z","end":"2026-07-02T05:57:56.532771Z","steps":["trace[641949473] 'process raft request' (duration: 322.528278ms)","trace[641949473] 'compare' (duration: 100.38874ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:56.532872Z","caller":"traceutil/trace.go:171","msg":"trace[1500501203] linearizableReadLoop","detail":"{readStateIndex:25721; appliedIndex:25717; }","duration":"352.091803ms","start":"2026-07-02T05:57:56.180752Z","end":"2026-07-02T05:57:56.532843Z","steps":["trace[1500501203] 'read index received' (duration: 57.179873ms)","trace[1500501203] 'applied index is now lower than readState.Index' (duration: 294.90939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:56.532971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.108555Z","time spent":"424.327505ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:56.532974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.10893Z","time spent":"423.951378ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:56.532909Z","caller":"traceutil/trace.go:171","msg":"trace[240003110] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"423.54787ms","start":"2026-07-02T05:57:56.109291Z","end":"2026-07-02T05:57:56.532839Z","steps":["trace[240003110] 'process raft request' (duration: 423.295465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.533039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.264806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:56.533122Z","caller":"traceutil/trace.go:171","msg":"trace[340955144] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24905; }","duration":"352.389919ms","start":"2026-07-02T05:57:56.180712Z","end":"2026-07-02T05:57:56.533102Z","steps":["trace[340955144] 'agreement among raft nodes before linearized reading' (duration: 352.225556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.533172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.180696Z","time spent":"352.46581ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:57:56.534019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.109274Z","time spent":"424.652881ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:56.537448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.572269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:56.537508Z","caller":"traceutil/trace.go:171","msg":"trace[1795482236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24906; }","duration":"264.675692ms","start":"2026-07-02T05:57:56.272819Z","end":"2026-07-02T05:57:56.537494Z","steps":["trace[1795482236] 'agreement among raft nodes before linearized reading' (duration: 264.544509ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:00.667688Z","caller":"traceutil/trace.go:171","msg":"trace[560960697] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"118.335833ms","start":"2026-07-02T05:58:00.54932Z","end":"2026-07-02T05:58:00.667655Z","steps":["trace[560960697] 'process raft request' (duration: 42.444442ms)","trace[560960697] 'compare' (duration: 75.736478ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:00.667793Z","caller":"traceutil/trace.go:171","msg":"trace[1881715408] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"105.187701ms","start":"2026-07-02T05:58:00.562593Z","end":"2026-07-02T05:58:00.667781Z","steps":["trace[1881715408] 'process raft request' (duration: 105.040949ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:00.667693Z","caller":"traceutil/trace.go:171","msg":"trace[1522320698] linearizableReadLoop","detail":"{readStateIndex:25751; appliedIndex:25750; }","duration":"110.394861ms","start":"2026-07-02T05:58:00.557277Z","end":"2026-07-02T05:58:00.667672Z","steps":["trace[1522320698] 'read index received' (duration: 34.461699ms)","trace[1522320698] 'applied index is now lower than readState.Index' (duration: 75.932322ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:00.667926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.602236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T05:58:00.667988Z","caller":"traceutil/trace.go:171","msg":"trace[32271791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24934; }","duration":"119.689979ms","start":"2026-07-02T05:58:00.548284Z","end":"2026-07-02T05:58:00.667974Z","steps":["trace[32271791] 'agreement among raft nodes before linearized reading' (duration: 119.457555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:00.668082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.784501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:58:00.668168Z","caller":"traceutil/trace.go:171","msg":"trace[2048579854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24934; }","duration":"119.928274ms","start":"2026-07-02T05:58:00.548219Z","end":"2026-07-02T05:58:00.668148Z","steps":["trace[2048579854] 'agreement among raft nodes before linearized reading' (duration: 119.523166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:00.882025Z","caller":"traceutil/trace.go:171","msg":"trace[1668934293] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"207.63281ms","start":"2026-07-02T05:58:00.674367Z","end":"2026-07-02T05:58:00.882Z","steps":["trace[1668934293] 'process raft request' (duration: 207.543948ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:00.882307Z","caller":"traceutil/trace.go:171","msg":"trace[1059275357] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"209.780462ms","start":"2026-07-02T05:58:00.672509Z","end":"2026-07-02T05:58:00.88229Z","steps":["trace[1059275357] 'process raft request' (duration: 185.672191ms)","trace[1059275357] 'compare' (duration: 23.322176ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:00.882318Z","caller":"traceutil/trace.go:171","msg":"trace[738536059] linearizableReadLoop","detail":"{readStateIndex:25753; appliedIndex:25752; }","duration":"209.342824ms","start":"2026-07-02T05:58:00.672947Z","end":"2026-07-02T05:58:00.88229Z","steps":["trace[738536059] 'read index received' (duration: 185.377185ms)","trace[738536059] 'applied index is now lower than readState.Index' (duration: 23.962809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:00.88263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.66889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-02T05:58:00.882675Z","caller":"traceutil/trace.go:171","msg":"trace[764809971] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24936; }","duration":"209.751411ms","start":"2026-07-02T05:58:00.672911Z","end":"2026-07-02T05:58:00.882662Z","steps":["trace[764809971] 'agreement among raft nodes before linearized reading' (duration: 209.478876ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:01.088375Z","caller":"traceutil/trace.go:171","msg":"trace[1846476353] linearizableReadLoop","detail":"{readStateIndex:25756; appliedIndex:25755; }","duration":"122.242489ms","start":"2026-07-02T05:58:00.966107Z","end":"2026-07-02T05:58:01.088349Z","steps":["trace[1846476353] 'read index received' (duration: 44.858568ms)","trace[1846476353] 'applied index is now lower than readState.Index' (duration: 77.382771ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:01.088616Z","caller":"traceutil/trace.go:171","msg":"trace[22215383] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"132.534415ms","start":"2026-07-02T05:58:00.956067Z","end":"2026-07-02T05:58:01.088601Z","steps":["trace[22215383] 'process raft request' (duration: 54.954691ms)","trace[22215383] 'compare' (duration: 77.036234ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:01.088926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.806729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-02T05:58:01.088978Z","caller":"traceutil/trace.go:171","msg":"trace[1759943906] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24938; }","duration":"122.88931ms","start":"2026-07-02T05:58:00.966073Z","end":"2026-07-02T05:58:01.088962Z","steps":["trace[1759943906] 'agreement among raft nodes before linearized reading' (duration: 122.724297ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:01.496393Z","caller":"traceutil/trace.go:171","msg":"trace[1078820441] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"172.678882ms","start":"2026-07-02T05:58:01.323684Z","end":"2026-07-02T05:58:01.496363Z","steps":["trace[1078820441] 'process raft request' (duration: 172.479438ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:01.570821Z","caller":"traceutil/trace.go:171","msg":"trace[822763331] linearizableReadLoop","detail":"{readStateIndex:25759; appliedIndex:25757; }","duration":"234.153627ms","start":"2026-07-02T05:58:01.336647Z","end":"2026-07-02T05:58:01.570801Z","steps":["trace[822763331] 'read index received' (duration: 159.545621ms)","trace[822763331] 'applied index is now lower than readState.Index' (duration: 74.607216ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:01.570965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.29193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:01.571008Z","caller":"traceutil/trace.go:171","msg":"trace[2095517882] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24942; }","duration":"234.381022ms","start":"2026-07-02T05:58:01.336614Z","end":"2026-07-02T05:58:01.570995Z","steps":["trace[2095517882] 'agreement among raft nodes before linearized reading' (duration: 234.26732ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:01.571306Z","caller":"traceutil/trace.go:171","msg":"trace[1497883803] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"164.001066ms","start":"2026-07-02T05:58:01.407282Z","end":"2026-07-02T05:58:01.571283Z","steps":["trace[1497883803] 'process raft request' (duration: 163.433465ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:01.571469Z","caller":"traceutil/trace.go:171","msg":"trace[1794918087] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"246.828872ms","start":"2026-07-02T05:58:01.324626Z","end":"2026-07-02T05:58:01.571455Z","steps":["trace[1794918087] 'process raft request' (duration: 177.996985ms)","trace[1794918087] 'compare' (duration: 67.793696ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:39.503441Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000666c.wal"} {"level":"info","ts":"2026-07-02T06:00:20.933597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23469} {"level":"info","ts":"2026-07-02T06:00:21.066171Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23469,"took":"128.400951ms","hash":1880565605,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":37855232,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-02T06:00:21.066233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1880565605,"revision":23469,"compact-revision":20313} {"level":"info","ts":"2026-07-02T06:05:20.940386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26416} {"level":"info","ts":"2026-07-02T06:05:21.098717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26416,"took":"154.669944ms","hash":3910533422,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":33689600,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T06:05:21.098823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910533422,"revision":26416,"compact-revision":23469} {"level":"info","ts":"2026-07-02T06:05:37.202021Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:05:37.206995Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T06:05:37.20712Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T06:10:20.946546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28956} {"level":"info","ts":"2026-07-02T06:10:21.085601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28956,"took":"135.368904ms","hash":1883913667,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T06:10:21.085658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1883913667,"revision":28956,"compact-revision":26416} {"level":"info","ts":"2026-07-02T06:15:20.951406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31179} {"level":"info","ts":"2026-07-02T06:15:21.073002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31179,"took":"118.180275ms","hash":3614686454,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T06:15:21.073074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3614686454,"revision":31179,"compact-revision":28956} {"level":"info","ts":"2026-07-02T06:20:20.960025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33473} {"level":"info","ts":"2026-07-02T06:20:21.112806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33473,"took":"149.35461ms","hash":3910240864,"current-db-size-bytes":62144512,"current-db-size":"62 MB","current-db-size-in-use-bytes":33153024,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T06:20:21.112917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910240864,"revision":33473,"compact-revision":31179} {"level":"info","ts":"2026-07-02T06:25:20.965771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35739} {"level":"info","ts":"2026-07-02T06:25:21.10601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35739,"took":"136.297692ms","hash":3780983530,"current-db-size-bytes":62144512,"current-db-size":"62 MB","current-db-size-in-use-bytes":31612928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T06:25:21.106079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3780983530,"revision":35739,"compact-revision":33473} {"level":"info","ts":"2026-07-02T06:27:06.960906Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:27:06.965896Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T06:27:06.965974Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}