{"level":"warn","ts":"2026-07-14T23:07:13.359524Z","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-14T23:07:13.359614Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-14T23:07:13.35968Z","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-14T23:07:13.359688Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-07-14T23:07:13.359705Z","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-14T23:07:13.360346Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-07-14T23:07:13.360448Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-14T23:07:13.365049Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.30983ms"} {"level":"info","ts":"2026-07-14T23:07:13.378459Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-07-14T23:07:13.378554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T23:07:13.378599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-07-14T23:07:13.378609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T23:07:13.378626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-07-14T23:07:13.378687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-07-14T23:07:13.385666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T23:07:13.387338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T23:07:13.390933Z","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-14T23:07:13.39331Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T23:07:13.396003Z","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-14T23:07:13.396137Z","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-14T23:07:13.396148Z","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-14T23:07:13.396001Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T23:07:13.39717Z","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-14T23:07:13.397266Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-07-14T23:07:13.39729Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-07-14T23:07:13.397489Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T23:07:13.39755Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T23:07:13.398139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-07-14T23:07:13.398348Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-07-14T23:07:14.179225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T23:07:14.179293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T23:07:14.179328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-07-14T23:07:14.179344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-07-14T23:07:14.179355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-07-14T23:07:14.179366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-07-14T23:07:14.179378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-07-14T23:07:14.183311Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:07:14.183538Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:07:14.183744Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T23:07:14.183772Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T23:07:14.182894Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T23:07:14.183881Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:07:14.185239Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:07:14.185805Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:07:14.185862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:07:14.189702Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-07-14T23:07:14.18983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T23:07:26.074512Z","caller":"traceutil/trace.go:171","msg":"trace[702259904] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"175.841414ms","start":"2026-07-14T23:07:25.898648Z","end":"2026-07-14T23:07:26.07449Z","steps":["trace[702259904] 'read index received' (duration: 175.673519ms)","trace[702259904] 'applied index is now lower than readState.Index' (duration: 167.205µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:26.074636Z","caller":"traceutil/trace.go:171","msg":"trace[446984784] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"266.260745ms","start":"2026-07-14T23:07:25.808367Z","end":"2026-07-14T23:07:26.074628Z","steps":["trace[446984784] 'process raft request' (duration: 265.977487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:26.074781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.099811ms","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-14T23:07:26.07482Z","caller":"traceutil/trace.go:171","msg":"trace[857149480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:355; }","duration":"176.195814ms","start":"2026-07-14T23:07:25.898617Z","end":"2026-07-14T23:07:26.074812Z","steps":["trace[857149480] 'agreement among raft nodes before linearized reading' (duration: 176.103731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:07:26.228639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.845248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:07:26.228807Z","caller":"traceutil/trace.go:171","msg":"trace[216314392] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"148.689444ms","start":"2026-07-14T23:07:26.080102Z","end":"2026-07-14T23:07:26.228791Z","steps":["trace[216314392] 'process raft request' (duration: 148.635792ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:26.228953Z","caller":"traceutil/trace.go:171","msg":"trace[806342619] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"150.214026ms","start":"2026-07-14T23:07:26.078727Z","end":"2026-07-14T23:07:26.228941Z","steps":["trace[806342619] 'process raft request' (duration: 35.507644ms)","trace[806342619] 'compare' (duration: 113.735345ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:48.046758Z","caller":"traceutil/trace.go:171","msg":"trace[1881320743] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"187.50498ms","start":"2026-07-14T23:07:47.859226Z","end":"2026-07-14T23:07:48.046731Z","steps":["trace[1881320743] 'process raft request' (duration: 187.273303ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.051804Z","caller":"traceutil/trace.go:171","msg":"trace[1154745355] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"189.438564ms","start":"2026-07-14T23:07:47.862339Z","end":"2026-07-14T23:07:48.051777Z","steps":["trace[1154745355] 'process raft request' (duration: 189.264739ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.052185Z","caller":"traceutil/trace.go:171","msg":"trace[1599366276] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"188.831097ms","start":"2026-07-14T23:07:47.863331Z","end":"2026-07-14T23:07:48.052162Z","steps":["trace[1599366276] 'process raft request' (duration: 188.403375ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.05256Z","caller":"traceutil/trace.go:171","msg":"trace[1885569674] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"187.106119ms","start":"2026-07-14T23:07:47.865441Z","end":"2026-07-14T23:07:48.052547Z","steps":["trace[1885569674] 'process raft request' (duration: 186.726228ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.052702Z","caller":"traceutil/trace.go:171","msg":"trace[1600215134] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"186.230654ms","start":"2026-07-14T23:07:47.866453Z","end":"2026-07-14T23:07:48.052683Z","steps":["trace[1600215134] 'process raft request' (duration: 186.022809ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.052796Z","caller":"traceutil/trace.go:171","msg":"trace[1055473819] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"183.818807ms","start":"2026-07-14T23:07:47.868941Z","end":"2026-07-14T23:07:48.05276Z","steps":["trace[1055473819] 'process raft request' (duration: 183.630221ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.052977Z","caller":"traceutil/trace.go:171","msg":"trace[183846769] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"182.956132ms","start":"2026-07-14T23:07:47.87001Z","end":"2026-07-14T23:07:48.052966Z","steps":["trace[183846769] 'process raft request' (duration: 182.614993ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.053136Z","caller":"traceutil/trace.go:171","msg":"trace[1046674003] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"180.414811ms","start":"2026-07-14T23:07:47.872689Z","end":"2026-07-14T23:07:48.053103Z","steps":["trace[1046674003] 'process raft request' (duration: 180.080042ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.05326Z","caller":"traceutil/trace.go:171","msg":"trace[1825310990] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"170.603416ms","start":"2026-07-14T23:07:47.882644Z","end":"2026-07-14T23:07:48.053247Z","steps":["trace[1825310990] 'process raft request' (duration: 170.306878ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:48.053388Z","caller":"traceutil/trace.go:171","msg":"trace[368708068] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"170.671529ms","start":"2026-07-14T23:07:47.882693Z","end":"2026-07-14T23:07:48.053365Z","steps":["trace[368708068] 'process raft request' (duration: 170.454932ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:54.020723Z","caller":"traceutil/trace.go:171","msg":"trace[1286791732] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"203.96423ms","start":"2026-07-14T23:07:53.816674Z","end":"2026-07-14T23:07:54.020638Z","steps":["trace[1286791732] 'process raft request' (duration: 183.535078ms)","trace[1286791732] 'compare' (duration: 20.177695ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:07:54.021283Z","caller":"traceutil/trace.go:171","msg":"trace[438566581] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"150.322718ms","start":"2026-07-14T23:07:53.870943Z","end":"2026-07-14T23:07:54.021266Z","steps":["trace[438566581] 'process raft request' (duration: 149.806543ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:55.144066Z","caller":"traceutil/trace.go:171","msg":"trace[777582551] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"646.294894ms","start":"2026-07-14T23:07:54.497743Z","end":"2026-07-14T23:07:55.144038Z","steps":["trace[777582551] 'process raft request' (duration: 634.929275ms)","trace[777582551] 'compare' (duration: 11.012189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:07:55.1442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:07:54.497708Z","time spent":"646.427718ms","remote":"127.0.0.1:35426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:08:42.522351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.26327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:08:42.522456Z","caller":"traceutil/trace.go:171","msg":"trace[2006684428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"151.459596ms","start":"2026-07-14T23:08:42.370975Z","end":"2026-07-14T23:08:42.522434Z","steps":["trace[2006684428] 'range keys from in-memory index tree' (duration: 151.136567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:08:42.522493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.82554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T23:08:42.522718Z","caller":"traceutil/trace.go:171","msg":"trace[288215958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"117.080666ms","start":"2026-07-14T23:08:42.405618Z","end":"2026-07-14T23:08:42.522699Z","steps":["trace[288215958] 'range keys from in-memory index tree' (duration: 116.676325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.635656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.912184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:09:27.635773Z","caller":"traceutil/trace.go:171","msg":"trace[1998292093] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"264.824724ms","start":"2026-07-14T23:09:27.370928Z","end":"2026-07-14T23:09:27.635753Z","steps":["trace[1998292093] 'read index received' (duration: 3.56733ms)","trace[1998292093] 'applied index is now lower than readState.Index' (duration: 261.256144ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:09:27.635839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.919526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:09:27.635834Z","caller":"traceutil/trace.go:171","msg":"trace[119222877] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"341.179262ms","start":"2026-07-14T23:09:27.294612Z","end":"2026-07-14T23:09:27.635791Z","steps":["trace[119222877] 'process raft request' (duration: 79.960209ms)","trace[119222877] 'compare' (duration: 260.7779ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:09:27.635872Z","caller":"traceutil/trace.go:171","msg":"trace[984296136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"264.984728ms","start":"2026-07-14T23:09:27.370879Z","end":"2026-07-14T23:09:27.635863Z","steps":["trace[984296136] 'agreement among raft nodes before linearized reading' (duration: 264.928926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:09:27.635932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:09:27.294582Z","time spent":"341.304935ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:10:11.926721Z","caller":"traceutil/trace.go:171","msg":"trace[765451217] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"149.136418ms","start":"2026-07-14T23:10:11.777558Z","end":"2026-07-14T23:10:11.926694Z","steps":["trace[765451217] 'process raft request' (duration: 148.917182ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.352189Z","caller":"traceutil/trace.go:171","msg":"trace[1615988576] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"108.330631ms","start":"2026-07-14T23:11:40.243836Z","end":"2026-07-14T23:11:40.352167Z","steps":["trace[1615988576] 'process raft request' (duration: 108.206748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:40.945165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.114883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:11:40.945429Z","caller":"traceutil/trace.go:171","msg":"trace[1802986396] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"251.793872ms","start":"2026-07-14T23:11:40.6936Z","end":"2026-07-14T23:11:40.945394Z","steps":["trace[1802986396] 'process raft request' (duration: 251.700079ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:40.945425Z","caller":"traceutil/trace.go:171","msg":"trace[1118335231] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"356.758009ms","start":"2026-07-14T23:11:40.588631Z","end":"2026-07-14T23:11:40.945389Z","steps":["trace[1118335231] 'process raft request' (duration: 124.312047ms)","trace[1118335231] 'compare' (duration: 231.800384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:40.945618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:11:40.588613Z","time spent":"356.896273ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:14:00.154351Z","caller":"traceutil/trace.go:171","msg":"trace[1271911326] transaction","detail":"{read_only:false; response_revision:3720; number_of_response:1; }","duration":"108.488063ms","start":"2026-07-14T23:14:00.045829Z","end":"2026-07-14T23:14:00.154317Z","steps":["trace[1271911326] 'process raft request' (duration: 83.397272ms)","trace[1271911326] 'compare' (duration: 24.774432ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:14:05.640181Z","caller":"traceutil/trace.go:171","msg":"trace[221838589] transaction","detail":"{read_only:false; response_revision:3745; number_of_response:1; }","duration":"144.443193ms","start":"2026-07-14T23:14:05.495706Z","end":"2026-07-14T23:14:05.640149Z","steps":["trace[221838589] 'process raft request' (duration: 144.247387ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:14:05.645378Z","caller":"traceutil/trace.go:171","msg":"trace[1526886730] transaction","detail":"{read_only:false; response_revision:3746; number_of_response:1; }","duration":"148.719924ms","start":"2026-07-14T23:14:05.49664Z","end":"2026-07-14T23:14:05.64536Z","steps":["trace[1526886730] 'process raft request' (duration: 148.56378ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:24.744807Z","caller":"traceutil/trace.go:171","msg":"trace[419658722] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"164.870713ms","start":"2026-07-14T23:15:24.579905Z","end":"2026-07-14T23:15:24.744776Z","steps":["trace[419658722] 'process raft request' (duration: 164.691808ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:24.81122Z","caller":"traceutil/trace.go:171","msg":"trace[834764438] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4889; }","duration":"196.896031ms","start":"2026-07-14T23:15:24.614294Z","end":"2026-07-14T23:15:24.81119Z","steps":["trace[834764438] 'read index received' (duration: 130.421837ms)","trace[834764438] 'applied index is now lower than readState.Index' (duration: 66.472914ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:15:24.8113Z","caller":"traceutil/trace.go:171","msg":"trace[1254073666] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"230.327109ms","start":"2026-07-14T23:15:24.580942Z","end":"2026-07-14T23:15:24.811269Z","steps":["trace[1254073666] 'process raft request' (duration: 221.310806ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:24.811414Z","caller":"traceutil/trace.go:171","msg":"trace[557659083] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"229.335131ms","start":"2026-07-14T23:15:24.58203Z","end":"2026-07-14T23:15:24.811366Z","steps":["trace[557659083] 'process raft request' (duration: 229.091874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:24.811529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.282153ms","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-14T23:15:24.811636Z","caller":"traceutil/trace.go:171","msg":"trace[587243986] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4713; }","duration":"125.439208ms","start":"2026-07-14T23:15:24.686182Z","end":"2026-07-14T23:15:24.811621Z","steps":["trace[587243986] 'agreement among raft nodes before linearized reading' (duration: 125.202481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:24.811531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.80498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f7b1dd15-9af0-450d-9e80-a438f5489094\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-07-14T23:15:24.811703Z","caller":"traceutil/trace.go:171","msg":"trace[386508661] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f7b1dd15-9af0-450d-9e80-a438f5489094; range_end:; response_count:1; response_revision:4713; }","duration":"196.014187ms","start":"2026-07-14T23:15:24.615674Z","end":"2026-07-14T23:15:24.811688Z","steps":["trace[386508661] 'agreement among raft nodes before linearized reading' (duration: 195.711258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:24.812207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.881299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2209844"} {"level":"info","ts":"2026-07-14T23:15:24.812291Z","caller":"traceutil/trace.go:171","msg":"trace[161485081] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4713; }","duration":"198.021043ms","start":"2026-07-14T23:15:24.61425Z","end":"2026-07-14T23:15:24.812271Z","steps":["trace[161485081] 'agreement among raft nodes before linearized reading' (duration: 197.105007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:30.337133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.500252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-07-14T23:15:30.337226Z","caller":"traceutil/trace.go:171","msg":"trace[768080146] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4901; }","duration":"113.649497ms","start":"2026-07-14T23:15:30.223557Z","end":"2026-07-14T23:15:30.337206Z","steps":["trace[768080146] 'range keys from in-memory index tree' (duration: 113.323258ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:51.489131Z","caller":"traceutil/trace.go:171","msg":"trace[1891608017] linearizableReadLoop","detail":"{readStateIndex:5559; appliedIndex:5558; }","duration":"160.342574ms","start":"2026-07-14T23:15:51.328764Z","end":"2026-07-14T23:15:51.489106Z","steps":["trace[1891608017] 'read index received' (duration: 129.355657ms)","trace[1891608017] 'applied index is now lower than readState.Index' (duration: 30.985467ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:15:51.48925Z","caller":"traceutil/trace.go:171","msg":"trace[1753118871] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"191.992629ms","start":"2026-07-14T23:15:51.297245Z","end":"2026-07-14T23:15:51.489238Z","steps":["trace[1753118871] 'process raft request' (duration: 160.926911ms)","trace[1753118871] 'compare' (duration: 30.797141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:15:51.489291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.513209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:15:51.489359Z","caller":"traceutil/trace.go:171","msg":"trace[1320423113] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5361; }","duration":"160.633932ms","start":"2026-07-14T23:15:51.328711Z","end":"2026-07-14T23:15:51.489345Z","steps":["trace[1320423113] 'agreement among raft nodes before linearized reading' (duration: 160.527049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:51.770649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.356618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:15:51.770766Z","caller":"traceutil/trace.go:171","msg":"trace[882084576] linearizableReadLoop","detail":"{readStateIndex:5560; appliedIndex:5559; }","duration":"281.540733ms","start":"2026-07-14T23:15:51.489207Z","end":"2026-07-14T23:15:51.770747Z","steps":["trace[882084576] 'read index received' (duration: 123.027411ms)","trace[882084576] 'applied index is now lower than readState.Index' (duration: 158.512012ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:15:51.770871Z","caller":"traceutil/trace.go:171","msg":"trace[1932665350] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"310.702278ms","start":"2026-07-14T23:15:51.460144Z","end":"2026-07-14T23:15:51.770847Z","steps":["trace[1932665350] 'process raft request' (duration: 152.075162ms)","trace[1932665350] 'compare' (duration: 158.170793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:15:51.770921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:15:51.460125Z","time spent":"310.7728ms","remote":"127.0.0.1:35426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:15:51.771212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.080886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7cecdad-62f8-40d0-b170-fd2b18136c53\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-07-14T23:15:51.77122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.080231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:15:51.771251Z","caller":"traceutil/trace.go:171","msg":"trace[1312165325] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7cecdad-62f8-40d0-b170-fd2b18136c53; range_end:; response_count:1; response_revision:5362; }","duration":"332.123607ms","start":"2026-07-14T23:15:51.439117Z","end":"2026-07-14T23:15:51.771241Z","steps":["trace[1312165325] 'agreement among raft nodes before linearized reading' (duration: 331.955992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:51.771289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:15:51.43906Z","time spent":"332.22215ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7cecdad-62f8-40d0-b170-fd2b18136c53\" "} {"level":"info","ts":"2026-07-14T23:15:51.771293Z","caller":"traceutil/trace.go:171","msg":"trace[1438197455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5362; }","duration":"403.230986ms","start":"2026-07-14T23:15:51.368043Z","end":"2026-07-14T23:15:51.771274Z","steps":["trace[1438197455] 'agreement among raft nodes before linearized reading' (duration: 403.069911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:15:51.771307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.015512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7cecdad-62f8-40d0-b170-fd2b18136c53\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-07-14T23:15:51.771335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:15:51.368021Z","time spent":"403.306398ms","remote":"127.0.0.1:35312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T23:15:51.771382Z","caller":"traceutil/trace.go:171","msg":"trace[1746117123] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7cecdad-62f8-40d0-b170-fd2b18136c53; range_end:; response_count:1; response_revision:5362; }","duration":"279.128255ms","start":"2026-07-14T23:15:51.492236Z","end":"2026-07-14T23:15:51.771364Z","steps":["trace[1746117123] 'agreement among raft nodes before linearized reading' (duration: 278.891959ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:51.931705Z","caller":"traceutil/trace.go:171","msg":"trace[1094625145] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"148.526673ms","start":"2026-07-14T23:15:51.783157Z","end":"2026-07-14T23:15:51.931684Z","steps":["trace[1094625145] 'process raft request' (duration: 148.184674ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:15:51.931746Z","caller":"traceutil/trace.go:171","msg":"trace[1560356899] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"153.022049ms","start":"2026-07-14T23:15:51.778691Z","end":"2026-07-14T23:15:51.931713Z","steps":["trace[1560356899] 'process raft request' (duration: 143.210515ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:17:14.229298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-07-14T23:17:14.301585Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"70.635065ms","hash":212366935,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":34312192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:17:14.301637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":212366935,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-07-14T23:21:48.597344Z","caller":"traceutil/trace.go:171","msg":"trace[2036083422] transaction","detail":"{read_only:false; response_revision:8135; number_of_response:1; }","duration":"102.513673ms","start":"2026-07-14T23:21:48.494805Z","end":"2026-07-14T23:21:48.597319Z","steps":["trace[2036083422] 'process raft request' (duration: 102.216845ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:21:48.773073Z","caller":"traceutil/trace.go:171","msg":"trace[409767617] transaction","detail":"{read_only:false; response_revision:8136; number_of_response:1; }","duration":"171.273017ms","start":"2026-07-14T23:21:48.601775Z","end":"2026-07-14T23:21:48.773048Z","steps":["trace[409767617] 'process raft request' (duration: 165.00847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:22:00.431949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.968455ms","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-14T23:22:00.432074Z","caller":"traceutil/trace.go:171","msg":"trace[1140884234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8194; }","duration":"136.15222ms","start":"2026-07-14T23:22:00.295888Z","end":"2026-07-14T23:22:00.43204Z","steps":["trace[1140884234] 'range keys from in-memory index tree' (duration: 135.696087ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:02.240425Z","caller":"traceutil/trace.go:171","msg":"trace[60600275] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"106.207077ms","start":"2026-07-14T23:22:02.134186Z","end":"2026-07-14T23:22:02.240394Z","steps":["trace[60600275] 'process raft request' (duration: 90.290829ms)","trace[60600275] 'compare' (duration: 15.759414ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:22:14.235439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6100} {"level":"info","ts":"2026-07-14T23:22:14.335432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6100,"took":"97.941025ms","hash":1062363528,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":23207936,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-14T23:22:14.335497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1062363528,"revision":6100,"compact-revision":2967} {"level":"warn","ts":"2026-07-14T23:22:28.963125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.582341ms","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-14T23:22:28.963218Z","caller":"traceutil/trace.go:171","msg":"trace[352030953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8401; }","duration":"377.683914ms","start":"2026-07-14T23:22:28.585515Z","end":"2026-07-14T23:22:28.963199Z","steps":["trace[352030953] 'range keys from in-memory index tree' (duration: 377.445637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:22:28.963304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:22:28.585481Z","time spent":"377.813877ms","remote":"127.0.0.1:35596","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-14T23:22:28.963408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:22:28.589Z","time spent":"374.401132ms","remote":"127.0.0.1:33612","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-14T23:22:28.988065Z","caller":"traceutil/trace.go:171","msg":"trace[1407125641] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"264.119298ms","start":"2026-07-14T23:22:28.723925Z","end":"2026-07-14T23:22:28.988044Z","steps":["trace[1407125641] 'process raft request' (duration: 263.969184ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:40.792625Z","caller":"traceutil/trace.go:171","msg":"trace[1860285386] linearizableReadLoop","detail":"{readStateIndex:8813; appliedIndex:8812; }","duration":"133.34739ms","start":"2026-07-14T23:22:40.659251Z","end":"2026-07-14T23:22:40.792598Z","steps":["trace[1860285386] 'read index received' (duration: 115.13912ms)","trace[1860285386] 'applied index is now lower than readState.Index' (duration: 18.20664ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:22:40.792765Z","caller":"traceutil/trace.go:171","msg":"trace[1943475958] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8507; }","duration":"137.351332ms","start":"2026-07-14T23:22:40.655371Z","end":"2026-07-14T23:22:40.792723Z","steps":["trace[1943475958] 'process raft request' (duration: 119.075831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:22:40.792916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.645878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-z9tdf\" ","response":"range_response_count:1 size:4669"} {"level":"info","ts":"2026-07-14T23:22:40.792958Z","caller":"traceutil/trace.go:171","msg":"trace[819721882] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-z9tdf; range_end:; response_count:1; response_revision:8507; }","duration":"133.73309ms","start":"2026-07-14T23:22:40.659214Z","end":"2026-07-14T23:22:40.792947Z","steps":["trace[819721882] 'agreement among raft nodes before linearized reading' (duration: 133.545995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:22:40.793106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.604717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-z9tdf\" ","response":"range_response_count:1 size:2801"} {"level":"info","ts":"2026-07-14T23:22:40.793207Z","caller":"traceutil/trace.go:171","msg":"trace[1476437952] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-z9tdf; range_end:; response_count:1; response_revision:8507; }","duration":"133.7308ms","start":"2026-07-14T23:22:40.659457Z","end":"2026-07-14T23:22:40.793188Z","steps":["trace[1476437952] 'agreement among raft nodes before linearized reading' (duration: 133.442602ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:41.291364Z","caller":"traceutil/trace.go:171","msg":"trace[1126137605] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"132.612979ms","start":"2026-07-14T23:22:41.158715Z","end":"2026-07-14T23:22:41.291328Z","steps":["trace[1126137605] 'process raft request' (duration: 91.946602ms)","trace[1126137605] 'compare' (duration: 40.38816ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:22:41.356966Z","caller":"traceutil/trace.go:171","msg":"trace[1932135599] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"197.71528ms","start":"2026-07-14T23:22:41.159222Z","end":"2026-07-14T23:22:41.356937Z","steps":["trace[1932135599] 'process raft request' (duration: 197.561816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:22:41.62063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.050672ms","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-14T23:22:41.620821Z","caller":"traceutil/trace.go:171","msg":"trace[729164825] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8515; }","duration":"182.183795ms","start":"2026-07-14T23:22:41.438533Z","end":"2026-07-14T23:22:41.620717Z","steps":["trace[729164825] 'range keys from in-memory index tree' (duration: 181.895917ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:41.620957Z","caller":"traceutil/trace.go:171","msg":"trace[1226724515] linearizableReadLoop","detail":"{readStateIndex:8822; appliedIndex:8821; }","duration":"115.239123ms","start":"2026-07-14T23:22:41.505693Z","end":"2026-07-14T23:22:41.620932Z","steps":["trace[1226724515] 'read index received' (duration: 103.153196ms)","trace[1226724515] 'applied index is now lower than readState.Index' (duration: 12.084927ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:22:41.621025Z","caller":"traceutil/trace.go:171","msg":"trace[68672530] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"137.681511ms","start":"2026-07-14T23:22:41.483302Z","end":"2026-07-14T23:22:41.620983Z","steps":["trace[68672530] 'process raft request' (duration: 125.530671ms)","trace[68672530] 'compare' (duration: 11.978315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:22:41.621193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.4951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-z9tdf\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-07-14T23:22:41.621257Z","caller":"traceutil/trace.go:171","msg":"trace[2136346920] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-z9tdf; range_end:; response_count:1; response_revision:8516; }","duration":"115.592503ms","start":"2026-07-14T23:22:41.505646Z","end":"2026-07-14T23:22:41.621238Z","steps":["trace[2136346920] 'agreement among raft nodes before linearized reading' (duration: 115.367027ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:41.645669Z","caller":"traceutil/trace.go:171","msg":"trace[645030550] transaction","detail":"{read_only:false; response_revision:8517; number_of_response:1; }","duration":"139.559533ms","start":"2026-07-14T23:22:41.506078Z","end":"2026-07-14T23:22:41.645638Z","steps":["trace[645030550] 'process raft request' (duration: 139.355878ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:41.64582Z","caller":"traceutil/trace.go:171","msg":"trace[1213979862] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"131.718904ms","start":"2026-07-14T23:22:41.514073Z","end":"2026-07-14T23:22:41.645792Z","steps":["trace[1213979862] 'process raft request' (duration: 131.543219ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:41.645954Z","caller":"traceutil/trace.go:171","msg":"trace[1015101136] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"136.418396ms","start":"2026-07-14T23:22:41.50948Z","end":"2026-07-14T23:22:41.645898Z","steps":["trace[1015101136] 'process raft request' (duration: 136.081306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:22:42.345455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.479958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-14T23:22:42.345528Z","caller":"traceutil/trace.go:171","msg":"trace[744541538] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:8521; }","duration":"106.575031ms","start":"2026-07-14T23:22:42.238934Z","end":"2026-07-14T23:22:42.345509Z","steps":["trace[744541538] 'range keys from in-memory index tree' (duration: 106.359494ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:23:30.202616Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242e.wal"} {"level":"info","ts":"2026-07-14T23:24:51.803978Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:24:51.809661Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-14T23:24:51.809735Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-14T23:27:14.240962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8274} {"level":"info","ts":"2026-07-14T23:27:14.316515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8274,"took":"73.112864ms","hash":986876600,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":26705920,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-14T23:27:14.316589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":986876600,"revision":8274,"compact-revision":6100} {"level":"info","ts":"2026-07-14T23:27:34.256251Z","caller":"traceutil/trace.go:171","msg":"trace[882005744] transaction","detail":"{read_only:false; response_revision:11074; number_of_response:1; }","duration":"125.514309ms","start":"2026-07-14T23:27:34.130711Z","end":"2026-07-14T23:27:34.256225Z","steps":["trace[882005744] 'process raft request' (duration: 125.351175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:27:34.541045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.555118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hpbqp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-14T23:27:34.541128Z","caller":"traceutil/trace.go:171","msg":"trace[1435366669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hpbqp; range_end:; response_count:1; response_revision:11074; }","duration":"121.691003ms","start":"2026-07-14T23:27:34.419415Z","end":"2026-07-14T23:27:34.541106Z","steps":["trace[1435366669] 'range keys from in-memory index tree' (duration: 121.377303ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:32:14.24772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10961} {"level":"info","ts":"2026-07-14T23:32:14.327806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10961,"took":"77.454721ms","hash":118232422,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-14T23:32:14.327884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":118232422,"revision":10961,"compact-revision":8274} {"level":"info","ts":"2026-07-14T23:33:17.645224Z","caller":"traceutil/trace.go:171","msg":"trace[758340243] transaction","detail":"{read_only:false; response_revision:13432; number_of_response:1; }","duration":"137.916728ms","start":"2026-07-14T23:33:17.507282Z","end":"2026-07-14T23:33:17.645199Z","steps":["trace[758340243] 'process raft request' (duration: 137.764214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:33:52.002883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.57426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-07-14T23:33:52.002991Z","caller":"traceutil/trace.go:171","msg":"trace[140705642] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13626; }","duration":"317.719234ms","start":"2026-07-14T23:33:51.685244Z","end":"2026-07-14T23:33:52.002964Z","steps":["trace[140705642] 'range keys from in-memory index tree' (duration: 317.404765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:33:52.003017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.920307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T23:33:52.003037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:33:51.685229Z","time spent":"317.797386ms","remote":"127.0.0.1:35480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1879,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"info","ts":"2026-07-14T23:33:52.003054Z","caller":"traceutil/trace.go:171","msg":"trace[351908868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13626; }","duration":"143.176024ms","start":"2026-07-14T23:33:51.859866Z","end":"2026-07-14T23:33:52.003042Z","steps":["trace[351908868] 'range keys from in-memory index tree' (duration: 142.821004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:33:52.003063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.041361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-srjvh\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-07-14T23:33:52.00316Z","caller":"traceutil/trace.go:171","msg":"trace[1056251625] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-srjvh; range_end:; response_count:1; response_revision:13626; }","duration":"252.179295ms","start":"2026-07-14T23:33:51.750958Z","end":"2026-07-14T23:33:52.003138Z","steps":["trace[1056251625] 'range keys from in-memory index tree' (duration: 251.767363ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:34:00.781822Z","caller":"traceutil/trace.go:171","msg":"trace[267681593] transaction","detail":"{read_only:false; response_revision:13792; number_of_response:1; }","duration":"137.074295ms","start":"2026-07-14T23:34:00.644719Z","end":"2026-07-14T23:34:00.781793Z","steps":["trace[267681593] 'process raft request' (duration: 136.847759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:34:01.080655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.970901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:34:01.080751Z","caller":"traceutil/trace.go:171","msg":"trace[1808568093] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:13793; }","duration":"180.126975ms","start":"2026-07-14T23:34:00.900603Z","end":"2026-07-14T23:34:01.08073Z","steps":["trace[1808568093] 'count revisions from in-memory index tree' (duration: 179.88564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:34:01.081016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.371859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61097ac5-f0ae-4181-b9e9-298f96519e19\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-07-14T23:34:01.081128Z","caller":"traceutil/trace.go:171","msg":"trace[662101988] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61097ac5-f0ae-4181-b9e9-298f96519e19; range_end:; response_count:1; response_revision:13793; }","duration":"196.549134ms","start":"2026-07-14T23:34:00.884554Z","end":"2026-07-14T23:34:01.081104Z","steps":["trace[662101988] 'range keys from in-memory index tree' (duration: 196.128242ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:35:24.960855Z","caller":"traceutil/trace.go:171","msg":"trace[1084947500] linearizableReadLoop","detail":"{readStateIndex:15303; appliedIndex:15302; }","duration":"127.044297ms","start":"2026-07-14T23:35:24.833786Z","end":"2026-07-14T23:35:24.96083Z","steps":["trace[1084947500] 'read index received' (duration: 126.900183ms)","trace[1084947500] 'applied index is now lower than readState.Index' (duration: 142.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:35:24.961093Z","caller":"traceutil/trace.go:171","msg":"trace[1648161029] transaction","detail":"{read_only:false; response_revision:14801; number_of_response:1; }","duration":"138.319862ms","start":"2026-07-14T23:35:24.822763Z","end":"2026-07-14T23:35:24.961083Z","steps":["trace[1648161029] 'process raft request' (duration: 137.929951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:35:24.961291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.484409ms","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-14T23:35:24.961325Z","caller":"traceutil/trace.go:171","msg":"trace[967839382] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14801; }","duration":"127.579682ms","start":"2026-07-14T23:35:24.833735Z","end":"2026-07-14T23:35:24.961315Z","steps":["trace[967839382] 'agreement among raft nodes before linearized reading' (duration: 127.50793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:35:24.96146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.673125ms","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-14T23:35:24.961484Z","caller":"traceutil/trace.go:171","msg":"trace[358645477] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14801; }","duration":"127.717315ms","start":"2026-07-14T23:35:24.83376Z","end":"2026-07-14T23:35:24.961477Z","steps":["trace[358645477] 'agreement among raft nodes before linearized reading' (duration: 127.679664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:35:24.961599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.589151ms","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-14T23:35:24.961623Z","caller":"traceutil/trace.go:171","msg":"trace[499833041] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14801; }","duration":"127.612692ms","start":"2026-07-14T23:35:24.834003Z","end":"2026-07-14T23:35:24.961616Z","steps":["trace[499833041] 'agreement among raft nodes before linearized reading' (duration: 127.578871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:35:24.962096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.68425ms","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-14T23:35:24.962166Z","caller":"traceutil/trace.go:171","msg":"trace[1523954865] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14801; }","duration":"119.885306ms","start":"2026-07-14T23:35:24.842262Z","end":"2026-07-14T23:35:24.962147Z","steps":["trace[1523954865] 'agreement among raft nodes before linearized reading' (duration: 119.758942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:35:25.234522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.169216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:35:25.234633Z","caller":"traceutil/trace.go:171","msg":"trace[1781780086] transaction","detail":"{read_only:false; response_revision:14802; number_of_response:1; }","duration":"268.086293ms","start":"2026-07-14T23:35:24.966528Z","end":"2026-07-14T23:35:25.234614Z","steps":["trace[1781780086] 'process raft request' (duration: 128.737932ms)","trace[1781780086] 'compare' (duration: 138.93672ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:35:25.236416Z","caller":"traceutil/trace.go:171","msg":"trace[8291211] linearizableReadLoop","detail":"{readStateIndex:15305; appliedIndex:15303; }","duration":"266.819198ms","start":"2026-07-14T23:35:24.969565Z","end":"2026-07-14T23:35:25.236384Z","steps":["trace[8291211] 'read index received' (duration: 125.712888ms)","trace[8291211] 'applied index is now lower than readState.Index' (duration: 141.1052ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:35:25.236655Z","caller":"traceutil/trace.go:171","msg":"trace[506598803] transaction","detail":"{read_only:false; response_revision:14803; number_of_response:1; }","duration":"269.989857ms","start":"2026-07-14T23:35:24.966598Z","end":"2026-07-14T23:35:25.236588Z","steps":["trace[506598803] 'process raft request' (duration: 269.616386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:35:25.236797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.203289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-14T23:35:25.236933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.199889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-14T23:35:25.23702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.983175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fhlv2\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-07-14T23:35:25.237066Z","caller":"traceutil/trace.go:171","msg":"trace[1287110054] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fhlv2; range_end:; response_count:1; response_revision:14803; }","duration":"246.056377ms","start":"2026-07-14T23:35:24.990988Z","end":"2026-07-14T23:35:25.237045Z","steps":["trace[1287110054] 'agreement among raft nodes before linearized reading' (duration: 245.977725ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:35:25.237048Z","caller":"traceutil/trace.go:171","msg":"trace[2111761576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14803; }","duration":"267.420775ms","start":"2026-07-14T23:35:24.969538Z","end":"2026-07-14T23:35:25.236959Z","steps":["trace[2111761576] 'agreement among raft nodes before linearized reading' (duration: 267.081086ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:35:25.237202Z","caller":"traceutil/trace.go:171","msg":"trace[1714626296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14803; }","duration":"247.25484ms","start":"2026-07-14T23:35:24.989709Z","end":"2026-07-14T23:35:25.236963Z","steps":["trace[1714626296] 'agreement among raft nodes before linearized reading' (duration: 247.167768ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:14.253548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13006} {"level":"info","ts":"2026-07-14T23:37:14.326432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13006,"took":"70.36515ms","hash":4107155771,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-14T23:37:14.326502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107155771,"revision":13006,"compact-revision":10961} {"level":"info","ts":"2026-07-14T23:41:18.569989Z","caller":"traceutil/trace.go:171","msg":"trace[2044171999] transaction","detail":"{read_only:false; response_revision:17593; number_of_response:1; }","duration":"145.442949ms","start":"2026-07-14T23:41:18.424513Z","end":"2026-07-14T23:41:18.569956Z","steps":["trace[2044171999] 'process raft request' (duration: 145.14787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:41:24.973421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.989231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zsz5t\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-07-14T23:41:24.973512Z","caller":"traceutil/trace.go:171","msg":"trace[364778363] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zsz5t; range_end:; response_count:1; response_revision:17664; }","duration":"142.132075ms","start":"2026-07-14T23:41:24.83136Z","end":"2026-07-14T23:41:24.973492Z","steps":["trace[364778363] 'range keys from in-memory index tree' (duration: 141.796657ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:42:14.261778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15500} {"level":"info","ts":"2026-07-14T23:42:14.361364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15500,"took":"96.209941ms","hash":1126094144,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":34127872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:42:14.361418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1126094144,"revision":15500,"compact-revision":13006} {"level":"warn","ts":"2026-07-14T23:44:34.567516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171796782229435452,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:44:34.655013Z","caller":"traceutil/trace.go:171","msg":"trace[1952088003] linearizableReadLoop","detail":"{readStateIndex:19765; appliedIndex:19764; }","duration":"588.195554ms","start":"2026-07-14T23:44:34.066783Z","end":"2026-07-14T23:44:34.654979Z","steps":["trace[1952088003] 'read index received' (duration: 587.68097ms)","trace[1952088003] 'applied index is now lower than readState.Index' (duration: 513.124µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:44:34.65513Z","caller":"traceutil/trace.go:171","msg":"trace[26560237] transaction","detail":"{read_only:false; response_revision:19122; number_of_response:1; }","duration":"590.066317ms","start":"2026-07-14T23:44:34.065033Z","end":"2026-07-14T23:44:34.6551Z","steps":["trace[26560237] 'process raft request' (duration: 589.335796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:34.655254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:34.065023Z","time spent":"590.161219ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:44:34.655323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.519893ms","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-14T23:44:34.655394Z","caller":"traceutil/trace.go:171","msg":"trace[622312162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19122; }","duration":"588.618755ms","start":"2026-07-14T23:44:34.066755Z","end":"2026-07-14T23:44:34.655374Z","steps":["trace[622312162] 'agreement among raft nodes before linearized reading' (duration: 588.370928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:34.655453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:34.066748Z","time spent":"588.688828ms","remote":"127.0.0.1:35596","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-14T23:44:34.656995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.393352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:44:34.657034Z","caller":"traceutil/trace.go:171","msg":"trace[434164590] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:19123; }","duration":"385.486806ms","start":"2026-07-14T23:44:34.271538Z","end":"2026-07-14T23:44:34.657025Z","steps":["trace[434164590] 'agreement among raft nodes before linearized reading' (duration: 385.404603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:34.657053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:34.271514Z","time spent":"385.534726ms","remote":"127.0.0.1:35766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:44:34.657042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.986805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:44:34.657126Z","caller":"traceutil/trace.go:171","msg":"trace[319799893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19123; }","duration":"285.16308ms","start":"2026-07-14T23:44:34.371941Z","end":"2026-07-14T23:44:34.657104Z","steps":["trace[319799893] 'agreement among raft nodes before linearized reading' (duration: 285.012506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:34.657505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.064999ms","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-14T23:44:34.657565Z","caller":"traceutil/trace.go:171","msg":"trace[2043402300] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19123; }","duration":"469.169002ms","start":"2026-07-14T23:44:34.18838Z","end":"2026-07-14T23:44:34.657549Z","steps":["trace[2043402300] 'agreement among raft nodes before linearized reading' (duration: 468.551394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:34.657594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:34.188355Z","time spent":"469.230564ms","remote":"127.0.0.1:35596","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-14T23:44:37.166335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171796782229435477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:44:37.66681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171796782229435477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:44:37.751695Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091474816s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T23:44:37.752132Z","caller":"traceutil/trace.go:171","msg":"trace[1545154389] transaction","detail":"{read_only:false; response_revision:19133; number_of_response:1; }","duration":"1.091993401s","start":"2026-07-14T23:44:36.66011Z","end":"2026-07-14T23:44:37.752104Z","steps":["trace[1545154389] 'process raft request' (duration: 1.091785615s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.752282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:36.660094Z","time spent":"1.092110724s","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:44:37.752641Z","caller":"traceutil/trace.go:171","msg":"trace[546867679] linearizableReadLoop","detail":"{readStateIndex:19778; appliedIndex:19775; }","duration":"1.087381301s","start":"2026-07-14T23:44:36.665241Z","end":"2026-07-14T23:44:37.752622Z","steps":["trace[546867679] 'read index received' (duration: 1.08665731s)","trace[546867679] 'applied index is now lower than readState.Index' (duration: 723.081µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:44:37.752683Z","caller":"traceutil/trace.go:171","msg":"trace[94252565] transaction","detail":"{read_only:false; response_revision:19134; number_of_response:1; }","duration":"1.09019068s","start":"2026-07-14T23:44:36.662479Z","end":"2026-07-14T23:44:37.752669Z","steps":["trace[94252565] 'process raft request' (duration: 1.089992994s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:44:37.752719Z","caller":"traceutil/trace.go:171","msg":"trace[262576359] transaction","detail":"{read_only:false; response_revision:19135; number_of_response:1; }","duration":"782.163876ms","start":"2026-07-14T23:44:36.970535Z","end":"2026-07-14T23:44:37.752699Z","steps":["trace[262576359] 'process raft request' (duration: 782.053693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.752756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:36.662459Z","time spent":"1.090258931s","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:44:37.75279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087532405s","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-14T23:44:37.752809Z","caller":"traceutil/trace.go:171","msg":"trace[1031150894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19135; }","duration":"1.087590487s","start":"2026-07-14T23:44:36.665213Z","end":"2026-07-14T23:44:37.752803Z","steps":["trace[1031150894] 'agreement among raft nodes before linearized reading' (duration: 1.087489124s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.752829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:36.665203Z","time spent":"1.087622187s","remote":"127.0.0.1:35596","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-14T23:44:37.752837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:36.970518Z","time spent":"782.253639ms","remote":"127.0.0.1:35596","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-14T23:44:37.752911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.001282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-07-14T23:44:37.752924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085608791s","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-14T23:44:37.752954Z","caller":"traceutil/trace.go:171","msg":"trace[1913455155] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:19135; }","duration":"510.076614ms","start":"2026-07-14T23:44:37.242865Z","end":"2026-07-14T23:44:37.752942Z","steps":["trace[1913455155] 'agreement among raft nodes before linearized reading' (duration: 509.973441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.75293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.824466ms","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-14T23:44:37.753004Z","caller":"traceutil/trace.go:171","msg":"trace[1832982883] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19135; }","duration":"1.085707494s","start":"2026-07-14T23:44:36.667274Z","end":"2026-07-14T23:44:37.752982Z","steps":["trace[1832982883] 'agreement among raft nodes before linearized reading' (duration: 1.085526259s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.752996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.723407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:44:37.753015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.878508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-14T23:44:37.753065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:36.667265Z","time spent":"1.085787986s","remote":"127.0.0.1:35596","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-14T23:44:37.753079Z","caller":"traceutil/trace.go:171","msg":"trace[1137404033] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19135; }","duration":"933.986511ms","start":"2026-07-14T23:44:36.81908Z","end":"2026-07-14T23:44:37.753067Z","steps":["trace[1137404033] 'agreement among raft nodes before linearized reading' (duration: 933.826837ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:44:37.753083Z","caller":"traceutil/trace.go:171","msg":"trace[209814624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19135; }","duration":"382.875522ms","start":"2026-07-14T23:44:37.370187Z","end":"2026-07-14T23:44:37.753063Z","steps":["trace[209814624] 'agreement among raft nodes before linearized reading' (duration: 382.700427ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:44:37.753079Z","caller":"traceutil/trace.go:171","msg":"trace[1713029029] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19135; }","duration":"243.953279ms","start":"2026-07-14T23:44:37.509082Z","end":"2026-07-14T23:44:37.753035Z","steps":["trace[1713029029] 'agreement among raft nodes before linearized reading' (duration: 243.777324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.753114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:36.819063Z","time spent":"934.044043ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-14T23:44:37.753129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:37.370159Z","time spent":"382.962924ms","remote":"127.0.0.1:35324","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-14T23:44:37.753015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.896429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-14T23:44:37.752993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:37.242845Z","time spent":"510.142056ms","remote":"127.0.0.1:35346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.73\" "} {"level":"info","ts":"2026-07-14T23:44:37.753226Z","caller":"traceutil/trace.go:171","msg":"trace[408161688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19135; }","duration":"472.151106ms","start":"2026-07-14T23:44:37.281055Z","end":"2026-07-14T23:44:37.753207Z","steps":["trace[408161688] 'agreement among raft nodes before linearized reading' (duration: 471.787696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:37.753954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:37.281033Z","time spent":"472.382503ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:44:38.521644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171796782229435499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:44:39.02264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171796782229435499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:44:39.242738Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.225243073s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T23:44:39.243223Z","caller":"traceutil/trace.go:171","msg":"trace[924871655] transaction","detail":"{read_only:false; response_revision:19140; number_of_response:1; }","duration":"1.225780098s","start":"2026-07-14T23:44:38.017414Z","end":"2026-07-14T23:44:39.243194Z","steps":["trace[924871655] 'process raft request' (duration: 1.225525561s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.243479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.017397Z","time spent":"1.22587612s","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:44:39.243755Z","caller":"traceutil/trace.go:171","msg":"trace[1250697284] linearizableReadLoop","detail":"{readStateIndex:19784; appliedIndex:19784; }","duration":"1.222925878s","start":"2026-07-14T23:44:38.020814Z","end":"2026-07-14T23:44:39.243739Z","steps":["trace[1250697284] 'read index received' (duration: 1.222912607s)","trace[1250697284] 'applied index is now lower than readState.Index' (duration: 11.98µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:44:39.244053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223212185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-14T23:44:39.244137Z","caller":"traceutil/trace.go:171","msg":"trace[711643151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19140; }","duration":"1.223342109s","start":"2026-07-14T23:44:38.020776Z","end":"2026-07-14T23:44:39.244118Z","steps":["trace[711643151] 'agreement among raft nodes before linearized reading' (duration: 1.223066701s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.244194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.020758Z","time spent":"1.223418642s","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:44:39.244183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164937404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-14T23:44:39.244292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171059657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:44:39.244366Z","caller":"traceutil/trace.go:171","msg":"trace[220782886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19140; }","duration":"1.165161261s","start":"2026-07-14T23:44:38.079184Z","end":"2026-07-14T23:44:39.244345Z","steps":["trace[220782886] 'agreement among raft nodes before linearized reading' (duration: 1.164855872s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.244417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.079166Z","time spent":"1.165240063s","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T23:44:39.24418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148189403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-14T23:44:39.2446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.510721ms","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":"warn","ts":"2026-07-14T23:44:39.244636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.825286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-8569c4cb6f-g6k9x.18c24c9f06aa383c\" ","response":"range_response_count:1 size:907"} {"level":"info","ts":"2026-07-14T23:44:39.244667Z","caller":"traceutil/trace.go:171","msg":"trace[903593196] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:19140; }","duration":"534.601264ms","start":"2026-07-14T23:44:38.71004Z","end":"2026-07-14T23:44:39.244641Z","steps":["trace[903593196] 'agreement among raft nodes before linearized reading' (duration: 533.967616ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:44:39.244707Z","caller":"traceutil/trace.go:171","msg":"trace[373109060] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-8569c4cb6f-g6k9x.18c24c9f06aa383c; range_end:; response_count:1; response_revision:19140; }","duration":"955.95861ms","start":"2026-07-14T23:44:38.288725Z","end":"2026-07-14T23:44:39.244683Z","steps":["trace[373109060] 'agreement among raft nodes before linearized reading' (duration: 955.295091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.244718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.710022Z","time spent":"534.686097ms","remote":"127.0.0.1:35480","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-14T23:44:39.244765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.288707Z","time spent":"956.045262ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":929,"request content":"key:\"/registry/events/openstack/heat-cfn-8569c4cb6f-g6k9x.18c24c9f06aa383c\" "} {"level":"info","ts":"2026-07-14T23:44:39.244362Z","caller":"traceutil/trace.go:171","msg":"trace[1346979558] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:19140; }","duration":"1.171305704s","start":"2026-07-14T23:44:38.073043Z","end":"2026-07-14T23:44:39.244349Z","steps":["trace[1346979558] 'agreement among raft nodes before linearized reading' (duration: 1.170882862s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.245068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.087499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:44:39.245307Z","caller":"traceutil/trace.go:171","msg":"trace[1628465826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19140; }","duration":"871.782529ms","start":"2026-07-14T23:44:38.373501Z","end":"2026-07-14T23:44:39.245283Z","steps":["trace[1628465826] 'agreement among raft nodes before linearized reading' (duration: 870.483942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.245138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.073024Z","time spent":"1.171966063s","remote":"127.0.0.1:35672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":157,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:44:39.245643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.37348Z","time spent":"872.052547ms","remote":"127.0.0.1:35324","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-14T23:44:39.244899Z","caller":"traceutil/trace.go:171","msg":"trace[1699302912] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:19140; }","duration":"1.148570903s","start":"2026-07-14T23:44:38.095948Z","end":"2026-07-14T23:44:39.244519Z","steps":["trace[1699302912] 'agreement among raft nodes before linearized reading' (duration: 1.148057789s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:44:39.246116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:44:38.095927Z","time spent":"1.150131998s","remote":"127.0.0.1:35512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":78,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:44:59.871485Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:44:59.876081Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-14T23:44:59.876172Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-14T23:46:03.637583Z","caller":"traceutil/trace.go:171","msg":"trace[532571707] transaction","detail":"{read_only:false; response_revision:19730; number_of_response:1; }","duration":"111.51381ms","start":"2026-07-14T23:46:03.526046Z","end":"2026-07-14T23:46:03.63756Z","steps":["trace[532571707] 'process raft request' (duration: 111.388617ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:03.83777Z","caller":"traceutil/trace.go:171","msg":"trace[1785355940] transaction","detail":"{read_only:false; response_revision:19732; number_of_response:1; }","duration":"195.334541ms","start":"2026-07-14T23:46:03.64241Z","end":"2026-07-14T23:46:03.837744Z","steps":["trace[1785355940] 'process raft request' (duration: 195.28437ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:03.837828Z","caller":"traceutil/trace.go:171","msg":"trace[1455839139] transaction","detail":"{read_only:false; response_revision:19731; number_of_response:1; }","duration":"195.397653ms","start":"2026-07-14T23:46:03.642404Z","end":"2026-07-14T23:46:03.837802Z","steps":["trace[1455839139] 'process raft request' (duration: 195.146866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:04.391056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.000921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:04.391166Z","caller":"traceutil/trace.go:171","msg":"trace[205221804] linearizableReadLoop","detail":"{readStateIndex:20397; appliedIndex:20396; }","duration":"208.943044ms","start":"2026-07-14T23:46:04.182205Z","end":"2026-07-14T23:46:04.391148Z","steps":["trace[205221804] 'read index received' (duration: 78.707397ms)","trace[205221804] 'applied index is now lower than readState.Index' (duration: 130.234057ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:04.391265Z","caller":"traceutil/trace.go:171","msg":"trace[1982393255] transaction","detail":"{read_only:false; response_revision:19734; number_of_response:1; }","duration":"305.637167ms","start":"2026-07-14T23:46:04.085617Z","end":"2026-07-14T23:46:04.391254Z","steps":["trace[1982393255] 'process raft request' (duration: 175.349018ms)","trace[1982393255] 'compare' (duration: 129.856167ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:04.391323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:04.085582Z","time spent":"305.710729ms","remote":"127.0.0.1:35596","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-14T23:46:04.39144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.232572ms","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-14T23:46:04.391544Z","caller":"traceutil/trace.go:171","msg":"trace[55119413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19734; }","duration":"209.368636ms","start":"2026-07-14T23:46:04.182156Z","end":"2026-07-14T23:46:04.391525Z","steps":["trace[55119413] 'agreement among raft nodes before linearized reading' (duration: 209.194291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:04.391532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.941913ms","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-14T23:46:04.391612Z","caller":"traceutil/trace.go:171","msg":"trace[280376540] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19734; }","duration":"193.050176ms","start":"2026-07-14T23:46:04.198539Z","end":"2026-07-14T23:46:04.39159Z","steps":["trace[280376540] 'agreement among raft nodes before linearized reading' (duration: 192.805309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:04.391437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.733967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T23:46:04.391675Z","caller":"traceutil/trace.go:171","msg":"trace[1377526896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19734; }","duration":"208.997375ms","start":"2026-07-14T23:46:04.182665Z","end":"2026-07-14T23:46:04.391662Z","steps":["trace[1377526896] 'agreement among raft nodes before linearized reading' (duration: 208.627455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:04.391658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.737945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-14T23:46:04.39176Z","caller":"traceutil/trace.go:171","msg":"trace[1696458502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19734; }","duration":"204.874308ms","start":"2026-07-14T23:46:04.186861Z","end":"2026-07-14T23:46:04.391735Z","steps":["trace[1696458502] 'agreement among raft nodes before linearized reading' (duration: 204.551639ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:04.644097Z","caller":"traceutil/trace.go:171","msg":"trace[26236403] linearizableReadLoop","detail":"{readStateIndex:20401; appliedIndex:20398; }","duration":"153.229825ms","start":"2026-07-14T23:46:04.490843Z","end":"2026-07-14T23:46:04.644073Z","steps":["trace[26236403] 'read index received' (duration: 142.349779ms)","trace[26236403] 'applied index is now lower than readState.Index' (duration: 10.878966ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:04.644159Z","caller":"traceutil/trace.go:171","msg":"trace[1864259798] transaction","detail":"{read_only:false; response_revision:19738; number_of_response:1; }","duration":"247.088378ms","start":"2026-07-14T23:46:04.397031Z","end":"2026-07-14T23:46:04.644119Z","steps":["trace[1864259798] 'process raft request' (duration: 246.998486ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:04.64416Z","caller":"traceutil/trace.go:171","msg":"trace[2012722398] transaction","detail":"{read_only:false; response_revision:19737; number_of_response:1; }","duration":"247.680294ms","start":"2026-07-14T23:46:04.396434Z","end":"2026-07-14T23:46:04.644115Z","steps":["trace[2012722398] 'process raft request' (duration: 247.554621ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:04.644497Z","caller":"traceutil/trace.go:171","msg":"trace[561689360] transaction","detail":"{read_only:false; response_revision:19736; number_of_response:1; }","duration":"248.157608ms","start":"2026-07-14T23:46:04.396272Z","end":"2026-07-14T23:46:04.644429Z","steps":["trace[561689360] 'process raft request' (duration: 236.887681ms)","trace[561689360] 'compare' (duration: 10.691231ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:04.644666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.786891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T23:46:04.644828Z","caller":"traceutil/trace.go:171","msg":"trace[2077559944] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19738; }","duration":"153.974806ms","start":"2026-07-14T23:46:04.490834Z","end":"2026-07-14T23:46:04.644809Z","steps":["trace[2077559944] 'agreement among raft nodes before linearized reading' (duration: 153.618026ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:14.269809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18130} {"level":"info","ts":"2026-07-14T23:47:14.383405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18130,"took":"109.784211ms","hash":798982292,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-14T23:47:14.383486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":798982292,"revision":18130,"compact-revision":15500} {"level":"info","ts":"2026-07-14T23:49:47.587552Z","caller":"traceutil/trace.go:171","msg":"trace[660868907] linearizableReadLoop","detail":"{readStateIndex:22824; appliedIndex:22823; }","duration":"216.399494ms","start":"2026-07-14T23:49:47.371128Z","end":"2026-07-14T23:49:47.587527Z","steps":["trace[660868907] 'read index received' (duration: 206.497857ms)","trace[660868907] 'applied index is now lower than readState.Index' (duration: 9.900527ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:49:47.587707Z","caller":"traceutil/trace.go:171","msg":"trace[755314658] transaction","detail":"{read_only:false; response_revision:22086; number_of_response:1; }","duration":"232.991838ms","start":"2026-07-14T23:49:47.354688Z","end":"2026-07-14T23:49:47.58768Z","steps":["trace[755314658] 'process raft request' (duration: 222.993928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:49:47.588057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.910428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:49:47.58811Z","caller":"traceutil/trace.go:171","msg":"trace[1444038115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22086; }","duration":"216.97665ms","start":"2026-07-14T23:49:47.37112Z","end":"2026-07-14T23:49:47.588096Z","steps":["trace[1444038115] 'agreement among raft nodes before linearized reading' (duration: 216.555898ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:14.276047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20380} {"level":"info","ts":"2026-07-14T23:52:14.400558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20380,"took":"116.153198ms","hash":4088784338,"current-db-size-bytes":58728448,"current-db-size":"59 MB","current-db-size-in-use-bytes":44408832,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-14T23:52:14.400628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4088784338,"revision":20380,"compact-revision":18130} {"level":"info","ts":"2026-07-14T23:53:07.701045Z","caller":"traceutil/trace.go:171","msg":"trace[1953348537] linearizableReadLoop","detail":"{readStateIndex:24934; appliedIndex:24931; }","duration":"331.737509ms","start":"2026-07-14T23:53:07.369287Z","end":"2026-07-14T23:53:07.701024Z","steps":["trace[1953348537] 'read index received' (duration: 167.534431ms)","trace[1953348537] 'applied index is now lower than readState.Index' (duration: 164.202198ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:07.701296Z","caller":"traceutil/trace.go:171","msg":"trace[1572528928] transaction","detail":"{read_only:false; response_revision:24147; number_of_response:1; }","duration":"163.57201ms","start":"2026-07-14T23:53:07.537702Z","end":"2026-07-14T23:53:07.701274Z","steps":["trace[1572528928] 'process raft request' (duration: 163.456226ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:07.701344Z","caller":"traceutil/trace.go:171","msg":"trace[449789927] transaction","detail":"{read_only:false; response_revision:24143; number_of_response:1; }","duration":"409.483115ms","start":"2026-07-14T23:53:07.291845Z","end":"2026-07-14T23:53:07.701328Z","steps":["trace[449789927] 'process raft request' (duration: 363.802636ms)","trace[449789927] 'compare' (duration: 44.62462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:07.701449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:07.29182Z","time spent":"409.561608ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:53:07.701629Z","caller":"traceutil/trace.go:171","msg":"trace[651971168] transaction","detail":"{read_only:false; response_revision:24144; number_of_response:1; }","duration":"407.699035ms","start":"2026-07-14T23:53:07.29392Z","end":"2026-07-14T23:53:07.701619Z","steps":["trace[651971168] 'process raft request' (duration: 406.959625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:07.701716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:07.293911Z","time spent":"407.753347ms","remote":"127.0.0.1:35596","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-14T23:53:07.701756Z","caller":"traceutil/trace.go:171","msg":"trace[1846115045] transaction","detail":"{read_only:false; response_revision:24145; number_of_response:1; }","duration":"280.34518ms","start":"2026-07-14T23:53:07.4214Z","end":"2026-07-14T23:53:07.701745Z","steps":["trace[1846115045] 'process raft request' (duration: 279.545287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:07.701713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.412697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:07.701906Z","caller":"traceutil/trace.go:171","msg":"trace[2015143047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24147; }","duration":"332.652784ms","start":"2026-07-14T23:53:07.369239Z","end":"2026-07-14T23:53:07.701891Z","steps":["trace[2015143047] 'agreement among raft nodes before linearized reading' (duration: 332.404847ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:07.701941Z","caller":"traceutil/trace.go:171","msg":"trace[1076807734] transaction","detail":"{read_only:false; response_revision:24146; number_of_response:1; }","duration":"211.258365ms","start":"2026-07-14T23:53:07.490673Z","end":"2026-07-14T23:53:07.701931Z","steps":["trace[1076807734] 'process raft request' (duration: 210.308528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:07.701948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:07.369204Z","time spent":"332.734716ms","remote":"127.0.0.1:35312","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-14T23:53:07.986872Z","caller":"traceutil/trace.go:171","msg":"trace[1050575729] linearizableReadLoop","detail":"{readStateIndex:24939; appliedIndex:24937; }","duration":"285.701649ms","start":"2026-07-14T23:53:07.70115Z","end":"2026-07-14T23:53:07.986851Z","steps":["trace[1050575729] 'read index received' (duration: 217.16214ms)","trace[1050575729] 'applied index is now lower than readState.Index' (duration: 68.538619ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:07.986912Z","caller":"traceutil/trace.go:171","msg":"trace[459707415] transaction","detail":"{read_only:false; response_revision:24149; number_of_response:1; }","duration":"295.163154ms","start":"2026-07-14T23:53:07.691724Z","end":"2026-07-14T23:53:07.986888Z","steps":["trace[459707415] 'process raft request' (duration: 295.055841ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:07.986914Z","caller":"traceutil/trace.go:171","msg":"trace[1373973327] transaction","detail":"{read_only:false; response_revision:24148; number_of_response:1; }","duration":"298.57945ms","start":"2026-07-14T23:53:07.688295Z","end":"2026-07-14T23:53:07.986875Z","steps":["trace[1373973327] 'process raft request' (duration: 230.01501ms)","trace[1373973327] 'compare' (duration: 68.21427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:07.987004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.136805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:07.987032Z","caller":"traceutil/trace.go:171","msg":"trace[420358246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24149; }","duration":"549.181486ms","start":"2026-07-14T23:53:07.437841Z","end":"2026-07-14T23:53:07.987023Z","steps":["trace[420358246] 'agreement among raft nodes before linearized reading' (duration: 549.117074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:07.987057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:07.437827Z","time spent":"549.223768ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:53:07.98718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.363276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-14T23:53:07.987216Z","caller":"traceutil/trace.go:171","msg":"trace[1589094385] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24149; }","duration":"282.449198ms","start":"2026-07-14T23:53:07.704757Z","end":"2026-07-14T23:53:07.987206Z","steps":["trace[1589094385] 'agreement among raft nodes before linearized reading' (duration: 282.305634ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:08.123646Z","caller":"traceutil/trace.go:171","msg":"trace[1812356577] transaction","detail":"{read_only:false; response_revision:24150; number_of_response:1; }","duration":"132.568871ms","start":"2026-07-14T23:53:07.991049Z","end":"2026-07-14T23:53:08.123618Z","steps":["trace[1812356577] 'process raft request' (duration: 116.907992ms)","trace[1812356577] 'compare' (duration: 15.475894ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:08.123667Z","caller":"traceutil/trace.go:171","msg":"trace[91662404] transaction","detail":"{read_only:false; response_revision:24152; number_of_response:1; }","duration":"128.742693ms","start":"2026-07-14T23:53:07.994898Z","end":"2026-07-14T23:53:08.123641Z","steps":["trace[91662404] 'process raft request' (duration: 128.700942ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:08.123718Z","caller":"traceutil/trace.go:171","msg":"trace[1692807912] linearizableReadLoop","detail":"{readStateIndex:24940; appliedIndex:24939; }","duration":"132.620772ms","start":"2026-07-14T23:53:07.991079Z","end":"2026-07-14T23:53:08.1237Z","steps":["trace[1692807912] 'read index received' (duration: 117.024955ms)","trace[1692807912] 'applied index is now lower than readState.Index' (duration: 15.593817ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:08.123838Z","caller":"traceutil/trace.go:171","msg":"trace[548534283] transaction","detail":"{read_only:false; response_revision:24151; number_of_response:1; }","duration":"131.978525ms","start":"2026-07-14T23:53:07.991848Z","end":"2026-07-14T23:53:08.123826Z","steps":["trace[548534283] 'process raft request' (duration: 131.705707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:08.123906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.814727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-14T23:53:08.123941Z","caller":"traceutil/trace.go:171","msg":"trace[83320595] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24152; }","duration":"132.87714ms","start":"2026-07-14T23:53:07.991054Z","end":"2026-07-14T23:53:08.123931Z","steps":["trace[83320595] 'agreement among raft nodes before linearized reading' (duration: 132.741596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:12.453158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.403016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:12.453265Z","caller":"traceutil/trace.go:171","msg":"trace[615130308] linearizableReadLoop","detail":"{readStateIndex:24980; appliedIndex:24979; }","duration":"108.603801ms","start":"2026-07-14T23:53:12.344645Z","end":"2026-07-14T23:53:12.453249Z","steps":["trace[615130308] 'read index received' (duration: 43.891µs)","trace[615130308] 'applied index is now lower than readState.Index' (duration: 108.55868ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:12.45335Z","caller":"traceutil/trace.go:171","msg":"trace[362460320] transaction","detail":"{read_only:false; response_revision:24190; number_of_response:1; }","duration":"230.244756ms","start":"2026-07-14T23:53:12.223093Z","end":"2026-07-14T23:53:12.453338Z","steps":["trace[362460320] 'process raft request' (duration: 121.583544ms)","trace[362460320] 'compare' (duration: 108.292722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:12.454362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.013683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:12.454499Z","caller":"traceutil/trace.go:171","msg":"trace[2000333408] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24190; }","duration":"109.847096ms","start":"2026-07-14T23:53:12.344601Z","end":"2026-07-14T23:53:12.454448Z","steps":["trace[2000333408] 'agreement among raft nodes before linearized reading' (duration: 108.92559ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:12.827942Z","caller":"traceutil/trace.go:171","msg":"trace[1528247398] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"137.779928ms","start":"2026-07-14T23:53:12.690135Z","end":"2026-07-14T23:53:12.827915Z","steps":["trace[1528247398] 'process raft request' (duration: 137.026086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:13.080192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.894735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:13.080376Z","caller":"traceutil/trace.go:171","msg":"trace[1571877917] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"248.318122ms","start":"2026-07-14T23:53:12.83202Z","end":"2026-07-14T23:53:13.080338Z","steps":["trace[1571877917] 'process raft request' (duration: 147.206511ms)","trace[1571877917] 'compare' (duration: 100.762041ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:13.080546Z","caller":"traceutil/trace.go:171","msg":"trace[29388907] transaction","detail":"{read_only:false; response_revision:24194; number_of_response:1; }","duration":"247.735606ms","start":"2026-07-14T23:53:12.832783Z","end":"2026-07-14T23:53:13.080519Z","steps":["trace[29388907] 'process raft request' (duration: 247.53456ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:13.080376Z","caller":"traceutil/trace.go:171","msg":"trace[1453875850] transaction","detail":"{read_only:false; response_revision:24193; number_of_response:1; }","duration":"247.656084ms","start":"2026-07-14T23:53:12.832696Z","end":"2026-07-14T23:53:13.080352Z","steps":["trace[1453875850] 'process raft request' (duration: 247.572002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:13.371428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.688573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T23:53:13.371535Z","caller":"traceutil/trace.go:171","msg":"trace[1967063675] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24194; }","duration":"168.823517ms","start":"2026-07-14T23:53:13.202668Z","end":"2026-07-14T23:53:13.371491Z","steps":["trace[1967063675] 'range keys from in-memory index tree' (duration: 168.479488ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:13.69138Z","caller":"traceutil/trace.go:171","msg":"trace[1264185638] transaction","detail":"{read_only:false; response_revision:24195; number_of_response:1; }","duration":"157.603932ms","start":"2026-07-14T23:53:13.533743Z","end":"2026-07-14T23:53:13.691347Z","steps":["trace[1264185638] 'process raft request' (duration: 157.101298ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:14.008613Z","caller":"traceutil/trace.go:171","msg":"trace[1893135855] transaction","detail":"{read_only:false; response_revision:24196; number_of_response:1; }","duration":"285.283328ms","start":"2026-07-14T23:53:13.723305Z","end":"2026-07-14T23:53:14.008589Z","steps":["trace[1893135855] 'process raft request' (duration: 285.120963ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:14.027835Z","caller":"traceutil/trace.go:171","msg":"trace[337350012] transaction","detail":"{read_only:false; response_revision:24199; number_of_response:1; }","duration":"301.850701ms","start":"2026-07-14T23:53:13.72596Z","end":"2026-07-14T23:53:14.027811Z","steps":["trace[337350012] 'process raft request' (duration: 301.786419ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:14.027854Z","caller":"traceutil/trace.go:171","msg":"trace[543607611] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"302.363806ms","start":"2026-07-14T23:53:13.725462Z","end":"2026-07-14T23:53:14.027826Z","steps":["trace[543607611] 'process raft request' (duration: 302.242082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:14.027987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:13.725953Z","time spent":"301.949955ms","remote":"127.0.0.1:35596","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-14T23:53:14.028003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:13.725456Z","time spent":"302.468739ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:53:14.02816Z","caller":"traceutil/trace.go:171","msg":"trace[500011888] transaction","detail":"{read_only:false; response_revision:24197; number_of_response:1; }","duration":"304.028962ms","start":"2026-07-14T23:53:13.724113Z","end":"2026-07-14T23:53:14.028142Z","steps":["trace[500011888] 'process raft request' (duration: 303.479527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:14.028282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:13.724095Z","time spent":"304.146706ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:53:14.440629Z","caller":"traceutil/trace.go:171","msg":"trace[1379399439] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"217.429008ms","start":"2026-07-14T23:53:14.223181Z","end":"2026-07-14T23:53:14.44061Z","steps":["trace[1379399439] 'process raft request' (duration: 217.004406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:14.440882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.879092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-14T23:53:14.440964Z","caller":"traceutil/trace.go:171","msg":"trace[1028881912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24202; }","duration":"187.013246ms","start":"2026-07-14T23:53:14.253932Z","end":"2026-07-14T23:53:14.440945Z","steps":["trace[1028881912] 'agreement among raft nodes before linearized reading' (duration: 186.730578ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:14.440527Z","caller":"traceutil/trace.go:171","msg":"trace[1541916243] linearizableReadLoop","detail":"{readStateIndex:24993; appliedIndex:24992; }","duration":"186.489552ms","start":"2026-07-14T23:53:14.253981Z","end":"2026-07-14T23:53:14.44047Z","steps":["trace[1541916243] 'read index received' (duration: 186.129102ms)","trace[1541916243] 'applied index is now lower than readState.Index' (duration: 357.16µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:14.772334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.929404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:14.772505Z","caller":"traceutil/trace.go:171","msg":"trace[219567425] transaction","detail":"{read_only:false; response_revision:24204; number_of_response:1; }","duration":"299.523316ms","start":"2026-07-14T23:53:14.472932Z","end":"2026-07-14T23:53:14.772456Z","steps":["trace[219567425] 'process raft request' (duration: 52.363016ms)","trace[219567425] 'compare' (duration: 246.760928ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:15.062733Z","caller":"traceutil/trace.go:171","msg":"trace[35669719] linearizableReadLoop","detail":"{readStateIndex:24996; appliedIndex:24995; }","duration":"193.675482ms","start":"2026-07-14T23:53:14.869037Z","end":"2026-07-14T23:53:15.062713Z","steps":["trace[35669719] 'read index received' (duration: 161.605575ms)","trace[35669719] 'applied index is now lower than readState.Index' (duration: 32.068907ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:15.062831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.778265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:15.062856Z","caller":"traceutil/trace.go:171","msg":"trace[242490072] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24205; }","duration":"193.819366ms","start":"2026-07-14T23:53:14.869028Z","end":"2026-07-14T23:53:15.062847Z","steps":["trace[242490072] 'agreement among raft nodes before linearized reading' (duration: 193.757835ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:15.063026Z","caller":"traceutil/trace.go:171","msg":"trace[490478570] transaction","detail":"{read_only:false; response_revision:24205; number_of_response:1; }","duration":"226.612425ms","start":"2026-07-14T23:53:14.836378Z","end":"2026-07-14T23:53:15.06299Z","steps":["trace[490478570] 'process raft request' (duration: 194.191958ms)","trace[490478570] 'compare' (duration: 31.917123ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:15.207794Z","caller":"traceutil/trace.go:171","msg":"trace[803569584] transaction","detail":"{read_only:false; response_revision:24206; number_of_response:1; }","duration":"120.441791ms","start":"2026-07-14T23:53:15.087326Z","end":"2026-07-14T23:53:15.207768Z","steps":["trace[803569584] 'process raft request' (duration: 118.133976ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:15.433984Z","caller":"traceutil/trace.go:171","msg":"trace[1282349349] transaction","detail":"{read_only:false; response_revision:24207; number_of_response:1; }","duration":"346.12003ms","start":"2026-07-14T23:53:15.087844Z","end":"2026-07-14T23:53:15.433964Z","steps":["trace[1282349349] 'process raft request' (duration: 290.948285ms)","trace[1282349349] 'compare' (duration: 54.816615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:15.434159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:15.087829Z","time spent":"346.228913ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:53:15.434261Z","caller":"traceutil/trace.go:171","msg":"trace[74692513] linearizableReadLoop","detail":"{readStateIndex:24999; appliedIndex:24996; }","duration":"342.54511ms","start":"2026-07-14T23:53:15.091692Z","end":"2026-07-14T23:53:15.434237Z","steps":["trace[74692513] 'read index received' (duration: 113.780715ms)","trace[74692513] 'applied index is now lower than readState.Index' (duration: 228.763505ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:15.434281Z","caller":"traceutil/trace.go:171","msg":"trace[2085146494] transaction","detail":"{read_only:false; response_revision:24208; number_of_response:1; }","duration":"345.576555ms","start":"2026-07-14T23:53:15.088684Z","end":"2026-07-14T23:53:15.434261Z","steps":["trace[2085146494] 'process raft request' (duration: 345.209424ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:15.434328Z","caller":"traceutil/trace.go:171","msg":"trace[1581441354] transaction","detail":"{read_only:false; response_revision:24209; number_of_response:1; }","duration":"271.46209ms","start":"2026-07-14T23:53:15.162839Z","end":"2026-07-14T23:53:15.434301Z","steps":["trace[1581441354] 'process raft request' (duration: 271.302105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:15.434429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.709914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:15.434467Z","caller":"traceutil/trace.go:171","msg":"trace[478403857] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24209; }","duration":"342.790287ms","start":"2026-07-14T23:53:15.091667Z","end":"2026-07-14T23:53:15.434457Z","steps":["trace[478403857] 'agreement among raft nodes before linearized reading' (duration: 342.691984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:15.434411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:15.088674Z","time spent":"345.665147ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:53:15.434515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:15.091656Z","time spent":"342.850298ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:53:15.434564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.506637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:53:15.43462Z","caller":"traceutil/trace.go:171","msg":"trace[942816911] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24209; }","duration":"298.61842ms","start":"2026-07-14T23:53:15.135992Z","end":"2026-07-14T23:53:15.43461Z","steps":["trace[942816911] 'agreement among raft nodes before linearized reading' (duration: 298.393354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:15.765291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.784275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:15.765383Z","caller":"traceutil/trace.go:171","msg":"trace[676368247] transaction","detail":"{read_only:false; response_revision:24210; number_of_response:1; }","duration":"198.681023ms","start":"2026-07-14T23:53:15.566686Z","end":"2026-07-14T23:53:15.765367Z","steps":["trace[676368247] 'process raft request' (duration: 59.733263ms)","trace[676368247] 'compare' (duration: 138.538768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:16.0861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.300326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:53:16.08618Z","caller":"traceutil/trace.go:171","msg":"trace[2018157675] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24210; }","duration":"181.43138ms","start":"2026-07-14T23:53:15.904716Z","end":"2026-07-14T23:53:16.086148Z","steps":["trace[2018157675] 'count revisions from in-memory index tree' (duration: 181.220453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:28.37846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.049586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:28.378721Z","caller":"traceutil/trace.go:171","msg":"trace[2069906849] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"236.969355ms","start":"2026-07-14T23:53:28.141735Z","end":"2026-07-14T23:53:28.378704Z","steps":["trace[2069906849] 'process raft request' (duration: 236.894403ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:28.37878Z","caller":"traceutil/trace.go:171","msg":"trace[1273979405] transaction","detail":"{read_only:false; response_revision:24360; number_of_response:1; }","duration":"239.509336ms","start":"2026-07-14T23:53:28.139241Z","end":"2026-07-14T23:53:28.37875Z","steps":["trace[1273979405] 'process raft request' (duration: 239.30755ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:28.378869Z","caller":"traceutil/trace.go:171","msg":"trace[1922510925] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"238.94375ms","start":"2026-07-14T23:53:28.139915Z","end":"2026-07-14T23:53:28.378859Z","steps":["trace[1922510925] 'process raft request' (duration: 238.682373ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:28.378862Z","caller":"traceutil/trace.go:171","msg":"trace[1676597783] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"241.903583ms","start":"2026-07-14T23:53:28.136913Z","end":"2026-07-14T23:53:28.378816Z","steps":["trace[1676597783] 'process raft request' (duration: 113.422756ms)","trace[1676597783] 'compare' (duration: 127.958462ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:28.379006Z","caller":"traceutil/trace.go:171","msg":"trace[2089418534] linearizableReadLoop","detail":"{readStateIndex:25157; appliedIndex:25152; }","duration":"203.516458ms","start":"2026-07-14T23:53:28.175478Z","end":"2026-07-14T23:53:28.378995Z","steps":["trace[2089418534] 'read index received' (duration: 74.772453ms)","trace[2089418534] 'applied index is now lower than readState.Index' (duration: 128.742565ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:28.379072Z","caller":"traceutil/trace.go:171","msg":"trace[510189243] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"236.402639ms","start":"2026-07-14T23:53:28.142659Z","end":"2026-07-14T23:53:28.379061Z","steps":["trace[510189243] 'process raft request' (duration: 236.012998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:28.379279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.774256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-14T23:53:28.379368Z","caller":"traceutil/trace.go:171","msg":"trace[784383630] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3; range_end:; response_count:1; response_revision:24363; }","duration":"203.91158ms","start":"2026-07-14T23:53:28.175436Z","end":"2026-07-14T23:53:28.379348Z","steps":["trace[784383630] 'agreement among raft nodes before linearized reading' (duration: 203.650302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:28.67943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.611915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:28.679521Z","caller":"traceutil/trace.go:171","msg":"trace[860903414] linearizableReadLoop","detail":"{readStateIndex:25158; appliedIndex:25157; }","duration":"139.588658ms","start":"2026-07-14T23:53:28.539919Z","end":"2026-07-14T23:53:28.679508Z","steps":["trace[860903414] 'read index received' (duration: 58.851µs)","trace[860903414] 'applied index is now lower than readState.Index' (duration: 139.528687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:28.679691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.779114ms","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-14T23:53:28.679725Z","caller":"traceutil/trace.go:171","msg":"trace[985308710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24364; }","duration":"139.845576ms","start":"2026-07-14T23:53:28.539871Z","end":"2026-07-14T23:53:28.679717Z","steps":["trace[985308710] 'agreement among raft nodes before linearized reading' (duration: 139.705332ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:28.679584Z","caller":"traceutil/trace.go:171","msg":"trace[2108688661] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"188.755064ms","start":"2026-07-14T23:53:28.490802Z","end":"2026-07-14T23:53:28.679557Z","steps":["trace[2108688661] 'process raft request' (duration: 41.939543ms)","trace[2108688661] 'compare' (duration: 146.447801ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:28.9313Z","caller":"traceutil/trace.go:171","msg":"trace[2144266090] linearizableReadLoop","detail":"{readStateIndex:25159; appliedIndex:25158; }","duration":"247.192431ms","start":"2026-07-14T23:53:28.684087Z","end":"2026-07-14T23:53:28.93128Z","steps":["trace[2144266090] 'read index received' (duration: 214.387983ms)","trace[2144266090] 'applied index is now lower than readState.Index' (duration: 32.803668ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:28.931381Z","caller":"traceutil/trace.go:171","msg":"trace[542364590] transaction","detail":"{read_only:false; response_revision:24365; number_of_response:1; }","duration":"247.488169ms","start":"2026-07-14T23:53:28.683854Z","end":"2026-07-14T23:53:28.931342Z","steps":["trace[542364590] 'process raft request' (duration: 214.6619ms)","trace[542364590] 'compare' (duration: 32.683995ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:28.931497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.387637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18432"} {"level":"info","ts":"2026-07-14T23:53:28.931569Z","caller":"traceutil/trace.go:171","msg":"trace[1005829853] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24365; }","duration":"247.481959ms","start":"2026-07-14T23:53:28.684073Z","end":"2026-07-14T23:53:28.931555Z","steps":["trace[1005829853] 'agreement among raft nodes before linearized reading' (duration: 247.280423ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:28.93568Z","caller":"traceutil/trace.go:171","msg":"trace[1562945194] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"223.652412ms","start":"2026-07-14T23:53:28.712012Z","end":"2026-07-14T23:53:28.935664Z","steps":["trace[1562945194] 'process raft request' (duration: 223.537079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:28.935747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.333315ms","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-14T23:53:28.935787Z","caller":"traceutil/trace.go:171","msg":"trace[2074659442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24366; }","duration":"102.408177ms","start":"2026-07-14T23:53:28.833365Z","end":"2026-07-14T23:53:28.935773Z","steps":["trace[2074659442] 'agreement among raft nodes before linearized reading' (duration: 102.273153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.33632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.869374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:29.336401Z","caller":"traceutil/trace.go:171","msg":"trace[523576022] linearizableReadLoop","detail":"{readStateIndex:25161; appliedIndex:25160; }","duration":"345.965996ms","start":"2026-07-14T23:53:28.990424Z","end":"2026-07-14T23:53:29.33639Z","steps":["trace[523576022] 'read index received' (duration: 79.830545ms)","trace[523576022] 'applied index is now lower than readState.Index' (duration: 266.134501ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:29.336466Z","caller":"traceutil/trace.go:171","msg":"trace[377143011] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"388.60231ms","start":"2026-07-14T23:53:28.947856Z","end":"2026-07-14T23:53:29.336458Z","steps":["trace[377143011] 'process raft request' (duration: 122.50791ms)","trace[377143011] 'compare' (duration: 265.782092ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:29.336502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:28.947835Z","time spent":"388.647282ms","remote":"127.0.0.1:35596","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-14T23:53:29.336743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.318116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:53:29.336739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.465964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-14T23:53:29.336815Z","caller":"traceutil/trace.go:171","msg":"trace[1888611774] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3; range_end:; response_count:1; response_revision:24367; }","duration":"224.585338ms","start":"2026-07-14T23:53:29.11221Z","end":"2026-07-14T23:53:29.336795Z","steps":["trace[1888611774] 'agreement among raft nodes before linearized reading' (duration: 224.371362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.336824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.105733ms","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-14T23:53:29.336771Z","caller":"traceutil/trace.go:171","msg":"trace[2052630907] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24367; }","duration":"346.374488ms","start":"2026-07-14T23:53:28.99039Z","end":"2026-07-14T23:53:29.336764Z","steps":["trace[2052630907] 'agreement among raft nodes before linearized reading' (duration: 346.326327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.336839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.765336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-14T23:53:29.336885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:28.990375Z","time spent":"346.502111ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-14T23:53:29.33688Z","caller":"traceutil/trace.go:171","msg":"trace[108769295] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24367; }","duration":"155.197995ms","start":"2026-07-14T23:53:29.181667Z","end":"2026-07-14T23:53:29.336865Z","steps":["trace[108769295] 'agreement among raft nodes before linearized reading' (duration: 155.047471ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:29.33689Z","caller":"traceutil/trace.go:171","msg":"trace[399803123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24367; }","duration":"198.860798ms","start":"2026-07-14T23:53:29.138018Z","end":"2026-07-14T23:53:29.336879Z","steps":["trace[399803123] 'agreement among raft nodes before linearized reading' (duration: 198.744165ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:29.53744Z","caller":"traceutil/trace.go:171","msg":"trace[2137351945] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"196.944473ms","start":"2026-07-14T23:53:29.340464Z","end":"2026-07-14T23:53:29.537409Z","steps":["trace[2137351945] 'process raft request' (duration: 139.690521ms)","trace[2137351945] 'compare' (duration: 56.911142ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:29.568676Z","caller":"traceutil/trace.go:171","msg":"trace[1210628673] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"226.969853ms","start":"2026-07-14T23:53:29.341678Z","end":"2026-07-14T23:53:29.568647Z","steps":["trace[1210628673] 'process raft request' (duration: 226.717916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.568856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.056709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T23:53:29.568911Z","caller":"traceutil/trace.go:171","msg":"trace[604106962] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24370; }","duration":"226.14753ms","start":"2026-07-14T23:53:29.342751Z","end":"2026-07-14T23:53:29.568898Z","steps":["trace[604106962] 'agreement among raft nodes before linearized reading' (duration: 225.963775ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:29.568663Z","caller":"traceutil/trace.go:171","msg":"trace[1571672887] linearizableReadLoop","detail":"{readStateIndex:25163; appliedIndex:25161; }","duration":"225.850852ms","start":"2026-07-14T23:53:29.342782Z","end":"2026-07-14T23:53:29.568633Z","steps":["trace[1571672887] 'read index received' (duration: 137.392646ms)","trace[1571672887] 'applied index is now lower than readState.Index' (duration: 88.457206ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:29.569265Z","caller":"traceutil/trace.go:171","msg":"trace[293289414] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"225.304667ms","start":"2026-07-14T23:53:29.343948Z","end":"2026-07-14T23:53:29.569253Z","steps":["trace[293289414] 'process raft request' (duration: 224.623828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.569422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.003727ms","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-14T23:53:29.569469Z","caller":"traceutil/trace.go:171","msg":"trace[153517514] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24370; }","duration":"226.077469ms","start":"2026-07-14T23:53:29.34338Z","end":"2026-07-14T23:53:29.569457Z","steps":["trace[153517514] 'agreement among raft nodes before linearized reading' (duration: 225.930595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.569701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.960846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-14T23:53:29.569749Z","caller":"traceutil/trace.go:171","msg":"trace[1908248082] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3; range_end:; response_count:1; response_revision:24370; }","duration":"186.035078ms","start":"2026-07-14T23:53:29.3837Z","end":"2026-07-14T23:53:29.569735Z","steps":["trace[1908248082] 'agreement among raft nodes before linearized reading' (duration: 185.966806ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:29.766727Z","caller":"traceutil/trace.go:171","msg":"trace[413098611] linearizableReadLoop","detail":"{readStateIndex:25165; appliedIndex:25164; }","duration":"191.290426ms","start":"2026-07-14T23:53:29.575409Z","end":"2026-07-14T23:53:29.7667Z","steps":["trace[413098611] 'read index received' (duration: 191.047939ms)","trace[413098611] 'applied index is now lower than readState.Index' (duration: 241.297µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:29.766821Z","caller":"traceutil/trace.go:171","msg":"trace[2123470759] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"192.16176ms","start":"2026-07-14T23:53:29.574602Z","end":"2026-07-14T23:53:29.766764Z","steps":["trace[2123470759] 'process raft request' (duration: 191.920304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.766957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.527092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-07-14T23:53:29.766998Z","caller":"traceutil/trace.go:171","msg":"trace[899995376] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:24371; }","duration":"191.595355ms","start":"2026-07-14T23:53:29.57539Z","end":"2026-07-14T23:53:29.766986Z","steps":["trace[899995376] 'agreement among raft nodes before linearized reading' (duration: 191.44104ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:29.826664Z","caller":"traceutil/trace.go:171","msg":"trace[2021600101] transaction","detail":"{read_only:false; response_revision:24372; number_of_response:1; }","duration":"247.543991ms","start":"2026-07-14T23:53:29.579089Z","end":"2026-07-14T23:53:29.826633Z","steps":["trace[2021600101] 'process raft request' (duration: 247.364026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:29.826911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.515784ms","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-14T23:53:29.827022Z","caller":"traceutil/trace.go:171","msg":"trace[8867234] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24372; }","duration":"245.678779ms","start":"2026-07-14T23:53:29.581315Z","end":"2026-07-14T23:53:29.826994Z","steps":["trace[8867234] 'agreement among raft nodes before linearized reading' (duration: 245.333269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:30.174707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.134788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:30.174803Z","caller":"traceutil/trace.go:171","msg":"trace[1843747798] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25166; }","duration":"342.664604ms","start":"2026-07-14T23:53:29.832123Z","end":"2026-07-14T23:53:30.174788Z","steps":["trace[1843747798] 'read index received' (duration: 119.393023ms)","trace[1843747798] 'applied index is now lower than readState.Index' (duration: 223.270201ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:30.174839Z","caller":"traceutil/trace.go:171","msg":"trace[262717000] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"345.661228ms","start":"2026-07-14T23:53:29.829165Z","end":"2026-07-14T23:53:30.174827Z","steps":["trace[262717000] 'process raft request' (duration: 122.323284ms)","trace[262717000] 'compare' (duration: 222.938342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:30.174887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:29.829142Z","time spent":"345.72271ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:53:30.174966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.851109ms","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-14T23:53:30.174991Z","caller":"traceutil/trace.go:171","msg":"trace[2136598656] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24373; }","duration":"342.900901ms","start":"2026-07-14T23:53:29.832082Z","end":"2026-07-14T23:53:30.174983Z","steps":["trace[2136598656] 'agreement among raft nodes before linearized reading' (duration: 342.746717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:30.175019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:29.832044Z","time spent":"342.968853ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9624,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-07-14T23:53:30.440938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.068492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:30.441046Z","caller":"traceutil/trace.go:171","msg":"trace[1995918282] linearizableReadLoop","detail":"{readStateIndex:25169; appliedIndex:25168; }","duration":"158.92327ms","start":"2026-07-14T23:53:30.282097Z","end":"2026-07-14T23:53:30.441021Z","steps":["trace[1995918282] 'read index received' (duration: 18.712744ms)","trace[1995918282] 'applied index is now lower than readState.Index' (duration: 140.209686ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:30.441187Z","caller":"traceutil/trace.go:171","msg":"trace[2083876848] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"188.192109ms","start":"2026-07-14T23:53:30.252936Z","end":"2026-07-14T23:53:30.441128Z","steps":["trace[2083876848] 'process raft request' (duration: 47.861199ms)","trace[2083876848] 'compare' (duration: 139.946429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:30.441334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.231658ms","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-14T23:53:30.441396Z","caller":"traceutil/trace.go:171","msg":"trace[172305874] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24375; }","duration":"159.341301ms","start":"2026-07-14T23:53:30.282045Z","end":"2026-07-14T23:53:30.441386Z","steps":["trace[172305874] 'agreement among raft nodes before linearized reading' (duration: 159.155486ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:30.612715Z","caller":"traceutil/trace.go:171","msg":"trace[1999012056] transaction","detail":"{read_only:false; response_revision:24376; number_of_response:1; }","duration":"228.753944ms","start":"2026-07-14T23:53:30.383937Z","end":"2026-07-14T23:53:30.612691Z","steps":["trace[1999012056] 'process raft request' (duration: 209.919087ms)","trace[1999012056] 'compare' (duration: 18.627991ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:30.612754Z","caller":"traceutil/trace.go:171","msg":"trace[1711309167] transaction","detail":"{read_only:false; response_revision:24377; number_of_response:1; }","duration":"228.745204ms","start":"2026-07-14T23:53:30.383987Z","end":"2026-07-14T23:53:30.612732Z","steps":["trace[1711309167] 'process raft request' (duration: 228.633701ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:30.61305Z","caller":"traceutil/trace.go:171","msg":"trace[34391210] transaction","detail":"{read_only:false; response_revision:24378; number_of_response:1; }","duration":"229.018491ms","start":"2026-07-14T23:53:30.384011Z","end":"2026-07-14T23:53:30.613029Z","steps":["trace[34391210] 'process raft request' (duration: 228.675882ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:30.613177Z","caller":"traceutil/trace.go:171","msg":"trace[1832947508] transaction","detail":"{read_only:false; response_revision:24379; number_of_response:1; }","duration":"228.93899ms","start":"2026-07-14T23:53:30.38421Z","end":"2026-07-14T23:53:30.613149Z","steps":["trace[1832947508] 'process raft request' (duration: 228.673682ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:30.613187Z","caller":"traceutil/trace.go:171","msg":"trace[2098036996] linearizableReadLoop","detail":"{readStateIndex:25174; appliedIndex:25169; }","duration":"172.063857ms","start":"2026-07-14T23:53:30.441071Z","end":"2026-07-14T23:53:30.613135Z","steps":["trace[2098036996] 'read index received' (duration: 152.833169ms)","trace[2098036996] 'applied index is now lower than readState.Index' (duration: 19.229678ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:30.613278Z","caller":"traceutil/trace.go:171","msg":"trace[1950780839] transaction","detail":"{read_only:false; response_revision:24380; number_of_response:1; }","duration":"228.368653ms","start":"2026-07-14T23:53:30.384874Z","end":"2026-07-14T23:53:30.613243Z","steps":["trace[1950780839] 'process raft request' (duration: 228.176348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:30.61344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.23488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18c24d2129c56af7\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-07-14T23:53:30.613468Z","caller":"traceutil/trace.go:171","msg":"trace[1451326875] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18c24d2129c56af7; range_end:; response_count:1; response_revision:24380; }","duration":"168.316892ms","start":"2026-07-14T23:53:30.44514Z","end":"2026-07-14T23:53:30.613457Z","steps":["trace[1451326875] 'agreement among raft nodes before linearized reading' (duration: 168.173798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:30.613467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.424315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:30.613506Z","caller":"traceutil/trace.go:171","msg":"trace[1374371747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24380; }","duration":"243.528228ms","start":"2026-07-14T23:53:30.369967Z","end":"2026-07-14T23:53:30.613495Z","steps":["trace[1374371747] 'agreement among raft nodes before linearized reading' (duration: 243.425986ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:30.862207Z","caller":"traceutil/trace.go:171","msg":"trace[673236467] linearizableReadLoop","detail":"{readStateIndex:25175; appliedIndex:25174; }","duration":"205.037151ms","start":"2026-07-14T23:53:30.657148Z","end":"2026-07-14T23:53:30.862185Z","steps":["trace[673236467] 'read index received' (duration: 124.543477ms)","trace[673236467] 'applied index is now lower than readState.Index' (duration: 80.492584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:30.862321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.158735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:30.86235Z","caller":"traceutil/trace.go:171","msg":"trace[117791778] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24381; }","duration":"205.247927ms","start":"2026-07-14T23:53:30.657095Z","end":"2026-07-14T23:53:30.862343Z","steps":["trace[117791778] 'agreement among raft nodes before linearized reading' (duration: 205.173945ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:30.862423Z","caller":"traceutil/trace.go:171","msg":"trace[489307180] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"246.271786ms","start":"2026-07-14T23:53:30.616121Z","end":"2026-07-14T23:53:30.862392Z","steps":["trace[489307180] 'process raft request' (duration: 165.547795ms)","trace[489307180] 'compare' (duration: 80.414382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:31.155698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.359062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:31.155801Z","caller":"traceutil/trace.go:171","msg":"trace[1309974854] linearizableReadLoop","detail":"{readStateIndex:25176; appliedIndex:25175; }","duration":"217.723296ms","start":"2026-07-14T23:53:30.93806Z","end":"2026-07-14T23:53:31.155784Z","steps":["trace[1309974854] 'read index received' (duration: 48.148028ms)","trace[1309974854] 'applied index is now lower than readState.Index' (duration: 169.574068ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:31.155934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.87077ms","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-14T23:53:31.155921Z","caller":"traceutil/trace.go:171","msg":"trace[2118743162] transaction","detail":"{read_only:false; response_revision:24382; number_of_response:1; }","duration":"470.452742ms","start":"2026-07-14T23:53:30.685434Z","end":"2026-07-14T23:53:31.155887Z","steps":["trace[2118743162] 'process raft request' (duration: 300.827813ms)","trace[2118743162] 'compare' (duration: 169.250198ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:31.155969Z","caller":"traceutil/trace.go:171","msg":"trace[1892896702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24382; }","duration":"217.932552ms","start":"2026-07-14T23:53:30.938026Z","end":"2026-07-14T23:53:31.155959Z","steps":["trace[1892896702] 'agreement among raft nodes before linearized reading' (duration: 217.802838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.15611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:30.685415Z","time spent":"470.569875ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:53:31.291982Z","caller":"traceutil/trace.go:171","msg":"trace[1732416196] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"131.764119ms","start":"2026-07-14T23:53:31.160189Z","end":"2026-07-14T23:53:31.291953Z","steps":["trace[1732416196] 'process raft request' (duration: 121.983076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.813027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.465887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:31.813118Z","caller":"traceutil/trace.go:171","msg":"trace[1171371921] linearizableReadLoop","detail":"{readStateIndex:25178; appliedIndex:25177; }","duration":"451.723388ms","start":"2026-07-14T23:53:31.361379Z","end":"2026-07-14T23:53:31.813103Z","steps":["trace[1171371921] 'read index received' (duration: 193.082266ms)","trace[1171371921] 'applied index is now lower than readState.Index' (duration: 258.639652ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:53:31.813135Z","caller":"traceutil/trace.go:171","msg":"trace[1240263075] transaction","detail":"{read_only:false; response_revision:24384; number_of_response:1; }","duration":"467.702905ms","start":"2026-07-14T23:53:31.34541Z","end":"2026-07-14T23:53:31.813113Z","steps":["trace[1240263075] 'process raft request' (duration: 209.092224ms)","trace[1240263075] 'compare' (duration: 258.383885ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:31.813765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:31.345387Z","time spent":"468.344863ms","remote":"127.0.0.1:35596","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-14T23:53:31.81328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.898793ms","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-14T23:53:31.813821Z","caller":"traceutil/trace.go:171","msg":"trace[1257924912] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24384; }","duration":"452.483749ms","start":"2026-07-14T23:53:31.361324Z","end":"2026-07-14T23:53:31.813807Z","steps":["trace[1257924912] 'agreement among raft nodes before linearized reading' (duration: 451.862051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.813853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:31.361301Z","time spent":"452.543261ms","remote":"127.0.0.1:35596","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-14T23:53:31.813331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.433471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:31.813946Z","caller":"traceutil/trace.go:171","msg":"trace[2141185482] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24384; }","duration":"446.047198ms","start":"2026-07-14T23:53:31.367855Z","end":"2026-07-14T23:53:31.813902Z","steps":["trace[2141185482] 'agreement among raft nodes before linearized reading' (duration: 445.450072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.813391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.754809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-14T23:53:31.81406Z","caller":"traceutil/trace.go:171","msg":"trace[9756895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24384; }","duration":"237.438938ms","start":"2026-07-14T23:53:31.576602Z","end":"2026-07-14T23:53:31.814041Z","steps":["trace[9756895] 'agreement among raft nodes before linearized reading' (duration: 236.744119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.814089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:31.367836Z","time spent":"446.225544ms","remote":"127.0.0.1:41700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:53:31.813518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.499924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:53:31.814169Z","caller":"traceutil/trace.go:171","msg":"trace[685988272] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24384; }","duration":"306.187643ms","start":"2026-07-14T23:53:31.507966Z","end":"2026-07-14T23:53:31.814153Z","steps":["trace[685988272] 'agreement among raft nodes before linearized reading' (duration: 305.499174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.814213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:31.507943Z","time spent":"306.256275ms","remote":"127.0.0.1:42856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:53:31.813639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.181946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-14T23:53:31.814334Z","caller":"traceutil/trace.go:171","msg":"trace[1508166172] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6631a4b8-3563-42a7-92c2-7889fb4c1af3; range_end:; response_count:1; response_revision:24384; }","duration":"194.909917ms","start":"2026-07-14T23:53:31.619411Z","end":"2026-07-14T23:53:31.814321Z","steps":["trace[1508166172] 'agreement among raft nodes before linearized reading' (duration: 194.139235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.813631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.973295ms","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-14T23:53:31.814437Z","caller":"traceutil/trace.go:171","msg":"trace[1928580010] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24384; }","duration":"322.849669ms","start":"2026-07-14T23:53:31.491577Z","end":"2026-07-14T23:53:31.814427Z","steps":["trace[1928580010] 'agreement among raft nodes before linearized reading' (duration: 321.935773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:31.8137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.65044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-14T23:53:31.814501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:53:31.491556Z","time spent":"322.910831ms","remote":"127.0.0.1:48866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:53:31.814525Z","caller":"traceutil/trace.go:171","msg":"trace[802661758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24384; }","duration":"270.527704ms","start":"2026-07-14T23:53:31.543986Z","end":"2026-07-14T23:53:31.814513Z","steps":["trace[802661758] 'agreement among raft nodes before linearized reading' (duration: 269.361232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:33.747577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.231599ms","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-14T23:53:33.747653Z","caller":"traceutil/trace.go:171","msg":"trace[613919453] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24399; }","duration":"249.354042ms","start":"2026-07-14T23:53:33.498283Z","end":"2026-07-14T23:53:33.747637Z","steps":["trace[613919453] 'range keys from in-memory index tree' (duration: 249.075074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:33.747717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.940898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:53:33.747835Z","caller":"traceutil/trace.go:171","msg":"trace[472816487] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"245.058061ms","start":"2026-07-14T23:53:33.502756Z","end":"2026-07-14T23:53:33.747815Z","steps":["trace[472816487] 'process raft request' (duration: 39.930588ms)","trace[472816487] 'compare' (duration: 204.819965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:53:36.026388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.963833ms","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-14T23:53:36.026452Z","caller":"traceutil/trace.go:171","msg":"trace[2122307272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24431; }","duration":"138.077756ms","start":"2026-07-14T23:53:35.88836Z","end":"2026-07-14T23:53:36.026438Z","steps":["trace[2122307272] 'range keys from in-memory index tree' (duration: 137.774698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:36.026447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.179157ms","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-14T23:53:36.026533Z","caller":"traceutil/trace.go:171","msg":"trace[1521944621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24431; }","duration":"189.323441ms","start":"2026-07-14T23:53:35.837182Z","end":"2026-07-14T23:53:36.026506Z","steps":["trace[1521944621] 'range keys from in-memory index tree' (duration: 188.967671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:36.026561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.331305ms","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":"warn","ts":"2026-07-14T23:53:36.026592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.299965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:53:36.026642Z","caller":"traceutil/trace.go:171","msg":"trace[1089063091] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24431; }","duration":"172.462779ms","start":"2026-07-14T23:53:35.854157Z","end":"2026-07-14T23:53:36.02662Z","steps":["trace[1089063091] 'range keys from in-memory index tree' (duration: 172.075138ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:53:36.026669Z","caller":"traceutil/trace.go:171","msg":"trace[970618646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24431; }","duration":"132.426328ms","start":"2026-07-14T23:53:35.894225Z","end":"2026-07-14T23:53:36.026651Z","steps":["trace[970618646] 'range keys from in-memory index tree' (duration: 132.12258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:53:36.026694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.442517ms","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-14T23:53:36.026775Z","caller":"traceutil/trace.go:171","msg":"trace[452544051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24431; }","duration":"138.56611ms","start":"2026-07-14T23:53:35.888189Z","end":"2026-07-14T23:53:36.026755Z","steps":["trace[452544051] 'range keys from in-memory index tree' (duration: 138.028985ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:01.637903Z","caller":"traceutil/trace.go:171","msg":"trace[1802026598] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"112.760688ms","start":"2026-07-14T23:54:01.525115Z","end":"2026-07-14T23:54:01.637876Z","steps":["trace[1802026598] 'process raft request' (duration: 112.604563ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:02.508217Z","caller":"traceutil/trace.go:171","msg":"trace[869161226] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"259.788083ms","start":"2026-07-14T23:54:02.248403Z","end":"2026-07-14T23:54:02.508191Z","steps":["trace[869161226] 'process raft request' (duration: 259.604588ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:02.508561Z","caller":"traceutil/trace.go:171","msg":"trace[670648661] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"260.094713ms","start":"2026-07-14T23:54:02.248428Z","end":"2026-07-14T23:54:02.508522Z","steps":["trace[670648661] 'process raft request' (duration: 259.723532ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:02.508653Z","caller":"traceutil/trace.go:171","msg":"trace[1536045245] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"260.137973ms","start":"2026-07-14T23:54:02.248481Z","end":"2026-07-14T23:54:02.508619Z","steps":["trace[1536045245] 'process raft request' (duration: 259.926887ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:02.508568Z","caller":"traceutil/trace.go:171","msg":"trace[1641098609] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"259.632079ms","start":"2026-07-14T23:54:02.248913Z","end":"2026-07-14T23:54:02.508546Z","steps":["trace[1641098609] 'process raft request' (duration: 259.588898ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:02.574006Z","caller":"traceutil/trace.go:171","msg":"trace[1070183944] linearizableReadLoop","detail":"{readStateIndex:25454; appliedIndex:25449; }","duration":"204.936608ms","start":"2026-07-14T23:54:02.36904Z","end":"2026-07-14T23:54:02.573977Z","steps":["trace[1070183944] 'read index received' (duration: 138.976491ms)","trace[1070183944] 'applied index is now lower than readState.Index' (duration: 65.958847ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:02.574039Z","caller":"traceutil/trace.go:171","msg":"trace[737831167] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"209.586418ms","start":"2026-07-14T23:54:02.364426Z","end":"2026-07-14T23:54:02.574012Z","steps":["trace[737831167] 'process raft request' (duration: 181.261325ms)","trace[737831167] 'compare' (duration: 28.162479ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:02.574171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.077702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:02.574214Z","caller":"traceutil/trace.go:171","msg":"trace[1032589470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24652; }","duration":"205.166815ms","start":"2026-07-14T23:54:02.369036Z","end":"2026-07-14T23:54:02.574203Z","steps":["trace[1032589470] 'agreement among raft nodes before linearized reading' (duration: 205.047451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:02.860486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.974242ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T23:54:02.860718Z","caller":"traceutil/trace.go:171","msg":"trace[656684127] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"142.333915ms","start":"2026-07-14T23:54:02.718368Z","end":"2026-07-14T23:54:02.860702Z","steps":["trace[656684127] 'process raft request' (duration: 142.202301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:03.059309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.689081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:03.059586Z","caller":"traceutil/trace.go:171","msg":"trace[961070682] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"309.871027ms","start":"2026-07-14T23:54:02.74968Z","end":"2026-07-14T23:54:03.059551Z","steps":["trace[961070682] 'process raft request' (duration: 134.846016ms)","trace[961070682] 'compare' (duration: 174.553107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:03.059735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:02.749656Z","time spent":"310.0039ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:54:03.05993Z","caller":"traceutil/trace.go:171","msg":"trace[484942884] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"310.134004ms","start":"2026-07-14T23:54:02.749776Z","end":"2026-07-14T23:54:03.05991Z","steps":["trace[484942884] 'process raft request' (duration: 309.63682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:03.060046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:02.749769Z","time spent":"310.211086ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:54:03.060091Z","caller":"traceutil/trace.go:171","msg":"trace[2055943855] linearizableReadLoop","detail":"{readStateIndex:25460; appliedIndex:25454; }","duration":"279.798304ms","start":"2026-07-14T23:54:02.78027Z","end":"2026-07-14T23:54:03.060068Z","steps":["trace[2055943855] 'read index received' (duration: 70.00492ms)","trace[2055943855] 'applied index is now lower than readState.Index' (duration: 209.792294ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:03.060113Z","caller":"traceutil/trace.go:171","msg":"trace[179616787] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"307.86033ms","start":"2026-07-14T23:54:02.75224Z","end":"2026-07-14T23:54:03.0601Z","steps":["trace[179616787] 'process raft request' (duration: 307.601663ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:03.060178Z","caller":"traceutil/trace.go:171","msg":"trace[880799572] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"306.490761ms","start":"2026-07-14T23:54:02.753657Z","end":"2026-07-14T23:54:03.060148Z","steps":["trace[880799572] 'process raft request' (duration: 306.345867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:03.06026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:02.752227Z","time spent":"307.984693ms","remote":"127.0.0.1:35596","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-14T23:54:03.060336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:02.753647Z","time spent":"306.614155ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:54:03.060536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.229147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:03.061037Z","caller":"traceutil/trace.go:171","msg":"trace[1612842063] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24657; }","duration":"280.618487ms","start":"2026-07-14T23:54:02.780213Z","end":"2026-07-14T23:54:03.060832Z","steps":["trace[1612842063] 'agreement among raft nodes before linearized reading' (duration: 279.942279ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:03.37625Z","caller":"traceutil/trace.go:171","msg":"trace[274786987] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"112.995174ms","start":"2026-07-14T23:54:03.263223Z","end":"2026-07-14T23:54:03.376219Z","steps":["trace[274786987] 'process raft request' (duration: 112.701906ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:03.864747Z","caller":"traceutil/trace.go:171","msg":"trace[1344307105] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"218.458747ms","start":"2026-07-14T23:54:03.646262Z","end":"2026-07-14T23:54:03.86472Z","steps":["trace[1344307105] 'process raft request' (duration: 218.271011ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:03.928761Z","caller":"traceutil/trace.go:171","msg":"trace[1400995314] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"155.575156ms","start":"2026-07-14T23:54:03.773149Z","end":"2026-07-14T23:54:03.928724Z","steps":["trace[1400995314] 'process raft request' (duration: 155.326059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.301095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.872924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:04.301543Z","caller":"traceutil/trace.go:171","msg":"trace[1338593414] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"367.814499ms","start":"2026-07-14T23:54:03.933699Z","end":"2026-07-14T23:54:04.301513Z","steps":["trace[1338593414] 'process raft request' (duration: 136.403809ms)","trace[1338593414] 'compare' (duration: 230.460033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:04.301649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:03.933682Z","time spent":"367.914701ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:54:04.301799Z","caller":"traceutil/trace.go:171","msg":"trace[385937185] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"320.053211ms","start":"2026-07-14T23:54:03.981716Z","end":"2026-07-14T23:54:04.301769Z","steps":["trace[385937185] 'process raft request' (duration: 319.528236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.301919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:03.981697Z","time spent":"320.156184ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:54:04.731735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.070317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:04.731867Z","caller":"traceutil/trace.go:171","msg":"trace[271864644] linearizableReadLoop","detail":"{readStateIndex:25466; appliedIndex:25465; }","duration":"385.339099ms","start":"2026-07-14T23:54:04.346506Z","end":"2026-07-14T23:54:04.731845Z","steps":["trace[271864644] 'read index received' (duration: 108.007284ms)","trace[271864644] 'applied index is now lower than readState.Index' (duration: 277.330095ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:04.731966Z","caller":"traceutil/trace.go:171","msg":"trace[478635118] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"426.198863ms","start":"2026-07-14T23:54:04.305742Z","end":"2026-07-14T23:54:04.731941Z","steps":["trace[478635118] 'process raft request' (duration: 148.826867ms)","trace[478635118] 'compare' (duration: 276.933613ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:04.732095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.305721Z","time spent":"426.323407ms","remote":"127.0.0.1:35596","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-14T23:54:04.731994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.471603ms","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-14T23:54:04.732408Z","caller":"traceutil/trace.go:171","msg":"trace[885977448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24663; }","duration":"385.910665ms","start":"2026-07-14T23:54:04.346469Z","end":"2026-07-14T23:54:04.73238Z","steps":["trace[885977448] 'agreement among raft nodes before linearized reading' (duration: 385.465963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.732479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.346448Z","time spent":"386.006457ms","remote":"127.0.0.1:48872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:54:04.810132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.211051ms","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-14T23:54:04.810238Z","caller":"traceutil/trace.go:171","msg":"trace[138785200] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24664; }","duration":"297.362915ms","start":"2026-07-14T23:54:04.512849Z","end":"2026-07-14T23:54:04.810212Z","steps":["trace[138785200] 'agreement among raft nodes before linearized reading' (duration: 297.15928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.810245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.209011ms","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-14T23:54:04.81031Z","caller":"traceutil/trace.go:171","msg":"trace[1208472965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24664; }","duration":"297.302414ms","start":"2026-07-14T23:54:04.512983Z","end":"2026-07-14T23:54:04.810286Z","steps":["trace[1208472965] 'agreement among raft nodes before linearized reading' (duration: 296.910133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.810332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.088482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:54:04.810397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.95842ms","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":"info","ts":"2026-07-14T23:54:04.810405Z","caller":"traceutil/trace.go:171","msg":"trace[1721927680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24664; }","duration":"440.186114ms","start":"2026-07-14T23:54:04.3702Z","end":"2026-07-14T23:54:04.810386Z","steps":["trace[1721927680] 'agreement among raft nodes before linearized reading' (duration: 440.05007ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:04.810424Z","caller":"traceutil/trace.go:171","msg":"trace[1939009650] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24664; }","duration":"324.031132ms","start":"2026-07-14T23:54:04.486386Z","end":"2026-07-14T23:54:04.810417Z","steps":["trace[1939009650] 'agreement among raft nodes before linearized reading' (duration: 323.921709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.810443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.486365Z","time spent":"324.072434ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":92,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:54:04.810143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.13173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-14T23:54:04.810445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.370192Z","time spent":"440.245356ms","remote":"127.0.0.1:35324","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-14T23:54:04.81049Z","caller":"traceutil/trace.go:171","msg":"trace[1544150055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24664; }","duration":"297.50949ms","start":"2026-07-14T23:54:04.512958Z","end":"2026-07-14T23:54:04.810468Z","steps":["trace[1544150055] 'agreement among raft nodes before linearized reading' (duration: 296.950684ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:04.810519Z","caller":"traceutil/trace.go:171","msg":"trace[961895869] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"232.082299ms","start":"2026-07-14T23:54:04.578385Z","end":"2026-07-14T23:54:04.810467Z","steps":["trace[961895869] 'process raft request' (duration: 231.346888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:04.810567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.72767ms","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-14T23:54:04.81062Z","caller":"traceutil/trace.go:171","msg":"trace[467062071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24664; }","duration":"295.796232ms","start":"2026-07-14T23:54:04.514808Z","end":"2026-07-14T23:54:04.810605Z","steps":["trace[467062071] 'agreement among raft nodes before linearized reading' (duration: 295.653708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:05.013415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.3675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:05.013493Z","caller":"traceutil/trace.go:171","msg":"trace[613816147] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"198.853148ms","start":"2026-07-14T23:54:04.814628Z","end":"2026-07-14T23:54:05.013481Z","steps":["trace[613816147] 'process raft request' (duration: 63.356884ms)","trace[613816147] 'compare' (duration: 135.250677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:05.257342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.960463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:05.257641Z","caller":"traceutil/trace.go:171","msg":"trace[1930736010] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"441.487211ms","start":"2026-07-14T23:54:04.816135Z","end":"2026-07-14T23:54:05.257622Z","steps":["trace[1930736010] 'process raft request' (duration: 441.379498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:05.257725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.816122Z","time spent":"441.566943ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:54:05.257759Z","caller":"traceutil/trace.go:171","msg":"trace[1841963311] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"442.693105ms","start":"2026-07-14T23:54:04.815021Z","end":"2026-07-14T23:54:05.257714Z","steps":["trace[1841963311] 'process raft request' (duration: 324.246329ms)","trace[1841963311] 'compare' (duration: 117.813158ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:05.257836Z","caller":"traceutil/trace.go:171","msg":"trace[1179807201] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"441.766459ms","start":"2026-07-14T23:54:04.816035Z","end":"2026-07-14T23:54:05.257801Z","steps":["trace[1179807201] 'process raft request' (duration: 441.417399ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:05.257869Z","caller":"traceutil/trace.go:171","msg":"trace[1142851716] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"192.426558ms","start":"2026-07-14T23:54:05.06543Z","end":"2026-07-14T23:54:05.257857Z","steps":["trace[1142851716] 'process raft request' (duration: 192.319135ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:05.257871Z","caller":"traceutil/trace.go:171","msg":"trace[1269812642] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"390.835382ms","start":"2026-07-14T23:54:04.867013Z","end":"2026-07-14T23:54:05.257848Z","steps":["trace[1269812642] 'process raft request' (duration: 390.557675ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:05.257882Z","caller":"traceutil/trace.go:171","msg":"trace[1759576702] linearizableReadLoop","detail":"{readStateIndex:25471; appliedIndex:25467; }","duration":"441.007768ms","start":"2026-07-14T23:54:04.816846Z","end":"2026-07-14T23:54:05.257854Z","steps":["trace[1759576702] 'read index received' (duration: 61.149622ms)","trace[1759576702] 'applied index is now lower than readState.Index' (duration: 379.855406ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:05.257935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.815008Z","time spent":"442.838628ms","remote":"127.0.0.1:35596","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-14T23:54:05.257964Z","caller":"traceutil/trace.go:171","msg":"trace[2007914750] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"192.207311ms","start":"2026-07-14T23:54:05.065745Z","end":"2026-07-14T23:54:05.257953Z","steps":["trace[2007914750] 'process raft request' (duration: 192.070058ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:05.257958Z","caller":"traceutil/trace.go:171","msg":"trace[588625525] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"192.1598ms","start":"2026-07-14T23:54:05.065778Z","end":"2026-07-14T23:54:05.257938Z","steps":["trace[588625525] 'process raft request' (duration: 192.115949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:05.257976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.816025Z","time spent":"441.882392ms","remote":"127.0.0.1:35596","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-14T23:54:05.257996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.866988Z","time spent":"390.934356ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:54:05.25806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.339285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:05.258073Z","caller":"traceutil/trace.go:171","msg":"trace[751193695] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"192.285854ms","start":"2026-07-14T23:54:05.065779Z","end":"2026-07-14T23:54:05.258064Z","steps":["trace[751193695] 'process raft request' (duration: 192.072638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:05.25807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.196143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:05.258095Z","caller":"traceutil/trace.go:171","msg":"trace[840143833] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24673; }","duration":"387.405747ms","start":"2026-07-14T23:54:04.870681Z","end":"2026-07-14T23:54:05.258086Z","steps":["trace[840143833] 'agreement among raft nodes before linearized reading' (duration: 387.348016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:05.25812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.870665Z","time spent":"387.448118ms","remote":"127.0.0.1:55718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:54:05.258115Z","caller":"traceutil/trace.go:171","msg":"trace[904988127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24673; }","duration":"441.263575ms","start":"2026-07-14T23:54:04.816838Z","end":"2026-07-14T23:54:05.258101Z","steps":["trace[904988127] 'agreement among raft nodes before linearized reading' (duration: 441.164792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:05.258157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:04.816814Z","time spent":"441.331047ms","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:54:06.096582Z","caller":"traceutil/trace.go:171","msg":"trace[1707312213] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25477; }","duration":"112.744557ms","start":"2026-07-14T23:54:05.983815Z","end":"2026-07-14T23:54:06.09656Z","steps":["trace[1707312213] 'read index received' (duration: 112.561812ms)","trace[1707312213] 'applied index is now lower than readState.Index' (duration: 182.065µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:06.09664Z","caller":"traceutil/trace.go:171","msg":"trace[1689797193] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"224.361792ms","start":"2026-07-14T23:54:05.872243Z","end":"2026-07-14T23:54:06.096605Z","steps":["trace[1689797193] 'process raft request' (duration: 224.160986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:06.096683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.85257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:06.096708Z","caller":"traceutil/trace.go:171","msg":"trace[782687819] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24675; }","duration":"112.925582ms","start":"2026-07-14T23:54:05.983776Z","end":"2026-07-14T23:54:06.096701Z","steps":["trace[782687819] 'agreement among raft nodes before linearized reading' (duration: 112.86143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:07.028152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.370321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:07.028371Z","caller":"traceutil/trace.go:171","msg":"trace[264327912] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"213.438516ms","start":"2026-07-14T23:54:06.814898Z","end":"2026-07-14T23:54:07.028337Z","steps":["trace[264327912] 'process raft request' (duration: 87.747307ms)","trace[264327912] 'compare' (duration: 125.132643ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:07.297997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.555017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:54:07.298097Z","caller":"traceutil/trace.go:171","msg":"trace[1704887340] linearizableReadLoop","detail":"{readStateIndex:25482; appliedIndex:25481; }","duration":"160.633388ms","start":"2026-07-14T23:54:07.137448Z","end":"2026-07-14T23:54:07.298081Z","steps":["trace[1704887340] 'read index received' (duration: 15.957757ms)","trace[1704887340] 'applied index is now lower than readState.Index' (duration: 144.674321ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:07.298269Z","caller":"traceutil/trace.go:171","msg":"trace[1249702322] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"265.679578ms","start":"2026-07-14T23:54:07.032562Z","end":"2026-07-14T23:54:07.298241Z","steps":["trace[1249702322] 'process raft request' (duration: 120.807482ms)","trace[1249702322] 'compare' (duration: 144.434674ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:07.29827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.807743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:07.298381Z","caller":"traceutil/trace.go:171","msg":"trace[445495559] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24679; }","duration":"160.932526ms","start":"2026-07-14T23:54:07.137422Z","end":"2026-07-14T23:54:07.298355Z","steps":["trace[445495559] 'agreement among raft nodes before linearized reading' (duration: 160.797172ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.4705Z","caller":"traceutil/trace.go:171","msg":"trace[986830051] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"284.833885ms","start":"2026-07-14T23:54:07.185639Z","end":"2026-07-14T23:54:07.470473Z","steps":["trace[986830051] 'process raft request' (duration: 275.72666ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.538855Z","caller":"traceutil/trace.go:171","msg":"trace[859859112] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"274.854566ms","start":"2026-07-14T23:54:07.263975Z","end":"2026-07-14T23:54:07.538829Z","steps":["trace[859859112] 'process raft request' (duration: 274.763683ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.538905Z","caller":"traceutil/trace.go:171","msg":"trace[1912490825] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"275.087403ms","start":"2026-07-14T23:54:07.263775Z","end":"2026-07-14T23:54:07.538862Z","steps":["trace[1912490825] 'process raft request' (duration: 274.821855ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.53917Z","caller":"traceutil/trace.go:171","msg":"trace[1299690565] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"274.749223ms","start":"2026-07-14T23:54:07.2644Z","end":"2026-07-14T23:54:07.539149Z","steps":["trace[1299690565] 'process raft request' (duration: 274.384022ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.539303Z","caller":"traceutil/trace.go:171","msg":"trace[846420899] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"274.62219ms","start":"2026-07-14T23:54:07.264636Z","end":"2026-07-14T23:54:07.539258Z","steps":["trace[846420899] 'process raft request' (duration: 274.418544ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.539351Z","caller":"traceutil/trace.go:171","msg":"trace[2071399507] linearizableReadLoop","detail":"{readStateIndex:25488; appliedIndex:25482; }","duration":"241.212344ms","start":"2026-07-14T23:54:07.298123Z","end":"2026-07-14T23:54:07.539335Z","steps":["trace[2071399507] 'read index received' (duration: 163.440766ms)","trace[2071399507] 'applied index is now lower than readState.Index' (duration: 77.770208ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:07.539445Z","caller":"traceutil/trace.go:171","msg":"trace[628626087] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"265.065792ms","start":"2026-07-14T23:54:07.274341Z","end":"2026-07-14T23:54:07.539406Z","steps":["trace[628626087] 'process raft request' (duration: 264.869906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:07.539502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.463043ms","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-14T23:54:07.539558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.618917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:54:07.539573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.580855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-14T23:54:07.539585Z","caller":"traceutil/trace.go:171","msg":"trace[697981149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24685; }","duration":"275.560355ms","start":"2026-07-14T23:54:07.264006Z","end":"2026-07-14T23:54:07.539566Z","steps":["trace[697981149] 'agreement among raft nodes before linearized reading' (duration: 275.332879ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.539613Z","caller":"traceutil/trace.go:171","msg":"trace[552873563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24685; }","duration":"275.706109ms","start":"2026-07-14T23:54:07.263895Z","end":"2026-07-14T23:54:07.539601Z","steps":["trace[552873563] 'agreement among raft nodes before linearized reading' (duration: 275.521344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:07.539551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.570726ms","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-14T23:54:07.539639Z","caller":"traceutil/trace.go:171","msg":"trace[1826293613] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:24685; }","duration":"266.678057ms","start":"2026-07-14T23:54:07.272948Z","end":"2026-07-14T23:54:07.539626Z","steps":["trace[1826293613] 'agreement among raft nodes before linearized reading' (duration: 266.408929ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:07.539695Z","caller":"traceutil/trace.go:171","msg":"trace[2137556216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24685; }","duration":"275.72346ms","start":"2026-07-14T23:54:07.263945Z","end":"2026-07-14T23:54:07.539669Z","steps":["trace[2137556216] 'agreement among raft nodes before linearized reading' (duration: 275.418432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:07.539949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.338969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:54:07.539991Z","caller":"traceutil/trace.go:171","msg":"trace[1076827815] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24686; }","duration":"215.421151ms","start":"2026-07-14T23:54:07.32456Z","end":"2026-07-14T23:54:07.539981Z","steps":["trace[1076827815] 'agreement among raft nodes before linearized reading' (duration: 215.326288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:07.540075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.081122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:07.540146Z","caller":"traceutil/trace.go:171","msg":"trace[1458539038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24686; }","duration":"170.185535ms","start":"2026-07-14T23:54:07.369947Z","end":"2026-07-14T23:54:07.540133Z","steps":["trace[1458539038] 'agreement among raft nodes before linearized reading' (duration: 170.081172ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:08.427659Z","caller":"traceutil/trace.go:171","msg":"trace[715012548] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25494; }","duration":"123.189389ms","start":"2026-07-14T23:54:08.304443Z","end":"2026-07-14T23:54:08.427632Z","steps":["trace[715012548] 'read index received' (duration: 122.983833ms)","trace[715012548] 'applied index is now lower than readState.Index' (duration: 204.756µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:08.427896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.432226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-07-14T23:54:08.427914Z","caller":"traceutil/trace.go:171","msg":"trace[790769031] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"321.562183ms","start":"2026-07-14T23:54:08.106323Z","end":"2026-07-14T23:54:08.427885Z","steps":["trace[790769031] 'process raft request' (duration: 321.175662ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:08.427976Z","caller":"traceutil/trace.go:171","msg":"trace[631606724] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24691; }","duration":"123.558869ms","start":"2026-07-14T23:54:08.304405Z","end":"2026-07-14T23:54:08.427964Z","steps":["trace[631606724] 'agreement among raft nodes before linearized reading' (duration: 123.387524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:08.42807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:54:08.106299Z","time spent":"321.683717ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:54:11.481386Z","caller":"traceutil/trace.go:171","msg":"trace[2062043652] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"166.999506ms","start":"2026-07-14T23:54:11.31436Z","end":"2026-07-14T23:54:11.481359Z","steps":["trace[2062043652] 'process raft request' (duration: 166.828071ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:11.575665Z","caller":"traceutil/trace.go:171","msg":"trace[116411461] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"139.624069ms","start":"2026-07-14T23:54:11.436015Z","end":"2026-07-14T23:54:11.575639Z","steps":["trace[116411461] 'process raft request' (duration: 100.946776ms)","trace[116411461] 'compare' (duration: 38.56633ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:11.792575Z","caller":"traceutil/trace.go:171","msg":"trace[1715946784] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"239.945498ms","start":"2026-07-14T23:54:11.552601Z","end":"2026-07-14T23:54:11.792546Z","steps":["trace[1715946784] 'process raft request' (duration: 201.44218ms)","trace[1715946784] 'compare' (duration: 38.313263ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:11.792762Z","caller":"traceutil/trace.go:171","msg":"trace[1083912784] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"238.966711ms","start":"2026-07-14T23:54:11.553775Z","end":"2026-07-14T23:54:11.792742Z","steps":["trace[1083912784] 'process raft request' (duration: 238.728324ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:11.793169Z","caller":"traceutil/trace.go:171","msg":"trace[1152871900] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"238.140577ms","start":"2026-07-14T23:54:11.555007Z","end":"2026-07-14T23:54:11.793147Z","steps":["trace[1152871900] 'process raft request' (duration: 237.960322ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:11.793286Z","caller":"traceutil/trace.go:171","msg":"trace[1097036140] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"237.775907ms","start":"2026-07-14T23:54:11.555498Z","end":"2026-07-14T23:54:11.793274Z","steps":["trace[1097036140] 'process raft request' (duration: 237.54733ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:11.79336Z","caller":"traceutil/trace.go:171","msg":"trace[690610895] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"239.493915ms","start":"2026-07-14T23:54:11.55386Z","end":"2026-07-14T23:54:11.793354Z","steps":["trace[690610895] 'process raft request' (duration: 238.823756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:11.793509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.329787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:54:11.793537Z","caller":"traceutil/trace.go:171","msg":"trace[83919265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24715; }","duration":"236.367898ms","start":"2026-07-14T23:54:11.557161Z","end":"2026-07-14T23:54:11.793529Z","steps":["trace[83919265] 'agreement among raft nodes before linearized reading' (duration: 236.261375ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:11.793993Z","caller":"traceutil/trace.go:171","msg":"trace[1266280153] linearizableReadLoop","detail":"{readStateIndex:25519; appliedIndex:25514; }","duration":"235.902004ms","start":"2026-07-14T23:54:11.557178Z","end":"2026-07-14T23:54:11.79308Z","steps":["trace[1266280153] 'read index received' (duration: 63.338213ms)","trace[1266280153] 'applied index is now lower than readState.Index' (duration: 172.563151ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:11.79424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.786113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-07-14T23:54:11.794286Z","caller":"traceutil/trace.go:171","msg":"trace[710059554] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:24715; }","duration":"109.862596ms","start":"2026-07-14T23:54:11.684411Z","end":"2026-07-14T23:54:11.794274Z","steps":["trace[710059554] 'agreement among raft nodes before linearized reading' (duration: 109.742162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:11.794398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.115461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:54:11.794441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.631235ms","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-14T23:54:11.794503Z","caller":"traceutil/trace.go:171","msg":"trace[1479363699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24715; }","duration":"236.722248ms","start":"2026-07-14T23:54:11.557765Z","end":"2026-07-14T23:54:11.794487Z","steps":["trace[1479363699] 'agreement among raft nodes before linearized reading' (duration: 236.535612ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:11.794604Z","caller":"traceutil/trace.go:171","msg":"trace[1570936510] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24715; }","duration":"191.365898ms","start":"2026-07-14T23:54:11.603115Z","end":"2026-07-14T23:54:11.794481Z","steps":["trace[1570936510] 'agreement among raft nodes before linearized reading' (duration: 191.073319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:11.795348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.826719ms","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-14T23:54:11.795431Z","caller":"traceutil/trace.go:171","msg":"trace[523252538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24715; }","duration":"237.910771ms","start":"2026-07-14T23:54:11.55749Z","end":"2026-07-14T23:54:11.795401Z","steps":["trace[523252538] 'agreement among raft nodes before linearized reading' (duration: 236.638066ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:27.781921Z","caller":"traceutil/trace.go:171","msg":"trace[541138453] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"101.894033ms","start":"2026-07-14T23:54:27.679997Z","end":"2026-07-14T23:54:27.781891Z","steps":["trace[541138453] 'process raft request' (duration: 101.704257ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:28.211566Z","caller":"traceutil/trace.go:171","msg":"trace[1922578343] linearizableReadLoop","detail":"{readStateIndex:25643; appliedIndex:25642; }","duration":"124.718592ms","start":"2026-07-14T23:54:28.086812Z","end":"2026-07-14T23:54:28.21153Z","steps":["trace[1922578343] 'read index received' (duration: 46.250625ms)","trace[1922578343] 'applied index is now lower than readState.Index' (duration: 78.466907ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:28.211686Z","caller":"traceutil/trace.go:171","msg":"trace[672380091] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"218.837717ms","start":"2026-07-14T23:54:27.992789Z","end":"2026-07-14T23:54:28.211626Z","steps":["trace[672380091] 'process raft request' (duration: 140.33688ms)","trace[672380091] 'compare' (duration: 78.147238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:28.212071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.917738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:28.212267Z","caller":"traceutil/trace.go:171","msg":"trace[788288802] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24835; }","duration":"125.394201ms","start":"2026-07-14T23:54:28.086753Z","end":"2026-07-14T23:54:28.212147Z","steps":["trace[788288802] 'agreement among raft nodes before linearized reading' (duration: 124.924858ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:28.480684Z","caller":"traceutil/trace.go:171","msg":"trace[1951902729] linearizableReadLoop","detail":"{readStateIndex:25644; appliedIndex:25643; }","duration":"108.708974ms","start":"2026-07-14T23:54:28.37194Z","end":"2026-07-14T23:54:28.480649Z","steps":["trace[1951902729] 'read index received' (duration: 108.417706ms)","trace[1951902729] 'applied index is now lower than readState.Index' (duration: 289.928µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:54:28.480812Z","caller":"traceutil/trace.go:171","msg":"trace[1378430301] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"173.971321ms","start":"2026-07-14T23:54:28.306804Z","end":"2026-07-14T23:54:28.480775Z","steps":["trace[1378430301] 'process raft request' (duration: 173.651372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:54:28.480857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.90665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:54:28.480979Z","caller":"traceutil/trace.go:171","msg":"trace[19561272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24836; }","duration":"109.091884ms","start":"2026-07-14T23:54:28.371864Z","end":"2026-07-14T23:54:28.480955Z","steps":["trace[19561272] 'agreement among raft nodes before linearized reading' (duration: 108.92258ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:28.67961Z","caller":"traceutil/trace.go:171","msg":"trace[1023394305] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"122.393047ms","start":"2026-07-14T23:54:28.557187Z","end":"2026-07-14T23:54:28.67958Z","steps":["trace[1023394305] 'process raft request' (duration: 122.202061ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:29.251613Z","caller":"traceutil/trace.go:171","msg":"trace[1959715466] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"139.941729ms","start":"2026-07-14T23:54:29.111639Z","end":"2026-07-14T23:54:29.251581Z","steps":["trace[1959715466] 'process raft request' (duration: 139.769024ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:29.461474Z","caller":"traceutil/trace.go:171","msg":"trace[521702458] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"130.233886ms","start":"2026-07-14T23:54:29.331159Z","end":"2026-07-14T23:54:29.461393Z","steps":["trace[521702458] 'process raft request' (duration: 50.952957ms)","trace[521702458] 'compare' (duration: 79.000211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:54:29.768164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.832594ms","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-14T23:54:29.768225Z","caller":"traceutil/trace.go:171","msg":"trace[1676195362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24840; }","duration":"205.910776ms","start":"2026-07-14T23:54:29.562301Z","end":"2026-07-14T23:54:29.768211Z","steps":["trace[1676195362] 'range keys from in-memory index tree' (duration: 205.646118ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:54:48.65882Z","caller":"traceutil/trace.go:171","msg":"trace[905665539] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"207.790888ms","start":"2026-07-14T23:54:48.450998Z","end":"2026-07-14T23:54:48.658788Z","steps":["trace[905665539] 'process raft request' (duration: 207.616393ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:55:32.564839Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006707.wal"} {"level":"info","ts":"2026-07-14T23:57:14.282303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23588} {"level":"info","ts":"2026-07-14T23:57:14.416636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23588,"took":"130.367599ms","hash":880037114,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":37486592,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-14T23:57:14.41672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":880037114,"revision":23588,"compact-revision":20380} {"level":"info","ts":"2026-07-15T00:02:14.288388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26467} {"level":"info","ts":"2026-07-15T00:02:14.427949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26467,"took":"135.655518ms","hash":3112754358,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32948224,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T00:02:14.428035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3112754358,"revision":26467,"compact-revision":23588} {"level":"info","ts":"2026-07-15T00:02:17.365476Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T00:02:17.370689Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T00:02:17.370789Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T00:07:14.29437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29058} {"level":"info","ts":"2026-07-15T00:07:14.426506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29058,"took":"128.706122ms","hash":3252469898,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30666752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:07:14.426658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3252469898,"revision":29058,"compact-revision":26467} {"level":"info","ts":"2026-07-15T00:12:14.301618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31310} {"level":"info","ts":"2026-07-15T00:12:14.430803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31310,"took":"124.631217ms","hash":1777461963,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30855168,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:12:14.430875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1777461963,"revision":31310,"compact-revision":29058} {"level":"info","ts":"2026-07-15T00:17:14.311719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33608} {"level":"info","ts":"2026-07-15T00:17:14.455892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33608,"took":"140.446561ms","hash":536112459,"current-db-size-bytes":60428288,"current-db-size":"60 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T00:17:14.45597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":536112459,"revision":33608,"compact-revision":31310} {"level":"info","ts":"2026-07-15T00:22:14.319369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35868} {"level":"info","ts":"2026-07-15T00:22:14.464126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35868,"took":"140.188603ms","hash":1912071481,"current-db-size-bytes":62164992,"current-db-size":"62 MB","current-db-size-in-use-bytes":32382976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T00:22:14.464194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1912071481,"revision":35868,"compact-revision":33608} {"level":"info","ts":"2026-07-15T00:22:21.926133Z","caller":"traceutil/trace.go:171","msg":"trace[275601389] linearizableReadLoop","detail":"{readStateIndex:39372; appliedIndex:39370; }","duration":"423.976833ms","start":"2026-07-15T00:22:21.502127Z","end":"2026-07-15T00:22:21.926104Z","steps":["trace[275601389] 'read index received' (duration: 46.263885ms)","trace[275601389] 'applied index is now lower than readState.Index' (duration: 377.711608ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T00:22:21.927181Z","caller":"traceutil/trace.go:171","msg":"trace[2007456710] transaction","detail":"{read_only:false; response_revision:38192; number_of_response:1; }","duration":"423.336055ms","start":"2026-07-15T00:22:21.503813Z","end":"2026-07-15T00:22:21.927149Z","steps":["trace[2007456710] 'process raft request' (duration: 422.235545ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.927209Z","caller":"traceutil/trace.go:171","msg":"trace[1268760611] transaction","detail":"{read_only:false; response_revision:38191; number_of_response:1; }","duration":"429.093046ms","start":"2026-07-15T00:22:21.498096Z","end":"2026-07-15T00:22:21.92719Z","steps":["trace[1268760611] 'process raft request' (duration: 427.78334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T00:22:21.92727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.139486ms","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-15T00:22:21.927305Z","caller":"traceutil/trace.go:171","msg":"trace[1492358513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:38192; }","duration":"425.212357ms","start":"2026-07-15T00:22:21.502082Z","end":"2026-07-15T00:22:21.927294Z","steps":["trace[1492358513] 'agreement among raft nodes before linearized reading' (duration: 425.083934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T00:22:21.927309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T00:22:21.49808Z","time spent":"429.173568ms","remote":"127.0.0.1:35596","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-15T00:22:21.927325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T00:22:21.502069Z","time spent":"425.251109ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T00:22:21.927322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T00:22:21.503786Z","time spent":"423.464699ms","remote":"127.0.0.1:35596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T00:22:21.927979Z","caller":"traceutil/trace.go:171","msg":"trace[834762207] transaction","detail":"{read_only:false; response_revision:38193; number_of_response:1; }","duration":"224.501981ms","start":"2026-07-15T00:22:21.703457Z","end":"2026-07-15T00:22:21.927959Z","steps":["trace[834762207] 'process raft request' (duration: 224.365547ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.928217Z","caller":"traceutil/trace.go:171","msg":"trace[2094433553] transaction","detail":"{read_only:false; response_revision:38194; number_of_response:1; }","duration":"146.148669ms","start":"2026-07-15T00:22:21.782053Z","end":"2026-07-15T00:22:21.928202Z","steps":["trace[2094433553] 'process raft request' (duration: 145.866601ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.928247Z","caller":"traceutil/trace.go:171","msg":"trace[678624055] transaction","detail":"{read_only:false; response_revision:38195; number_of_response:1; }","duration":"142.866357ms","start":"2026-07-15T00:22:21.785368Z","end":"2026-07-15T00:22:21.928234Z","steps":["trace[678624055] 'process raft request' (duration: 142.718293ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.928283Z","caller":"traceutil/trace.go:171","msg":"trace[773548538] transaction","detail":"{read_only:false; response_revision:38198; number_of_response:1; }","duration":"138.364411ms","start":"2026-07-15T00:22:21.789913Z","end":"2026-07-15T00:22:21.928277Z","steps":["trace[773548538] 'process raft request' (duration: 138.297379ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.928316Z","caller":"traceutil/trace.go:171","msg":"trace[1464224157] transaction","detail":"{read_only:false; response_revision:38199; number_of_response:1; }","duration":"101.88801ms","start":"2026-07-15T00:22:21.826422Z","end":"2026-07-15T00:22:21.92831Z","steps":["trace[1464224157] 'process raft request' (duration: 101.815758ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.928324Z","caller":"traceutil/trace.go:171","msg":"trace[992967955] transaction","detail":"{read_only:false; response_revision:38197; number_of_response:1; }","duration":"138.66869ms","start":"2026-07-15T00:22:21.789643Z","end":"2026-07-15T00:22:21.928311Z","steps":["trace[992967955] 'process raft request' (duration: 138.538706ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:22:21.928338Z","caller":"traceutil/trace.go:171","msg":"trace[675875789] transaction","detail":"{read_only:false; response_revision:38196; number_of_response:1; }","duration":"139.07014ms","start":"2026-07-15T00:22:21.789262Z","end":"2026-07-15T00:22:21.928332Z","steps":["trace[675875789] 'process raft request' (duration: 138.890435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T00:22:21.928397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.898533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-07-15T00:22:21.928436Z","caller":"traceutil/trace.go:171","msg":"trace[248893713] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:38199; }","duration":"169.965655ms","start":"2026-07-15T00:22:21.75846Z","end":"2026-07-15T00:22:21.928426Z","steps":["trace[248893713] 'agreement among raft nodes before linearized reading' (duration: 169.808501ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T00:23:38.234137Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T00:23:38.239272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T00:23:38.239343Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}