{"level":"warn","ts":"2026-10-14T15:41:51.183392Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:41:51.183485Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22: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://162.253.55.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22: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-10-14T15:41:51.183571Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:41:51.183588Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-14T15:41:51.18361Z","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-10-14T15:41:51.18437Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-10-14T15:41:51.184458Z","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://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22: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-10-14T15:41:51.192252Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.50632ms"} {"level":"info","ts":"2026-10-14T15:41:51.201178Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-10-14T15:41:51.201245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T15:41:51.201264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-10-14T15:41:51.201281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T15:41:51.201289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-10-14T15:41:51.201323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-10-14T15:41:51.207134Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T15:41:51.209341Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T15:41:51.211328Z","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-10-14T15:41:51.214704Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T15:41:51.21486Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T15:41:51.215094Z","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-10-14T15:41:51.215254Z","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-10-14T15:41:51.215297Z","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-10-14T15:41:51.217164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-10-14T15:41:51.217304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-14T15:41:51.224051Z","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-10-14T15:41:51.224139Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-14T15:41:51.22419Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-14T15:41:51.224676Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T15:41:51.224752Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T15:41:52.202484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T15:41:52.202574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T15:41:52.202615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-10-14T15:41:52.202636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-10-14T15:41:52.202653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-14T15:41:52.202668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-10-14T15:41:52.202685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-14T15:41:52.203778Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:41:52.204837Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T15:41:52.204853Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:41:52.20496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:41:52.205369Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T15:41:52.205442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T15:41:52.205612Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:41:52.205741Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:41:52.205799Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:41:52.208264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-10-14T15:41:52.209321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T15:41:53.933732Z","caller":"traceutil/trace.go:171","msg":"trace[146701890] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"235.609357ms","start":"2026-10-14T15:41:53.698097Z","end":"2026-10-14T15:41:53.933706Z","steps":["trace[146701890] 'read index received' (duration: 235.352199ms)","trace[146701890] 'applied index is now lower than readState.Index' (duration: 256.188µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:41:53.933798Z","caller":"traceutil/trace.go:171","msg":"trace[2050491037] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"240.333138ms","start":"2026-10-14T15:41:53.693422Z","end":"2026-10-14T15:41:53.933756Z","steps":["trace[2050491037] 'process raft request' (duration: 240.038249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:53.934055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.934648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:53.934173Z","caller":"traceutil/trace.go:171","msg":"trace[515804045] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"236.089332ms","start":"2026-10-14T15:41:53.698057Z","end":"2026-10-14T15:41:53.934146Z","steps":["trace[515804045] 'agreement among raft nodes before linearized reading' (duration: 235.791843ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.142245Z","caller":"traceutil/trace.go:171","msg":"trace[2114984471] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"359.522556ms","start":"2026-10-14T15:41:53.782641Z","end":"2026-10-14T15:41:54.142163Z","steps":["trace[2114984471] 'process raft request' (duration: 359.427203ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.142297Z","caller":"traceutil/trace.go:171","msg":"trace[509315709] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"208.392038ms","start":"2026-10-14T15:41:53.933887Z","end":"2026-10-14T15:41:54.142279Z","steps":["trace[509315709] 'read index received' (duration: 208.071448ms)","trace[509315709] 'applied index is now lower than readState.Index' (duration: 319.79µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:41:54.142429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.003125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.142467Z","caller":"traceutil/trace.go:171","msg":"trace[479248151] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"444.083128ms","start":"2026-10-14T15:41:53.698373Z","end":"2026-10-14T15:41:54.142456Z","steps":["trace[479248151] 'agreement among raft nodes before linearized reading' (duration: 443.971614ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.142461Z","caller":"traceutil/trace.go:171","msg":"trace[1111417230] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"343.0296ms","start":"2026-10-14T15:41:53.799398Z","end":"2026-10-14T15:41:54.142428Z","steps":["trace[1111417230] 'process raft request' (duration: 342.845184ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.142534Z","caller":"traceutil/trace.go:171","msg":"trace[1830461974] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"359.207057ms","start":"2026-10-14T15:41:53.783306Z","end":"2026-10-14T15:41:54.142513Z","steps":["trace[1830461974] 'process raft request' (duration: 358.873706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.142503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.698361Z","time spent":"444.134279ms","remote":"127.0.0.1:42138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-10-14T15:41:54.142509Z","caller":"traceutil/trace.go:171","msg":"trace[1804953979] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"360.486077ms","start":"2026-10-14T15:41:53.781949Z","end":"2026-10-14T15:41:54.142435Z","steps":["trace[1804953979] 'process raft request' (duration: 360.005112ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.14258Z","caller":"traceutil/trace.go:171","msg":"trace[1915519089] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"359.331101ms","start":"2026-10-14T15:41:53.783182Z","end":"2026-10-14T15:41:54.142513Z","steps":["trace[1915519089] 'process raft request' (duration: 358.973879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.142671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.511892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.142725Z","caller":"traceutil/trace.go:171","msg":"trace[443662496] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"359.761304ms","start":"2026-10-14T15:41:53.782955Z","end":"2026-10-14T15:41:54.142716Z","steps":["trace[443662496] 'process raft request' (duration: 359.146464ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.142761Z","caller":"traceutil/trace.go:171","msg":"trace[1852308936] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"362.601125ms","start":"2026-10-14T15:41:53.780121Z","end":"2026-10-14T15:41:54.142722Z","steps":["trace[1852308936] 'agreement among raft nodes before linearized reading' (duration: 362.477211ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.14278Z","caller":"traceutil/trace.go:171","msg":"trace[980194337] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"359.657331ms","start":"2026-10-14T15:41:53.783113Z","end":"2026-10-14T15:41:54.14277Z","steps":["trace[980194337] 'process raft request' (duration: 359.01769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.142781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.718817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-14T15:41:54.142806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.780112Z","time spent":"362.683327ms","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-14T15:41:54.142813Z","caller":"traceutil/trace.go:171","msg":"trace[688232657] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"443.81448ms","start":"2026-10-14T15:41:53.698989Z","end":"2026-10-14T15:41:54.142804Z","steps":["trace[688232657] 'agreement among raft nodes before linearized reading' (duration: 443.762968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.142839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.698976Z","time spent":"443.857911ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-10-14T15:41:54.142842Z","caller":"traceutil/trace.go:171","msg":"trace[992880440] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"348.66171ms","start":"2026-10-14T15:41:53.794172Z","end":"2026-10-14T15:41:54.142833Z","steps":["trace[992880440] 'process raft request' (duration: 348.04163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.142901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.782625Z","time spent":"359.669962ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.142887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.021994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.142992Z","caller":"traceutil/trace.go:171","msg":"trace[592449698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"443.152448ms","start":"2026-10-14T15:41:53.699803Z","end":"2026-10-14T15:41:54.142956Z","steps":["trace[592449698] 'agreement among raft nodes before linearized reading' (duration: 442.961212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.143003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.799379Z","time spent":"343.122282ms","remote":"127.0.0.1:41992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.143059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.69979Z","time spent":"443.259042ms","remote":"127.0.0.1:42444","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-10-14T15:41:54.14309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.482573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-14T15:41:54.143092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.8206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.14312Z","caller":"traceutil/trace.go:171","msg":"trace[852555969] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"311.541234ms","start":"2026-10-14T15:41:53.83157Z","end":"2026-10-14T15:41:54.143111Z","steps":["trace[852555969] 'agreement among raft nodes before linearized reading' (duration: 311.499523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.143143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.831533Z","time spent":"311.604536ms","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-14T15:41:54.143138Z","caller":"traceutil/trace.go:171","msg":"trace[1710899669] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"294.868862ms","start":"2026-10-14T15:41:53.848255Z","end":"2026-10-14T15:41:54.143124Z","steps":["trace[1710899669] 'agreement among raft nodes before linearized reading' (duration: 294.79722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.143171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.58029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.143209Z","caller":"traceutil/trace.go:171","msg":"trace[135581848] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"390.659422ms","start":"2026-10-14T15:41:53.752539Z","end":"2026-10-14T15:41:54.143199Z","steps":["trace[135581848] 'agreement among raft nodes before linearized reading' (duration: 390.58708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.143192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.902209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-14T15:41:54.14325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.752525Z","time spent":"390.707653ms","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-14T15:41:54.14327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.034427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.143283Z","caller":"traceutil/trace.go:171","msg":"trace[214088690] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:0; response_revision:10; }","duration":"365.019243ms","start":"2026-10-14T15:41:53.77824Z","end":"2026-10-14T15:41:54.143259Z","steps":["trace[214088690] 'agreement among raft nodes before linearized reading' (duration: 364.864538ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:54.143297Z","caller":"traceutil/trace.go:171","msg":"trace[553670016] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"295.059868ms","start":"2026-10-14T15:41:53.848229Z","end":"2026-10-14T15:41:54.143289Z","steps":["trace[553670016] 'agreement among raft nodes before linearized reading' (duration: 295.021847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.143323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.77823Z","time spent":"365.083715ms","remote":"127.0.0.1:41740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.22\" "} {"level":"warn","ts":"2026-10-14T15:41:54.143319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.782696Z","time spent":"360.050953ms","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.1435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.781918Z","time spent":"360.683783ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.14352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.086897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T15:41:54.143606Z","caller":"traceutil/trace.go:171","msg":"trace[1683379526] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"361.20644ms","start":"2026-10-14T15:41:53.782381Z","end":"2026-10-14T15:41:54.143588Z","steps":["trace[1683379526] 'agreement among raft nodes before linearized reading' (duration: 361.098117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:54.143619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.783295Z","time spent":"359.274628ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.143648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.782363Z","time spent":"361.271483ms","remote":"127.0.0.1:41794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-10-14T15:41:54.143703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.783082Z","time spent":"359.719213ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.143899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.794139Z","time spent":"348.721121ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:41:54.144218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:41:53.783169Z","time spent":"359.485525ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T15:42:09.40589Z","caller":"traceutil/trace.go:171","msg":"trace[2034785746] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"128.582608ms","start":"2026-10-14T15:42:09.277283Z","end":"2026-10-14T15:42:09.405866Z","steps":["trace[2034785746] 'process raft request' (duration: 128.437093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:09.645574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.778668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:42:09.645665Z","caller":"traceutil/trace.go:171","msg":"trace[1278844234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"171.940503ms","start":"2026-10-14T15:42:09.473707Z","end":"2026-10-14T15:42:09.645647Z","steps":["trace[1278844234] 'range keys from in-memory index tree' (duration: 171.688165ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:41.719742Z","caller":"traceutil/trace.go:171","msg":"trace[570705302] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"121.036887ms","start":"2026-10-14T15:42:41.598674Z","end":"2026-10-14T15:42:41.719711Z","steps":["trace[570705302] 'process raft request' (duration: 120.733308ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:41.866398Z","caller":"traceutil/trace.go:171","msg":"trace[1592225891] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"105.936624ms","start":"2026-10-14T15:42:41.760415Z","end":"2026-10-14T15:42:41.866352Z","steps":["trace[1592225891] 'process raft request' (duration: 59.649827ms)","trace[1592225891] 'compare' (duration: 44.226264ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:42.119452Z","caller":"traceutil/trace.go:171","msg":"trace[524160886] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"177.464364ms","start":"2026-10-14T15:42:41.941949Z","end":"2026-10-14T15:42:42.119414Z","steps":["trace[524160886] 'process raft request' (duration: 177.293678ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:42.324105Z","caller":"traceutil/trace.go:171","msg":"trace[1431248399] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"191.910076ms","start":"2026-10-14T15:42:42.132165Z","end":"2026-10-14T15:42:42.324075Z","steps":["trace[1431248399] 'read index received' (duration: 163.895378ms)","trace[1431248399] 'applied index is now lower than readState.Index' (duration: 28.013227ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:42.324184Z","caller":"traceutil/trace.go:171","msg":"trace[738231220] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"381.850912ms","start":"2026-10-14T15:42:41.942295Z","end":"2026-10-14T15:42:42.324145Z","steps":["trace[738231220] 'process raft request' (duration: 353.859625ms)","trace[738231220] 'compare' (duration: 27.744519ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:42:42.32429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.109552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:42:42.324345Z","caller":"traceutil/trace.go:171","msg":"trace[928811091] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:651; }","duration":"192.221666ms","start":"2026-10-14T15:42:42.132104Z","end":"2026-10-14T15:42:42.324325Z","steps":["trace[928811091] 'agreement among raft nodes before linearized reading' (duration: 192.125802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:42.324634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:42:41.942268Z","time spent":"381.999846ms","remote":"127.0.0.1:42006","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-10-14T15:42:42.989774Z","caller":"traceutil/trace.go:171","msg":"trace[1944944071] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"273.268727ms","start":"2026-10-14T15:42:42.716478Z","end":"2026-10-14T15:42:42.989747Z","steps":["trace[1944944071] 'process raft request' (duration: 272.69311ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:53.92416Z","caller":"traceutil/trace.go:171","msg":"trace[206512811] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"104.755667ms","start":"2026-10-14T15:42:53.81936Z","end":"2026-10-14T15:42:53.924116Z","steps":["trace[206512811] 'process raft request' (duration: 89.219681ms)","trace[206512811] 'compare' (duration: 15.317589ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:58.312224Z","caller":"traceutil/trace.go:171","msg":"trace[1516522546] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"320.468004ms","start":"2026-10-14T15:42:57.991726Z","end":"2026-10-14T15:42:58.312194Z","steps":["trace[1516522546] 'process raft request' (duration: 320.269718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:58.312414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:42:57.991707Z","time spent":"320.615778ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:43:27.810856Z","caller":"traceutil/trace.go:171","msg":"trace[2005005490] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"188.73159ms","start":"2026-10-14T15:43:27.62209Z","end":"2026-10-14T15:43:27.810822Z","steps":["trace[2005005490] 'process raft request' (duration: 177.8327ms)","trace[2005005490] 'compare' (duration: 10.439635ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:44:18.241363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.877626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:44:18.241683Z","caller":"traceutil/trace.go:171","msg":"trace[588929369] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"237.990621ms","start":"2026-10-14T15:44:18.003643Z","end":"2026-10-14T15:44:18.241633Z","steps":["trace[588929369] 'process raft request' (duration: 99.561476ms)","trace[588929369] 'compare' (duration: 137.707361ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:44:18.729006Z","caller":"traceutil/trace.go:171","msg":"trace[1195209615] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"255.3768ms","start":"2026-10-14T15:44:18.473608Z","end":"2026-10-14T15:44:18.728984Z","steps":["trace[1195209615] 'read index received' (duration: 255.119891ms)","trace[1195209615] 'applied index is now lower than readState.Index' (duration: 255.979µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:44:18.729091Z","caller":"traceutil/trace.go:171","msg":"trace[2127819780] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"275.577308ms","start":"2026-10-14T15:44:18.453468Z","end":"2026-10-14T15:44:18.729046Z","steps":["trace[2127819780] 'process raft request' (duration: 275.370292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:44:18.729198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.558906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:44:18.729298Z","caller":"traceutil/trace.go:171","msg":"trace[1658837783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"255.698199ms","start":"2026-10-14T15:44:18.473581Z","end":"2026-10-14T15:44:18.729279Z","steps":["trace[1658837783] 'agreement among raft nodes before linearized reading' (duration: 255.515824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:44:18.729231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.739911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2853ed8-2f61-4449-b0e8-810631019c11\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-10-14T15:44:18.729524Z","caller":"traceutil/trace.go:171","msg":"trace[686681] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d2853ed8-2f61-4449-b0e8-810631019c11; range_end:; response_count:1; response_revision:1900; }","duration":"247.037681ms","start":"2026-10-14T15:44:18.482467Z","end":"2026-10-14T15:44:18.729504Z","steps":["trace[686681] 'agreement among raft nodes before linearized reading' (duration: 246.659699ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:44:35.653655Z","caller":"traceutil/trace.go:171","msg":"trace[1961410057] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2111; }","duration":"199.803895ms","start":"2026-10-14T15:44:35.453823Z","end":"2026-10-14T15:44:35.653627Z","steps":["trace[1961410057] 'read index received' (duration: 199.641049ms)","trace[1961410057] 'applied index is now lower than readState.Index' (duration: 161.475µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:44:35.653711Z","caller":"traceutil/trace.go:171","msg":"trace[525413180] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"386.421226ms","start":"2026-10-14T15:44:35.267254Z","end":"2026-10-14T15:44:35.653676Z","steps":["trace[525413180] 'process raft request' (duration: 386.194899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:44:35.65391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.064383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-14T15:44:35.653883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.37153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:44:35.653958Z","caller":"traceutil/trace.go:171","msg":"trace[1837398732] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2039; }","duration":"200.162006ms","start":"2026-10-14T15:44:35.453781Z","end":"2026-10-14T15:44:35.653943Z","steps":["trace[1837398732] 'agreement among raft nodes before linearized reading' (duration: 199.97386ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:44:35.654Z","caller":"traceutil/trace.go:171","msg":"trace[655554404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2039; }","duration":"180.585307ms","start":"2026-10-14T15:44:35.473385Z","end":"2026-10-14T15:44:35.65397Z","steps":["trace[655554404] 'agreement among raft nodes before linearized reading' (duration: 180.414032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:44:35.654014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.65307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-14T15:44:35.65392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:44:35.267227Z","time spent":"386.579161ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:44:35.654065Z","caller":"traceutil/trace.go:171","msg":"trace[360210388] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:1; response_revision:2039; }","duration":"147.759744ms","start":"2026-10-14T15:44:35.506289Z","end":"2026-10-14T15:44:35.654049Z","steps":["trace[360210388] 'agreement among raft nodes before linearized reading' (duration: 147.557798ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:14.025133Z","caller":"traceutil/trace.go:171","msg":"trace[1074608780] linearizableReadLoop","detail":"{readStateIndex:2329; appliedIndex:2328; }","duration":"114.92319ms","start":"2026-10-14T15:45:13.910183Z","end":"2026-10-14T15:45:14.025106Z","steps":["trace[1074608780] 'read index received' (duration: 114.766965ms)","trace[1074608780] 'applied index is now lower than readState.Index' (duration: 155.155µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:45:14.025214Z","caller":"traceutil/trace.go:171","msg":"trace[2008421076] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"181.00941ms","start":"2026-10-14T15:45:13.844173Z","end":"2026-10-14T15:45:14.025183Z","steps":["trace[2008421076] 'process raft request' (duration: 180.794893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:14.025328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.134026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-14T15:45:14.025367Z","caller":"traceutil/trace.go:171","msg":"trace[35667514] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2247; }","duration":"115.226029ms","start":"2026-10-14T15:45:13.91013Z","end":"2026-10-14T15:45:14.025356Z","steps":["trace[35667514] 'agreement among raft nodes before linearized reading' (duration: 115.076854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:14.595494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.838972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:45:14.595613Z","caller":"traceutil/trace.go:171","msg":"trace[1092904337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2248; }","duration":"122.000607ms","start":"2026-10-14T15:45:14.473578Z","end":"2026-10-14T15:45:14.595578Z","steps":["trace[1092904337] 'range keys from in-memory index tree' (duration: 121.674587ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:15.150558Z","caller":"traceutil/trace.go:171","msg":"trace[1871573515] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"131.027917ms","start":"2026-10-14T15:45:15.019505Z","end":"2026-10-14T15:45:15.150533Z","steps":["trace[1871573515] 'read index received' (duration: 130.81203ms)","trace[1871573515] 'applied index is now lower than readState.Index' (duration: 214.797µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:45:15.150822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.297906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-10-14T15:45:15.150861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.080171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-14T15:45:15.150918Z","caller":"traceutil/trace.go:171","msg":"trace[1898201527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2250; }","duration":"118.185224ms","start":"2026-10-14T15:45:15.032704Z","end":"2026-10-14T15:45:15.150889Z","steps":["trace[1898201527] 'agreement among raft nodes before linearized reading' (duration: 117.990408ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:15.150901Z","caller":"traceutil/trace.go:171","msg":"trace[199417094] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2250; }","duration":"131.44204ms","start":"2026-10-14T15:45:15.019439Z","end":"2026-10-14T15:45:15.150881Z","steps":["trace[199417094] 'agreement among raft nodes before linearized reading' (duration: 131.187522ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:15.150655Z","caller":"traceutil/trace.go:171","msg":"trace[1564666894] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"151.66507ms","start":"2026-10-14T15:45:14.998925Z","end":"2026-10-14T15:45:15.15059Z","steps":["trace[1564666894] 'process raft request' (duration: 151.435012ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:49.485028Z","caller":"traceutil/trace.go:171","msg":"trace[224137147] linearizableReadLoop","detail":"{readStateIndex:2707; appliedIndex:2706; }","duration":"150.912565ms","start":"2026-10-14T15:45:49.334096Z","end":"2026-10-14T15:45:49.485008Z","steps":["trace[224137147] 'read index received' (duration: 150.800042ms)","trace[224137147] 'applied index is now lower than readState.Index' (duration: 111.623µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:45:49.485102Z","caller":"traceutil/trace.go:171","msg":"trace[308075887] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"194.833835ms","start":"2026-10-14T15:45:49.290237Z","end":"2026-10-14T15:45:49.485071Z","steps":["trace[308075887] 'process raft request' (duration: 194.65569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:49.485208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.093291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T15:45:49.485264Z","caller":"traceutil/trace.go:171","msg":"trace[2015481864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2612; }","duration":"151.189164ms","start":"2026-10-14T15:45:49.334064Z","end":"2026-10-14T15:45:49.485253Z","steps":["trace[2015481864] 'agreement among raft nodes before linearized reading' (duration: 151.05144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:49.509782Z","caller":"traceutil/trace.go:171","msg":"trace[1001711009] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"106.409145ms","start":"2026-10-14T15:45:49.403338Z","end":"2026-10-14T15:45:49.509748Z","steps":["trace[1001711009] 'process raft request' (duration: 106.269121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:49.509953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.6026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-10-14T15:45:49.509984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.609769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T15:45:49.510021Z","caller":"traceutil/trace.go:171","msg":"trace[1631616304] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2614; }","duration":"162.681622ms","start":"2026-10-14T15:45:49.347317Z","end":"2026-10-14T15:45:49.509998Z","steps":["trace[1631616304] 'agreement among raft nodes before linearized reading' (duration: 162.518457ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:49.51006Z","caller":"traceutil/trace.go:171","msg":"trace[1036073330] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2614; }","duration":"138.721903ms","start":"2026-10-14T15:45:49.371323Z","end":"2026-10-14T15:45:49.510044Z","steps":["trace[1036073330] 'agreement among raft nodes before linearized reading' (duration: 138.467335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:22.604993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.790591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:46:22.605139Z","caller":"traceutil/trace.go:171","msg":"trace[1575116609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2816; }","duration":"131.986047ms","start":"2026-10-14T15:46:22.473133Z","end":"2026-10-14T15:46:22.605119Z","steps":["trace[1575116609] 'range keys from in-memory index tree' (duration: 131.693348ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:22.740558Z","caller":"traceutil/trace.go:171","msg":"trace[959568464] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"131.504382ms","start":"2026-10-14T15:46:22.609027Z","end":"2026-10-14T15:46:22.740531Z","steps":["trace[959568464] 'process raft request' (duration: 131.332376ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:34.100003Z","caller":"traceutil/trace.go:171","msg":"trace[890733707] transaction","detail":"{read_only:false; response_revision:4025; number_of_response:1; }","duration":"128.123093ms","start":"2026-10-14T15:49:33.971851Z","end":"2026-10-14T15:49:34.099974Z","steps":["trace[890733707] 'process raft request' (duration: 127.980629ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:00.83516Z","caller":"traceutil/trace.go:171","msg":"trace[2064803675] linearizableReadLoop","detail":"{readStateIndex:5197; appliedIndex:5196; }","duration":"128.806325ms","start":"2026-10-14T15:51:00.706327Z","end":"2026-10-14T15:51:00.835133Z","steps":["trace[2064803675] 'read index received' (duration: 120.476888ms)","trace[2064803675] 'applied index is now lower than readState.Index' (duration: 8.328467ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:00.835339Z","caller":"traceutil/trace.go:171","msg":"trace[1226643152] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"137.953228ms","start":"2026-10-14T15:51:00.697367Z","end":"2026-10-14T15:51:00.835321Z","steps":["trace[1226643152] 'process raft request' (duration: 129.474406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:00.835416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.070623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-14T15:51:00.835467Z","caller":"traceutil/trace.go:171","msg":"trace[1291722239] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5013; }","duration":"129.152776ms","start":"2026-10-14T15:51:00.706297Z","end":"2026-10-14T15:51:00.83545Z","steps":["trace[1291722239] 'agreement among raft nodes before linearized reading' (duration: 128.97552ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:05.566223Z","caller":"traceutil/trace.go:171","msg":"trace[69663116] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"201.834199ms","start":"2026-10-14T15:51:05.364373Z","end":"2026-10-14T15:51:05.566207Z","steps":["trace[69663116] 'process raft request' (duration: 201.448407ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:05.566143Z","caller":"traceutil/trace.go:171","msg":"trace[1602462016] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5384; }","duration":"125.884341ms","start":"2026-10-14T15:51:05.440239Z","end":"2026-10-14T15:51:05.566124Z","steps":["trace[1602462016] 'read index received' (duration: 125.385195ms)","trace[1602462016] 'applied index is now lower than readState.Index' (duration: 497.906µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:05.567459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.199942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:782477"} {"level":"info","ts":"2026-10-14T15:51:05.56754Z","caller":"traceutil/trace.go:171","msg":"trace[1382010419] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5196; }","duration":"127.311697ms","start":"2026-10-14T15:51:05.440197Z","end":"2026-10-14T15:51:05.567509Z","steps":["trace[1382010419] 'agreement among raft nodes before linearized reading' (duration: 126.16712ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:05.797812Z","caller":"traceutil/trace.go:171","msg":"trace[2000105745] linearizableReadLoop","detail":"{readStateIndex:5387; appliedIndex:5385; }","duration":"130.50727ms","start":"2026-10-14T15:51:05.667286Z","end":"2026-10-14T15:51:05.797793Z","steps":["trace[2000105745] 'read index received' (duration: 45.668306ms)","trace[2000105745] 'applied index is now lower than readState.Index' (duration: 84.838354ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:05.798079Z","caller":"traceutil/trace.go:171","msg":"trace[704138922] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"146.605017ms","start":"2026-10-14T15:51:05.651465Z","end":"2026-10-14T15:51:05.79807Z","steps":["trace[704138922] 'process raft request' (duration: 146.202364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:05.798296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.995005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7bfce3bb-4ec5-479e-98f5-bcd157e836d6\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-10-14T15:51:05.798326Z","caller":"traceutil/trace.go:171","msg":"trace[1992752799] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7bfce3bb-4ec5-479e-98f5-bcd157e836d6; range_end:; response_count:1; response_revision:5197; }","duration":"131.077558ms","start":"2026-10-14T15:51:05.66724Z","end":"2026-10-14T15:51:05.798318Z","steps":["trace[1992752799] 'agreement among raft nodes before linearized reading' (duration: 130.959644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:05.79843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.124719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:51:05.798452Z","caller":"traceutil/trace.go:171","msg":"trace[814516864] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5197; }","duration":"107.15081ms","start":"2026-10-14T15:51:05.691296Z","end":"2026-10-14T15:51:05.798447Z","steps":["trace[814516864] 'agreement among raft nodes before linearized reading' (duration: 107.114179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:19.896803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.176792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:51:19.897161Z","caller":"traceutil/trace.go:171","msg":"trace[599417813] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"480.204944ms","start":"2026-10-14T15:51:19.416934Z","end":"2026-10-14T15:51:19.897139Z","steps":["trace[599417813] 'process raft request' (duration: 480.117891ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:19.897203Z","caller":"traceutil/trace.go:171","msg":"trace[2092931289] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"481.962038ms","start":"2026-10-14T15:51:19.415208Z","end":"2026-10-14T15:51:19.897171Z","steps":["trace[2092931289] 'process raft request' (duration: 337.32093ms)","trace[2092931289] 'compare' (duration: 143.705448ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:19.897265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:19.416918Z","time spent":"480.291776ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:51:19.897375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:19.415195Z","time spent":"482.104193ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:51:19.897478Z","caller":"traceutil/trace.go:171","msg":"trace[61489194] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"480.314657ms","start":"2026-10-14T15:51:19.4171Z","end":"2026-10-14T15:51:19.897415Z","steps":["trace[61489194] 'process raft request' (duration: 479.988966ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:19.897583Z","caller":"traceutil/trace.go:171","msg":"trace[1136995669] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"471.23296ms","start":"2026-10-14T15:51:19.426281Z","end":"2026-10-14T15:51:19.897514Z","steps":["trace[1136995669] 'process raft request' (duration: 471.052394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:19.897654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:19.417077Z","time spent":"480.486062ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:51:19.897682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:19.426241Z","time spent":"471.395455ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11832,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:51:19.898729Z","caller":"traceutil/trace.go:171","msg":"trace[657513270] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"482.132394ms","start":"2026-10-14T15:51:19.415866Z","end":"2026-10-14T15:51:19.897999Z","steps":["trace[657513270] 'process raft request' (duration: 481.095791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:19.898869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:19.41585Z","time spent":"482.951929ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:51:20.287368Z","caller":"traceutil/trace.go:171","msg":"trace[1010213955] linearizableReadLoop","detail":"{readStateIndex:5659; appliedIndex:5658; }","duration":"157.708369ms","start":"2026-10-14T15:51:20.129632Z","end":"2026-10-14T15:51:20.28734Z","steps":["trace[1010213955] 'read index received' (duration: 87.026318ms)","trace[1010213955] 'applied index is now lower than readState.Index' (duration: 70.680991ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:20.287479Z","caller":"traceutil/trace.go:171","msg":"trace[1746646132] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"209.784764ms","start":"2026-10-14T15:51:20.077651Z","end":"2026-10-14T15:51:20.287436Z","steps":["trace[1746646132] 'process raft request' (duration: 139.079982ms)","trace[1746646132] 'compare' (duration: 70.455825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:20.287616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.446173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ngzd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-14T15:51:20.287659Z","caller":"traceutil/trace.go:171","msg":"trace[1805903237] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ngzd; range_end:; response_count:1; response_revision:5460; }","duration":"120.532366ms","start":"2026-10-14T15:51:20.167115Z","end":"2026-10-14T15:51:20.287647Z","steps":["trace[1805903237] 'agreement among raft nodes before linearized reading' (duration: 120.376091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:20.287871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.252246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-14T15:51:20.288139Z","caller":"traceutil/trace.go:171","msg":"trace[1767771088] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5460; }","duration":"158.552196ms","start":"2026-10-14T15:51:20.129568Z","end":"2026-10-14T15:51:20.28812Z","steps":["trace[1767771088] 'agreement among raft nodes before linearized reading' (duration: 157.891555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:25.954693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.828029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:51:25.955118Z","caller":"traceutil/trace.go:171","msg":"trace[166198291] linearizableReadLoop","detail":"{readStateIndex:5722; appliedIndex:5721; }","duration":"343.726493ms","start":"2026-10-14T15:51:25.611368Z","end":"2026-10-14T15:51:25.955094Z","steps":["trace[166198291] 'read index received' (duration: 23.43667ms)","trace[166198291] 'applied index is now lower than readState.Index' (duration: 320.287223ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:25.955153Z","caller":"traceutil/trace.go:171","msg":"trace[63037830] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"368.491185ms","start":"2026-10-14T15:51:25.586628Z","end":"2026-10-14T15:51:25.955119Z","steps":["trace[63037830] 'process raft request' (duration: 48.11591ms)","trace[63037830] 'compare' (duration: 319.667623ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:25.955348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.94017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:51:25.95539Z","caller":"traceutil/trace.go:171","msg":"trace[512391661] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5521; }","duration":"344.033673ms","start":"2026-10-14T15:51:25.611346Z","end":"2026-10-14T15:51:25.95538Z","steps":["trace[512391661] 'agreement among raft nodes before linearized reading' (duration: 343.869408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:25.955432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:25.611337Z","time spent":"344.085455ms","remote":"127.0.0.1:43052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T15:51:25.955425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:25.586616Z","time spent":"368.751923ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:51:25.955648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.328739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:51:25.955929Z","caller":"traceutil/trace.go:171","msg":"trace[5410174] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5521; }","duration":"266.645769ms","start":"2026-10-14T15:51:25.689267Z","end":"2026-10-14T15:51:25.955913Z","steps":["trace[5410174] 'agreement among raft nodes before linearized reading' (duration: 266.237726ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:26.253519Z","caller":"traceutil/trace.go:171","msg":"trace[1546978437] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"211.284623ms","start":"2026-10-14T15:51:26.042216Z","end":"2026-10-14T15:51:26.253501Z","steps":["trace[1546978437] 'process raft request' (duration: 203.701693ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:26.254374Z","caller":"traceutil/trace.go:171","msg":"trace[422143170] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"139.959499ms","start":"2026-10-14T15:51:26.114405Z","end":"2026-10-14T15:51:26.254365Z","steps":["trace[422143170] 'process raft request' (duration: 139.929549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:26.254582Z","caller":"traceutil/trace.go:171","msg":"trace[667405222] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"176.143022ms","start":"2026-10-14T15:51:26.078431Z","end":"2026-10-14T15:51:26.254574Z","steps":["trace[667405222] 'process raft request' (duration: 175.849313ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:36.646108Z","caller":"traceutil/trace.go:171","msg":"trace[320172247] linearizableReadLoop","detail":"{readStateIndex:5938; appliedIndex:5937; }","duration":"234.639119ms","start":"2026-10-14T15:51:36.411445Z","end":"2026-10-14T15:51:36.646085Z","steps":["trace[320172247] 'read index received' (duration: 234.476223ms)","trace[320172247] 'applied index is now lower than readState.Index' (duration: 161.545µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:36.646287Z","caller":"traceutil/trace.go:171","msg":"trace[667159011] transaction","detail":"{read_only:false; response_revision:5733; number_of_response:1; }","duration":"239.848762ms","start":"2026-10-14T15:51:36.406355Z","end":"2026-10-14T15:51:36.646204Z","steps":["trace[667159011] 'process raft request' (duration: 239.586004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:36.64639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.380172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T15:51:36.646455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.99637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81528"} {"level":"info","ts":"2026-10-14T15:51:36.646476Z","caller":"traceutil/trace.go:171","msg":"trace[1195284952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5733; }","duration":"171.506246ms","start":"2026-10-14T15:51:36.47495Z","end":"2026-10-14T15:51:36.646456Z","steps":["trace[1195284952] 'agreement among raft nodes before linearized reading' (duration: 171.35212ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:36.646493Z","caller":"traceutil/trace.go:171","msg":"trace[1445710277] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5733; }","duration":"235.089953ms","start":"2026-10-14T15:51:36.411393Z","end":"2026-10-14T15:51:36.646482Z","steps":["trace[1445710277] 'agreement among raft nodes before linearized reading' (duration: 234.859965ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:39.732884Z","caller":"traceutil/trace.go:171","msg":"trace[1137519490] transaction","detail":"{read_only:false; response_revision:5783; number_of_response:1; }","duration":"105.123109ms","start":"2026-10-14T15:51:39.62772Z","end":"2026-10-14T15:51:39.732843Z","steps":["trace[1137519490] 'process raft request' (duration: 41.495841ms)","trace[1137519490] 'compare' (duration: 63.397931ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:39.73296Z","caller":"traceutil/trace.go:171","msg":"trace[1368874501] linearizableReadLoop","detail":"{readStateIndex:5990; appliedIndex:5989; }","duration":"102.800955ms","start":"2026-10-14T15:51:39.630135Z","end":"2026-10-14T15:51:39.732936Z","steps":["trace[1368874501] 'read index received' (duration: 8.026552ms)","trace[1368874501] 'applied index is now lower than readState.Index' (duration: 94.773353ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:39.733366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.216918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-14T15:51:39.733451Z","caller":"traceutil/trace.go:171","msg":"trace[1007255605] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5784; }","duration":"103.305331ms","start":"2026-10-14T15:51:39.630124Z","end":"2026-10-14T15:51:39.73343Z","steps":["trace[1007255605] 'agreement among raft nodes before linearized reading' (duration: 102.901439ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:44.733962Z","caller":"traceutil/trace.go:171","msg":"trace[1122448628] transaction","detail":"{read_only:false; response_revision:5813; number_of_response:1; }","duration":"102.016641ms","start":"2026-10-14T15:51:44.631914Z","end":"2026-10-14T15:51:44.73393Z","steps":["trace[1122448628] 'process raft request' (duration: 101.830826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.57642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.810937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:51:45.576802Z","caller":"traceutil/trace.go:171","msg":"trace[466862567] transaction","detail":"{read_only:false; response_revision:5815; number_of_response:1; }","duration":"835.293795ms","start":"2026-10-14T15:51:44.741477Z","end":"2026-10-14T15:51:45.576771Z","steps":["trace[466862567] 'process raft request' (duration: 835.078308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.576921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.741461Z","time spent":"835.404519ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:51:45.576798Z","caller":"traceutil/trace.go:171","msg":"trace[176566448] transaction","detail":"{read_only:false; response_revision:5814; number_of_response:1; }","duration":"837.424953ms","start":"2026-10-14T15:51:44.739332Z","end":"2026-10-14T15:51:45.576757Z","steps":["trace[176566448] 'process raft request' (duration: 423.167772ms)","trace[176566448] 'compare' (duration: 413.179257ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:45.577156Z","caller":"traceutil/trace.go:171","msg":"trace[1801904004] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"834.038835ms","start":"2026-10-14T15:51:44.743108Z","end":"2026-10-14T15:51:45.577147Z","steps":["trace[1801904004] 'process raft request' (duration: 833.607381ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.577172Z","caller":"traceutil/trace.go:171","msg":"trace[248782108] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"766.388629ms","start":"2026-10-14T15:51:44.810758Z","end":"2026-10-14T15:51:45.577147Z","steps":["trace[248782108] 'process raft request' (duration: 766.076539ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.577188Z","caller":"traceutil/trace.go:171","msg":"trace[1330429413] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"548.291102ms","start":"2026-10-14T15:51:45.028866Z","end":"2026-10-14T15:51:45.577158Z","steps":["trace[1330429413] 'process raft request' (duration: 548.192459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.57727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.73931Z","time spent":"837.820575ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:51:45.577294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.743094Z","time spent":"834.100978ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:51:45.577294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.810748Z","time spent":"766.505684ms","remote":"127.0.0.1:42006","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-10-14T15:51:45.577146Z","caller":"traceutil/trace.go:171","msg":"trace[948770817] linearizableReadLoop","detail":"{readStateIndex:6026; appliedIndex:6022; }","duration":"833.854519ms","start":"2026-10-14T15:51:44.743273Z","end":"2026-10-14T15:51:45.577127Z","steps":["trace[948770817] 'read index received' (duration: 419.253008ms)","trace[948770817] 'applied index is now lower than readState.Index' (duration: 414.600371ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:45.577313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:45.028848Z","time spent":"548.410646ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:51:45.577298Z","caller":"traceutil/trace.go:171","msg":"trace[1438040146] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"835.71965ms","start":"2026-10-14T15:51:44.741545Z","end":"2026-10-14T15:51:45.577265Z","steps":["trace[1438040146] 'process raft request' (duration: 835.10775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.577463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.741521Z","time spent":"835.854304ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:51:45.577496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.180651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T15:51:45.577521Z","caller":"traceutil/trace.go:171","msg":"trace[306184267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5819; }","duration":"143.232533ms","start":"2026-10-14T15:51:45.434281Z","end":"2026-10-14T15:51:45.577514Z","steps":["trace[306184267] 'agreement among raft nodes before linearized reading' (duration: 143.12264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.577538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.253733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81821"} {"level":"warn","ts":"2026-10-14T15:51:45.577558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.484279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-14T15:51:45.577584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.921901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:51:45.577597Z","caller":"traceutil/trace.go:171","msg":"trace[2021111056] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5819; }","duration":"834.317004ms","start":"2026-10-14T15:51:44.743263Z","end":"2026-10-14T15:51:45.57758Z","steps":["trace[2021111056] 'agreement among raft nodes before linearized reading' (duration: 833.919652ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.577614Z","caller":"traceutil/trace.go:171","msg":"trace[539595078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5819; }","duration":"281.561911ms","start":"2026-10-14T15:51:45.296036Z","end":"2026-10-14T15:51:45.577598Z","steps":["trace[539595078] 'agreement among raft nodes before linearized reading' (duration: 281.404116ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.577623Z","caller":"traceutil/trace.go:171","msg":"trace[30411870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5819; }","duration":"103.977363ms","start":"2026-10-14T15:51:45.473632Z","end":"2026-10-14T15:51:45.57761Z","steps":["trace[30411870] 'agreement among raft nodes before linearized reading' (duration: 103.920972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.577655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.743183Z","time spent":"834.455769ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":81845,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-14T15:51:45.577721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.916442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T15:51:45.577844Z","caller":"traceutil/trace.go:171","msg":"trace[1558527123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5819; }","duration":"289.095178ms","start":"2026-10-14T15:51:45.288718Z","end":"2026-10-14T15:51:45.577813Z","steps":["trace[1558527123] 'agreement among raft nodes before linearized reading' (duration: 288.728426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.577897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.365618ms","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-10-14T15:51:45.577989Z","caller":"traceutil/trace.go:171","msg":"trace[261343373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5819; }","duration":"829.501522ms","start":"2026-10-14T15:51:44.74846Z","end":"2026-10-14T15:51:45.577961Z","steps":["trace[261343373] 'agreement among raft nodes before linearized reading' (duration: 828.941815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.578044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.748449Z","time spent":"829.580564ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T15:51:45.579777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.410885ms","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-10-14T15:51:45.579825Z","caller":"traceutil/trace.go:171","msg":"trace[1110397001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5819; }","duration":"769.487167ms","start":"2026-10-14T15:51:44.810325Z","end":"2026-10-14T15:51:45.579812Z","steps":["trace[1110397001] 'agreement among raft nodes before linearized reading' (duration: 767.167315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:51:45.579848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:51:44.81031Z","time spent":"769.532668ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T15:51:45.779254Z","caller":"traceutil/trace.go:171","msg":"trace[621646339] transaction","detail":"{read_only:false; response_revision:5822; number_of_response:1; }","duration":"194.348037ms","start":"2026-10-14T15:51:45.584891Z","end":"2026-10-14T15:51:45.779239Z","steps":["trace[621646339] 'process raft request' (duration: 194.278435ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.779295Z","caller":"traceutil/trace.go:171","msg":"trace[1831157713] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"194.834802ms","start":"2026-10-14T15:51:45.584448Z","end":"2026-10-14T15:51:45.779282Z","steps":["trace[1831157713] 'process raft request' (duration: 194.662627ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.77927Z","caller":"traceutil/trace.go:171","msg":"trace[708570381] transaction","detail":"{read_only:false; response_revision:5820; number_of_response:1; }","duration":"196.620959ms","start":"2026-10-14T15:51:45.582594Z","end":"2026-10-14T15:51:45.779215Z","steps":["trace[708570381] 'process raft request' (duration: 129.168639ms)","trace[708570381] 'compare' (duration: 67.033036ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:51:45.779668Z","caller":"traceutil/trace.go:171","msg":"trace[1980433391] transaction","detail":"{read_only:false; response_revision:5823; number_of_response:1; }","duration":"193.481889ms","start":"2026-10-14T15:51:45.586173Z","end":"2026-10-14T15:51:45.779655Z","steps":["trace[1980433391] 'process raft request' (duration: 193.020954ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.779738Z","caller":"traceutil/trace.go:171","msg":"trace[896204676] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"193.19457ms","start":"2026-10-14T15:51:45.586532Z","end":"2026-10-14T15:51:45.779726Z","steps":["trace[896204676] 'process raft request' (duration: 193.108697ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.779828Z","caller":"traceutil/trace.go:171","msg":"trace[1604974296] transaction","detail":"{read_only:false; response_revision:5824; number_of_response:1; }","duration":"193.383606ms","start":"2026-10-14T15:51:45.586436Z","end":"2026-10-14T15:51:45.779819Z","steps":["trace[1604974296] 'process raft request' (duration: 193.066706ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.779727Z","caller":"traceutil/trace.go:171","msg":"trace[2098236062] linearizableReadLoop","detail":"{readStateIndex:6036; appliedIndex:6029; }","duration":"129.83111ms","start":"2026-10-14T15:51:45.649884Z","end":"2026-10-14T15:51:45.779715Z","steps":["trace[2098236062] 'read index received' (duration: 61.884945ms)","trace[2098236062] 'applied index is now lower than readState.Index' (duration: 67.945365ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:51:45.779891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.004005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-14T15:51:45.779925Z","caller":"traceutil/trace.go:171","msg":"trace[1120348327] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5826; }","duration":"130.097678ms","start":"2026-10-14T15:51:45.649817Z","end":"2026-10-14T15:51:45.779914Z","steps":["trace[1120348327] 'agreement among raft nodes before linearized reading' (duration: 129.943063ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:45.77982Z","caller":"traceutil/trace.go:171","msg":"trace[830568780] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"162.737328ms","start":"2026-10-14T15:51:45.617014Z","end":"2026-10-14T15:51:45.779752Z","steps":["trace[830568780] 'process raft request' (duration: 162.665246ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:51:52.242341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-10-14T15:51:52.328645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"84.757687ms","hash":3694190270,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T15:51:52.328722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694190270,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-10-14T15:56:52.249073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5887} {"level":"info","ts":"2026-10-14T15:56:52.340348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5887,"took":"89.568281ms","hash":2663495673,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":25903104,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T15:56:52.340416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2663495673,"revision":5887,"compact-revision":2981} {"level":"info","ts":"2026-10-14T15:57:45.890394Z","caller":"traceutil/trace.go:171","msg":"trace[1792812111] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8833; }","duration":"225.173087ms","start":"2026-10-14T15:57:45.665191Z","end":"2026-10-14T15:57:45.890364Z","steps":["trace[1792812111] 'read index received' (duration: 225.012212ms)","trace[1792812111] 'applied index is now lower than readState.Index' (duration: 159.065µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:57:45.890419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:57:45.549253Z","time spent":"341.157292ms","remote":"127.0.0.1:41740","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-14T15:57:45.89067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.471115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ngzd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-14T15:57:45.890743Z","caller":"traceutil/trace.go:171","msg":"trace[1644075750] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ngzd; range_end:; response_count:1; response_revision:8537; }","duration":"225.59142ms","start":"2026-10-14T15:57:45.665134Z","end":"2026-10-14T15:57:45.890725Z","steps":["trace[1644075750] 'agreement among raft nodes before linearized reading' (duration: 225.378053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:46.165637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.322587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:57:46.165964Z","caller":"traceutil/trace.go:171","msg":"trace[994146217] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"274.184834ms","start":"2026-10-14T15:57:45.89176Z","end":"2026-10-14T15:57:46.165945Z","steps":["trace[994146217] 'process raft request' (duration: 273.968047ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:46.165953Z","caller":"traceutil/trace.go:171","msg":"trace[1573570896] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"418.850001ms","start":"2026-10-14T15:57:45.747068Z","end":"2026-10-14T15:57:46.165918Z","steps":["trace[1573570896] 'process raft request' (duration: 263.170153ms)","trace[1573570896] 'compare' (duration: 155.202873ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:57:46.166125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:57:45.746988Z","time spent":"419.032936ms","remote":"127.0.0.1:42006","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-10-14T15:57:46.165969Z","caller":"traceutil/trace.go:171","msg":"trace[1542483427] linearizableReadLoop","detail":"{readStateIndex:8835; appliedIndex:8834; }","duration":"275.460054ms","start":"2026-10-14T15:57:45.890484Z","end":"2026-10-14T15:57:46.165944Z","steps":["trace[1542483427] 'read index received' (duration: 119.783526ms)","trace[1542483427] 'applied index is now lower than readState.Index' (duration: 155.673818ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:57:46.166181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.491777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T15:57:46.166233Z","caller":"traceutil/trace.go:171","msg":"trace[732034488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8539; }","duration":"383.578999ms","start":"2026-10-14T15:57:45.782638Z","end":"2026-10-14T15:57:46.166217Z","steps":["trace[732034488] 'agreement among raft nodes before linearized reading' (duration: 383.369693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:46.166268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:57:45.782621Z","time spent":"383.640692ms","remote":"127.0.0.1:42006","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-10-14T15:57:46.166234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.071902ms","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-10-14T15:57:46.166335Z","caller":"traceutil/trace.go:171","msg":"trace[341107358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8539; }","duration":"337.222816ms","start":"2026-10-14T15:57:45.829089Z","end":"2026-10-14T15:57:46.166312Z","steps":["trace[341107358] 'agreement among raft nodes before linearized reading' (duration: 336.907937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:46.166396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:57:45.829069Z","time spent":"337.315129ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T15:57:46.166426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.115918ms","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-10-14T15:57:46.16645Z","caller":"traceutil/trace.go:171","msg":"trace[266780059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8539; }","duration":"239.213751ms","start":"2026-10-14T15:57:45.927228Z","end":"2026-10-14T15:57:46.166442Z","steps":["trace[266780059] 'agreement among raft nodes before linearized reading' (duration: 239.122649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:46.166447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.626559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T15:57:46.166512Z","caller":"traceutil/trace.go:171","msg":"trace[35629310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8539; }","duration":"275.717772ms","start":"2026-10-14T15:57:45.890775Z","end":"2026-10-14T15:57:46.166493Z","steps":["trace[35629310] 'agreement among raft nodes before linearized reading' (duration: 275.524836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:46.166563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.593987ms","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-10-14T15:57:46.166608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.226564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T15:57:46.16665Z","caller":"traceutil/trace.go:171","msg":"trace[1671902585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8539; }","duration":"235.722221ms","start":"2026-10-14T15:57:45.930905Z","end":"2026-10-14T15:57:46.166628Z","steps":["trace[1671902585] 'agreement among raft nodes before linearized reading' (duration: 235.418172ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:46.166658Z","caller":"traceutil/trace.go:171","msg":"trace[1994016514] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8539; }","duration":"229.301867ms","start":"2026-10-14T15:57:45.937337Z","end":"2026-10-14T15:57:46.166639Z","steps":["trace[1994016514] 'agreement among raft nodes before linearized reading' (duration: 229.182933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:46.166863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.026864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223834"} {"level":"info","ts":"2026-10-14T15:57:46.166959Z","caller":"traceutil/trace.go:171","msg":"trace[1423754718] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8539; }","duration":"272.154458ms","start":"2026-10-14T15:57:45.894793Z","end":"2026-10-14T15:57:46.166948Z","steps":["trace[1423754718] 'agreement among raft nodes before linearized reading' (duration: 271.767696ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:58.409139Z","caller":"traceutil/trace.go:171","msg":"trace[1034363082] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"295.464679ms","start":"2026-10-14T15:57:58.113646Z","end":"2026-10-14T15:57:58.409111Z","steps":["trace[1034363082] 'process raft request' (duration: 295.239012ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:58.41864Z","caller":"traceutil/trace.go:171","msg":"trace[1156158684] linearizableReadLoop","detail":"{readStateIndex:8904; appliedIndex:8902; }","duration":"152.563919ms","start":"2026-10-14T15:57:58.266052Z","end":"2026-10-14T15:57:58.418616Z","steps":["trace[1156158684] 'read index received' (duration: 142.840789ms)","trace[1156158684] 'applied index is now lower than readState.Index' (duration: 9.72185ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:57:58.4187Z","caller":"traceutil/trace.go:171","msg":"trace[132647468] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"182.973865ms","start":"2026-10-14T15:57:58.235693Z","end":"2026-10-14T15:57:58.418667Z","steps":["trace[132647468] 'process raft request' (duration: 182.782339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:58.418859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.787026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ngzd\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-14T15:57:58.418891Z","caller":"traceutil/trace.go:171","msg":"trace[1254337387] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ngzd; range_end:; response_count:1; response_revision:8604; }","duration":"152.90747ms","start":"2026-10-14T15:57:58.265973Z","end":"2026-10-14T15:57:58.41888Z","steps":["trace[1254337387] 'agreement among raft nodes before linearized reading' (duration: 152.755445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:58.418939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.376291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-14T15:57:58.418977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.522095ms","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-10-14T15:57:58.419052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.142039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T15:57:58.419059Z","caller":"traceutil/trace.go:171","msg":"trace[1576102128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8604; }","duration":"134.502355ms","start":"2026-10-14T15:57:58.284498Z","end":"2026-10-14T15:57:58.419001Z","steps":["trace[1576102128] 'agreement among raft nodes before linearized reading' (duration: 134.318179ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:58.419077Z","caller":"traceutil/trace.go:171","msg":"trace[1186929250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8604; }","duration":"151.654039ms","start":"2026-10-14T15:57:58.267402Z","end":"2026-10-14T15:57:58.419056Z","steps":["trace[1186929250] 'agreement among raft nodes before linearized reading' (duration: 151.418942ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:58.419121Z","caller":"traceutil/trace.go:171","msg":"trace[317487603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8604; }","duration":"132.263623ms","start":"2026-10-14T15:57:58.286841Z","end":"2026-10-14T15:57:58.419105Z","steps":["trace[317487603] 'agreement among raft nodes before linearized reading' (duration: 131.989144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:58.419298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.901983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-10-14T15:57:58.419321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.150133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T15:57:58.419354Z","caller":"traceutil/trace.go:171","msg":"trace[367812779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8604; }","duration":"134.195094ms","start":"2026-10-14T15:57:58.285149Z","end":"2026-10-14T15:57:58.419345Z","steps":["trace[367812779] 'agreement among raft nodes before linearized reading' (duration: 134.098971ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:57:58.419432Z","caller":"traceutil/trace.go:171","msg":"trace[485247646] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8604; }","duration":"143.983486ms","start":"2026-10-14T15:57:58.275337Z","end":"2026-10-14T15:57:58.41932Z","steps":["trace[485247646] 'agreement among raft nodes before linearized reading' (duration: 143.891783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:57:58.419451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.951399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T15:57:58.419485Z","caller":"traceutil/trace.go:171","msg":"trace[1141031355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8604; }","duration":"152.016991ms","start":"2026-10-14T15:57:58.267458Z","end":"2026-10-14T15:57:58.419475Z","steps":["trace[1141031355] 'agreement among raft nodes before linearized reading' (duration: 151.918647ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:58:28.077226Z","caller":"traceutil/trace.go:171","msg":"trace[319706396] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"380.889305ms","start":"2026-10-14T15:58:27.696313Z","end":"2026-10-14T15:58:28.077202Z","steps":["trace[319706396] 'process raft request' (duration: 380.677498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:58:28.077419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:58:27.696283Z","time spent":"381.061059ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20168,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:58:32.693526Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c0.wal"} {"level":"warn","ts":"2026-10-14T15:58:37.646305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.417146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:58:37.646429Z","caller":"traceutil/trace.go:171","msg":"trace[1426153125] linearizableReadLoop","detail":"{readStateIndex:9183; appliedIndex:9182; }","duration":"212.39301ms","start":"2026-10-14T15:58:37.434015Z","end":"2026-10-14T15:58:37.646408Z","steps":["trace[1426153125] 'read index received' (duration: 41.601262ms)","trace[1426153125] 'applied index is now lower than readState.Index' (duration: 170.790128ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:58:37.646792Z","caller":"traceutil/trace.go:171","msg":"trace[310235507] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"372.437186ms","start":"2026-10-14T15:58:37.274318Z","end":"2026-10-14T15:58:37.646756Z","steps":["trace[310235507] 'process raft request' (duration: 201.429361ms)","trace[310235507] 'compare' (duration: 170.307063ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:58:37.64694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:58:37.274295Z","time spent":"372.56351ms","remote":"127.0.0.1:42006","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-10-14T15:58:37.646987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.961218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:58:37.647075Z","caller":"traceutil/trace.go:171","msg":"trace[1629763699] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8873; }","duration":"213.094112ms","start":"2026-10-14T15:58:37.433964Z","end":"2026-10-14T15:58:37.647058Z","steps":["trace[1629763699] 'agreement among raft nodes before linearized reading' (duration: 212.754432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:58:37.64707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.854003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:58:37.647138Z","caller":"traceutil/trace.go:171","msg":"trace[1493043276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8873; }","duration":"166.978227ms","start":"2026-10-14T15:58:37.48014Z","end":"2026-10-14T15:58:37.647119Z","steps":["trace[1493043276] 'agreement among raft nodes before linearized reading' (duration: 166.646487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:58:41.887597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.714842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:58:41.887882Z","caller":"traceutil/trace.go:171","msg":"trace[719844954] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"347.72129ms","start":"2026-10-14T15:58:41.540139Z","end":"2026-10-14T15:58:41.88786Z","steps":["trace[719844954] 'process raft request' (duration: 49.679378ms)","trace[719844954] 'compare' (duration: 297.02999ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:58:41.887917Z","caller":"traceutil/trace.go:171","msg":"trace[623550367] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"228.052968ms","start":"2026-10-14T15:58:41.659856Z","end":"2026-10-14T15:58:41.887909Z","steps":["trace[623550367] 'process raft request' (duration: 227.81632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:58:41.887941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:58:41.540117Z","time spent":"347.795892ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:59:56.079709Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T15:59:56.087157Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T15:59:56.087256Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T16:00:06.428255Z","caller":"traceutil/trace.go:171","msg":"trace[1046607530] transaction","detail":"{read_only:false; response_revision:9728; number_of_response:1; }","duration":"135.746887ms","start":"2026-10-14T16:00:06.29248Z","end":"2026-10-14T16:00:06.428227Z","steps":["trace[1046607530] 'process raft request' (duration: 135.566461ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:52.257013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8058} {"level":"info","ts":"2026-10-14T16:01:52.328067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8058,"took":"68.800921ms","hash":839714517,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":28278784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T16:01:52.328136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":839714517,"revision":8058,"compact-revision":5887} {"level":"info","ts":"2026-10-14T16:03:32.040388Z","caller":"traceutil/trace.go:171","msg":"trace[2097486593] transaction","detail":"{read_only:false; response_revision:11470; number_of_response:1; }","duration":"131.632643ms","start":"2026-10-14T16:03:31.908732Z","end":"2026-10-14T16:03:32.040365Z","steps":["trace[2097486593] 'process raft request' (duration: 131.495439ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:06:52.264878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10495} {"level":"info","ts":"2026-10-14T16:06:52.361781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10495,"took":"94.555661ms","hash":1774452982,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":27746304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T16:06:52.36185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1774452982,"revision":10495,"compact-revision":8058} {"level":"info","ts":"2026-10-14T16:09:44.65286Z","caller":"traceutil/trace.go:171","msg":"trace[2009379951] transaction","detail":"{read_only:false; response_revision:14020; number_of_response:1; }","duration":"101.887101ms","start":"2026-10-14T16:09:44.550949Z","end":"2026-10-14T16:09:44.652836Z","steps":["trace[2009379951] 'process raft request' (duration: 101.760537ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:09:44.652861Z","caller":"traceutil/trace.go:171","msg":"trace[819794878] linearizableReadLoop","detail":"{readStateIndex:14493; appliedIndex:14492; }","duration":"100.052613ms","start":"2026-10-14T16:09:44.55278Z","end":"2026-10-14T16:09:44.652832Z","steps":["trace[819794878] 'read index received' (duration: 100.037882ms)","trace[819794878] 'applied index is now lower than readState.Index' (duration: 12.871µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:09:44.653033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.228018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T16:09:44.653119Z","caller":"traceutil/trace.go:171","msg":"trace[868887537] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14020; }","duration":"100.359272ms","start":"2026-10-14T16:09:44.552742Z","end":"2026-10-14T16:09:44.653101Z","steps":["trace[868887537] 'agreement among raft nodes before linearized reading' (duration: 100.126055ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:09:48.657079Z","caller":"traceutil/trace.go:171","msg":"trace[938369778] linearizableReadLoop","detail":"{readStateIndex:14519; appliedIndex:14518; }","duration":"125.141856ms","start":"2026-10-14T16:09:48.53192Z","end":"2026-10-14T16:09:48.657062Z","steps":["trace[938369778] 'read index received' (duration: 125.038183ms)","trace[938369778] 'applied index is now lower than readState.Index' (duration: 102.773µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:09:48.657247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.309981ms","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-10-14T16:09:48.657278Z","caller":"traceutil/trace.go:171","msg":"trace[1777247234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14045; }","duration":"125.383863ms","start":"2026-10-14T16:09:48.531884Z","end":"2026-10-14T16:09:48.657268Z","steps":["trace[1777247234] 'agreement among raft nodes before linearized reading' (duration: 125.246279ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:09:48.65757Z","caller":"traceutil/trace.go:171","msg":"trace[190466275] transaction","detail":"{read_only:false; response_revision:14045; number_of_response:1; }","duration":"129.846155ms","start":"2026-10-14T16:09:48.527717Z","end":"2026-10-14T16:09:48.657563Z","steps":["trace[190466275] 'process raft request' (duration: 129.206635ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:10:26.903472Z","caller":"traceutil/trace.go:171","msg":"trace[777220857] linearizableReadLoop","detail":"{readStateIndex:14740; appliedIndex:14739; }","duration":"428.496667ms","start":"2026-10-14T16:10:26.474951Z","end":"2026-10-14T16:10:26.903447Z","steps":["trace[777220857] 'read index received' (duration: 428.353892ms)","trace[777220857] 'applied index is now lower than readState.Index' (duration: 141.815µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:10:26.903566Z","caller":"traceutil/trace.go:171","msg":"trace[627480089] transaction","detail":"{read_only:false; response_revision:14256; number_of_response:1; }","duration":"435.29863ms","start":"2026-10-14T16:10:26.468233Z","end":"2026-10-14T16:10:26.903532Z","steps":["trace[627480089] 'process raft request' (duration: 435.042712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:10:26.903727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:10:26.468212Z","time spent":"435.426345ms","remote":"127.0.0.1:42006","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-10-14T16:10:26.904326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.676622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:10:26.904403Z","caller":"traceutil/trace.go:171","msg":"trace[1425580685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14256; }","duration":"429.477628ms","start":"2026-10-14T16:10:26.474909Z","end":"2026-10-14T16:10:26.904386Z","steps":["trace[1425580685] 'agreement among raft nodes before linearized reading' (duration: 428.678162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:10:26.904402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.26192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T16:10:26.904563Z","caller":"traceutil/trace.go:171","msg":"trace[1330348159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14257; }","duration":"366.458946ms","start":"2026-10-14T16:10:26.538074Z","end":"2026-10-14T16:10:26.904533Z","steps":["trace[1330348159] 'agreement among raft nodes before linearized reading' (duration: 366.115155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:10:26.904445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:10:26.474889Z","time spent":"429.5408ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:10:26.904624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:10:26.537992Z","time spent":"366.61826ms","remote":"127.0.0.1:42006","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-10-14T16:10:26.904731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.163387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-gngvp\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-10-14T16:10:26.904898Z","caller":"traceutil/trace.go:171","msg":"trace[989643371] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-gngvp; range_end:; response_count:1; response_revision:14257; }","duration":"274.469617ms","start":"2026-10-14T16:10:26.630399Z","end":"2026-10-14T16:10:26.904868Z","steps":["trace[989643371] 'agreement among raft nodes before linearized reading' (duration: 273.940631ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:10:29.02599Z","caller":"traceutil/trace.go:171","msg":"trace[371585636] transaction","detail":"{read_only:false; response_revision:14275; number_of_response:1; }","duration":"113.081935ms","start":"2026-10-14T16:10:28.912885Z","end":"2026-10-14T16:10:29.025967Z","steps":["trace[371585636] 'process raft request' (duration: 112.948961ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:10:50.144185Z","caller":"traceutil/trace.go:171","msg":"trace[788530071] linearizableReadLoop","detail":"{readStateIndex:15070; appliedIndex:15069; }","duration":"162.911351ms","start":"2026-10-14T16:10:49.981246Z","end":"2026-10-14T16:10:50.144158Z","steps":["trace[788530071] 'read index received' (duration: 162.742105ms)","trace[788530071] 'applied index is now lower than readState.Index' (duration: 168.246µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:10:50.144303Z","caller":"traceutil/trace.go:171","msg":"trace[1346656293] transaction","detail":"{read_only:false; response_revision:14572; number_of_response:1; }","duration":"298.59046ms","start":"2026-10-14T16:10:49.845702Z","end":"2026-10-14T16:10:50.144293Z","steps":["trace[1346656293] 'process raft request' (duration: 298.29449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:10:50.144594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.335074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-376bc9b9-9ce2-4f33-b456-5dd97695a3c9\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-10-14T16:10:50.144712Z","caller":"traceutil/trace.go:171","msg":"trace[1026718166] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-376bc9b9-9ce2-4f33-b456-5dd97695a3c9; range_end:; response_count:1; response_revision:14572; }","duration":"163.497279ms","start":"2026-10-14T16:10:49.981196Z","end":"2026-10-14T16:10:50.144693Z","steps":["trace[1026718166] 'agreement among raft nodes before linearized reading' (duration: 163.228151ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:10:50.235651Z","caller":"traceutil/trace.go:171","msg":"trace[220719135] transaction","detail":"{read_only:false; response_revision:14573; number_of_response:1; }","duration":"131.321251ms","start":"2026-10-14T16:10:50.104274Z","end":"2026-10-14T16:10:50.235595Z","steps":["trace[220719135] 'process raft request' (duration: 131.115355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:10:50.235775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.627659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-14T16:10:50.235897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.325648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n2sw8\" ","response":"range_response_count:1 size:20654"} {"level":"info","ts":"2026-10-14T16:10:50.23589Z","caller":"traceutil/trace.go:171","msg":"trace[581204586] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14573; }","duration":"142.782834ms","start":"2026-10-14T16:10:50.093081Z","end":"2026-10-14T16:10:50.235863Z","steps":["trace[581204586] 'agreement among raft nodes before linearized reading' (duration: 142.473924ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:10:50.235984Z","caller":"traceutil/trace.go:171","msg":"trace[285913731] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-n2sw8; range_end:; response_count:1; response_revision:14573; }","duration":"110.476762ms","start":"2026-10-14T16:10:50.125488Z","end":"2026-10-14T16:10:50.235965Z","steps":["trace[285913731] 'agreement among raft nodes before linearized reading' (duration: 110.165252ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:11:52.274946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12805} {"level":"info","ts":"2026-10-14T16:11:52.365364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12805,"took":"85.75621ms","hash":1967898012,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":30998528,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:11:52.365453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1967898012,"revision":12805,"compact-revision":10495} {"level":"info","ts":"2026-10-14T16:12:00.654977Z","caller":"traceutil/trace.go:171","msg":"trace[1713790206] transaction","detail":"{read_only:false; response_revision:15304; number_of_response:1; }","duration":"157.756402ms","start":"2026-10-14T16:12:00.497203Z","end":"2026-10-14T16:12:00.65496Z","steps":["trace[1713790206] 'process raft request' (duration: 157.657639ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:00.659514Z","caller":"traceutil/trace.go:171","msg":"trace[1977921009] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"109.342211ms","start":"2026-10-14T16:12:00.550141Z","end":"2026-10-14T16:12:00.659483Z","steps":["trace[1977921009] 'process raft request' (duration: 109.117334ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:06.313583Z","caller":"traceutil/trace.go:171","msg":"trace[121660290] transaction","detail":"{read_only:false; response_revision:15339; number_of_response:1; }","duration":"102.203853ms","start":"2026-10-14T16:12:06.211324Z","end":"2026-10-14T16:12:06.313528Z","steps":["trace[121660290] 'process raft request' (duration: 101.953905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:07.757183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.020604ms","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-10-14T16:12:07.757288Z","caller":"traceutil/trace.go:171","msg":"trace[1380551213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15342; }","duration":"209.176879ms","start":"2026-10-14T16:12:07.548088Z","end":"2026-10-14T16:12:07.757265Z","steps":["trace[1380551213] 'range keys from in-memory index tree' (duration: 208.802788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:07.757381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.633621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T16:12:07.757474Z","caller":"traceutil/trace.go:171","msg":"trace[1163874418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15342; }","duration":"229.772455ms","start":"2026-10-14T16:12:07.527678Z","end":"2026-10-14T16:12:07.757451Z","steps":["trace[1163874418] 'range keys from in-memory index tree' (duration: 229.402544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:07.757617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.465784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T16:12:07.757712Z","caller":"traceutil/trace.go:171","msg":"trace[793130011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15342; }","duration":"228.572637ms","start":"2026-10-14T16:12:07.5291Z","end":"2026-10-14T16:12:07.757672Z","steps":["trace[793130011] 'range keys from in-memory index tree' (duration: 228.214126ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:29.005538Z","caller":"traceutil/trace.go:171","msg":"trace[553875885] transaction","detail":"{read_only:false; response_revision:15451; number_of_response:1; }","duration":"173.354858ms","start":"2026-10-14T16:12:28.832148Z","end":"2026-10-14T16:12:29.005503Z","steps":["trace[553875885] 'process raft request' (duration: 171.399696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:29.005659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.435438ms","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-10-14T16:12:29.005709Z","caller":"traceutil/trace.go:171","msg":"trace[1949285279] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15451; }","duration":"149.538252ms","start":"2026-10-14T16:12:28.85616Z","end":"2026-10-14T16:12:29.005698Z","steps":["trace[1949285279] 'agreement among raft nodes before linearized reading' (duration: 149.444549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:29.005524Z","caller":"traceutil/trace.go:171","msg":"trace[1169639351] linearizableReadLoop","detail":"{readStateIndex:15975; appliedIndex:15974; }","duration":"149.269612ms","start":"2026-10-14T16:12:28.856203Z","end":"2026-10-14T16:12:29.005473Z","steps":["trace[1169639351] 'read index received' (duration: 147.244418ms)","trace[1169639351] 'applied index is now lower than readState.Index' (duration: 2.024244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:12:29.006086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.844641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-14T16:12:29.006122Z","caller":"traceutil/trace.go:171","msg":"trace[1597671898] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15451; }","duration":"149.922304ms","start":"2026-10-14T16:12:28.856188Z","end":"2026-10-14T16:12:29.006111Z","steps":["trace[1597671898] 'agreement among raft nodes before linearized reading' (duration: 149.80057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:29.006108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.300677ms","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-10-14T16:12:29.006207Z","caller":"traceutil/trace.go:171","msg":"trace[1583472215] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15451; }","duration":"140.435651ms","start":"2026-10-14T16:12:28.865748Z","end":"2026-10-14T16:12:29.006183Z","steps":["trace[1583472215] 'agreement among raft nodes before linearized reading' (duration: 140.290227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:29.006309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.079771ms","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-10-14T16:12:29.006344Z","caller":"traceutil/trace.go:171","msg":"trace[1891849460] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15451; }","duration":"146.131142ms","start":"2026-10-14T16:12:28.860203Z","end":"2026-10-14T16:12:29.006334Z","steps":["trace[1891849460] 'agreement among raft nodes before linearized reading' (duration: 146.048539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:29.006502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.047948ms","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-10-14T16:12:29.006547Z","caller":"traceutil/trace.go:171","msg":"trace[275610245] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15451; }","duration":"150.12639ms","start":"2026-10-14T16:12:28.856407Z","end":"2026-10-14T16:12:29.006534Z","steps":["trace[275610245] 'agreement among raft nodes before linearized reading' (duration: 150.053298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:29.006547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.076868ms","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-10-14T16:12:29.006612Z","caller":"traceutil/trace.go:171","msg":"trace[150745524] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15451; }","duration":"150.163361ms","start":"2026-10-14T16:12:28.856436Z","end":"2026-10-14T16:12:29.0066Z","steps":["trace[150745524] 'agreement among raft nodes before linearized reading' (duration: 150.072608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:29.006681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.340532ms","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-10-14T16:12:29.006778Z","caller":"traceutil/trace.go:171","msg":"trace[767228667] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15451; }","duration":"104.474886ms","start":"2026-10-14T16:12:28.902278Z","end":"2026-10-14T16:12:29.006753Z","steps":["trace[767228667] 'agreement among raft nodes before linearized reading' (duration: 104.167456ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:22.612826Z","caller":"traceutil/trace.go:171","msg":"trace[2137581248] transaction","detail":"{read_only:false; response_revision:15817; number_of_response:1; }","duration":"114.865587ms","start":"2026-10-14T16:13:22.497929Z","end":"2026-10-14T16:13:22.612795Z","steps":["trace[2137581248] 'process raft request' (duration: 98.815627ms)","trace[2137581248] 'compare' (duration: 15.442551ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:15:03.648413Z","caller":"traceutil/trace.go:171","msg":"trace[341249293] transaction","detail":"{read_only:false; response_revision:16591; number_of_response:1; }","duration":"116.323771ms","start":"2026-10-14T16:15:03.532044Z","end":"2026-10-14T16:15:03.648368Z","steps":["trace[341249293] 'process raft request' (duration: 115.96462ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:05.543255Z","caller":"traceutil/trace.go:171","msg":"trace[1015855089] linearizableReadLoop","detail":"{readStateIndex:17163; appliedIndex:17162; }","duration":"134.606924ms","start":"2026-10-14T16:15:05.408621Z","end":"2026-10-14T16:15:05.543228Z","steps":["trace[1015855089] 'read index received' (duration: 133.957424ms)","trace[1015855089] 'applied index is now lower than readState.Index' (duration: 647.85µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:15:05.543316Z","caller":"traceutil/trace.go:171","msg":"trace[128535245] transaction","detail":"{read_only:false; response_revision:16601; number_of_response:1; }","duration":"138.118485ms","start":"2026-10-14T16:15:05.405169Z","end":"2026-10-14T16:15:05.543288Z","steps":["trace[128535245] 'process raft request' (duration: 137.397722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:05.544186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.545263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:82 size:795283"} {"level":"info","ts":"2026-10-14T16:15:05.544243Z","caller":"traceutil/trace.go:171","msg":"trace[553555214] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:82; response_revision:16601; }","duration":"135.652436ms","start":"2026-10-14T16:15:05.408577Z","end":"2026-10-14T16:15:05.544229Z","steps":["trace[553555214] 'agreement among raft nodes before linearized reading' (duration: 134.790739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:05.545375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.776645ms","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-10-14T16:15:05.545473Z","caller":"traceutil/trace.go:171","msg":"trace[467797191] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:16602; }","duration":"126.904689ms","start":"2026-10-14T16:15:05.418543Z","end":"2026-10-14T16:15:05.545448Z","steps":["trace[467797191] 'agreement among raft nodes before linearized reading' (duration: 126.62056ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:10.252955Z","caller":"traceutil/trace.go:171","msg":"trace[1920701978] transaction","detail":"{read_only:false; response_revision:16812; number_of_response:1; }","duration":"107.021055ms","start":"2026-10-14T16:15:10.145904Z","end":"2026-10-14T16:15:10.252925Z","steps":["trace[1920701978] 'process raft request' (duration: 106.753397ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:16:52.28503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15263} {"level":"info","ts":"2026-10-14T16:16:52.391634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15263,"took":"103.30726ms","hash":3552480643,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":31776768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T16:16:52.39172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3552480643,"revision":15263,"compact-revision":12805} {"level":"info","ts":"2026-10-14T16:18:04.593434Z","caller":"traceutil/trace.go:171","msg":"trace[1131321599] linearizableReadLoop","detail":"{readStateIndex:18582; appliedIndex:18581; }","duration":"120.050112ms","start":"2026-10-14T16:18:04.47336Z","end":"2026-10-14T16:18:04.59341Z","steps":["trace[1131321599] 'read index received' (duration: 106.781051ms)","trace[1131321599] 'applied index is now lower than readState.Index' (duration: 13.267921ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:18:04.593508Z","caller":"traceutil/trace.go:171","msg":"trace[1940111850] transaction","detail":"{read_only:false; response_revision:17976; number_of_response:1; }","duration":"263.518298ms","start":"2026-10-14T16:18:04.329959Z","end":"2026-10-14T16:18:04.593478Z","steps":["trace[1940111850] 'process raft request' (duration: 250.254237ms)","trace[1940111850] 'compare' (duration: 12.861238ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:18:04.593593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.225588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:04.59363Z","caller":"traceutil/trace.go:171","msg":"trace[271520673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17976; }","duration":"120.31178ms","start":"2026-10-14T16:18:04.473308Z","end":"2026-10-14T16:18:04.59362Z","steps":["trace[271520673] 'agreement among raft nodes before linearized reading' (duration: 120.196137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:11.304753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.518449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-14T16:18:11.306045Z","caller":"traceutil/trace.go:171","msg":"trace[1654491306] linearizableReadLoop","detail":"{readStateIndex:18620; appliedIndex:18618; }","duration":"264.283482ms","start":"2026-10-14T16:18:11.041725Z","end":"2026-10-14T16:18:11.306008Z","steps":["trace[1654491306] 'read index received' (duration: 228.826836ms)","trace[1654491306] 'applied index is now lower than readState.Index' (duration: 35.455516ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:18:11.306084Z","caller":"traceutil/trace.go:171","msg":"trace[1375992277] transaction","detail":"{read_only:false; response_revision:18012; number_of_response:1; }","duration":"284.616749ms","start":"2026-10-14T16:18:11.02143Z","end":"2026-10-14T16:18:11.306047Z","steps":["trace[1375992277] 'process raft request' (duration: 283.443091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:11.391703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.971534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n2sw8\" ","response":"range_response_count:1 size:20757"} {"level":"info","ts":"2026-10-14T16:18:11.39181Z","caller":"traceutil/trace.go:171","msg":"trace[2143677685] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-n2sw8; range_end:; response_count:1; response_revision:18012; }","duration":"350.118528ms","start":"2026-10-14T16:18:11.041665Z","end":"2026-10-14T16:18:11.391783Z","steps":["trace[2143677685] 'agreement among raft nodes before linearized reading' (duration: 264.420257ms)","trace[2143677685] 'range keys from in-memory index tree' (duration: 85.497545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:18:11.391852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.041642Z","time spent":"350.200191ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20781,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n2sw8\" "} {"level":"info","ts":"2026-10-14T16:18:11.39216Z","caller":"traceutil/trace.go:171","msg":"trace[2118137686] transaction","detail":"{read_only:false; response_revision:18013; number_of_response:1; }","duration":"310.610674ms","start":"2026-10-14T16:18:11.081513Z","end":"2026-10-14T16:18:11.392123Z","steps":["trace[2118137686] 'process raft request' (duration: 284.297708ms)","trace[2118137686] 'compare' (duration: 25.4904ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:18:11.392352Z","caller":"traceutil/trace.go:171","msg":"trace[1594281592] transaction","detail":"{read_only:false; response_revision:18014; number_of_response:1; }","duration":"159.04122ms","start":"2026-10-14T16:18:11.233283Z","end":"2026-10-14T16:18:11.392325Z","steps":["trace[1594281592] 'process raft request' (duration: 158.646027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:11.392387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.081483Z","time spent":"310.8214ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:18:11.976584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906287314990246145,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T16:18:12.329706Z","caller":"traceutil/trace.go:171","msg":"trace[825857685] transaction","detail":"{read_only:false; response_revision:18015; number_of_response:1; }","duration":"914.305764ms","start":"2026-10-14T16:18:11.415355Z","end":"2026-10-14T16:18:12.32966Z","steps":["trace[825857685] 'process raft request' (duration: 914.130258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.32991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.415332Z","time spent":"914.465039ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20872,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:18:12.374811Z","caller":"traceutil/trace.go:171","msg":"trace[996186639] linearizableReadLoop","detail":"{readStateIndex:18624; appliedIndex:18622; }","duration":"898.610905ms","start":"2026-10-14T16:18:11.476176Z","end":"2026-10-14T16:18:12.374787Z","steps":["trace[996186639] 'read index received' (duration: 853.280416ms)","trace[996186639] 'applied index is now lower than readState.Index' (duration: 45.302128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:18:12.374946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.759131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:12.374977Z","caller":"traceutil/trace.go:171","msg":"trace[1912482607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18016; }","duration":"898.832293ms","start":"2026-10-14T16:18:11.476133Z","end":"2026-10-14T16:18:12.374965Z","steps":["trace[1912482607] 'agreement among raft nodes before linearized reading' (duration: 898.765301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.375055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.47612Z","time spent":"898.883464ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-14T16:18:12.374944Z","caller":"traceutil/trace.go:171","msg":"trace[1419608033] transaction","detail":"{read_only:false; response_revision:18016; number_of_response:1; }","duration":"919.000263ms","start":"2026-10-14T16:18:11.455897Z","end":"2026-10-14T16:18:12.374897Z","steps":["trace[1419608033] 'process raft request' (duration: 918.733404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.375234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.455872Z","time spent":"919.247741ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:18:12.733676Z","caller":"traceutil/trace.go:171","msg":"trace[176370571] transaction","detail":"{read_only:false; response_revision:18018; number_of_response:1; }","duration":"109.654942ms","start":"2026-10-14T16:18:12.623992Z","end":"2026-10-14T16:18:12.733647Z","steps":["trace[176370571] 'process raft request' (duration: 109.57971ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:12.734108Z","caller":"traceutil/trace.go:171","msg":"trace[2138419013] linearizableReadLoop","detail":"{readStateIndex:18625; appliedIndex:18624; }","duration":"359.144545ms","start":"2026-10-14T16:18:12.374878Z","end":"2026-10-14T16:18:12.734023Z","steps":["trace[2138419013] 'read index received' (duration: 264.402377ms)","trace[2138419013] 'applied index is now lower than readState.Index' (duration: 94.739028ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:18:12.734311Z","caller":"traceutil/trace.go:171","msg":"trace[1840753790] transaction","detail":"{read_only:false; response_revision:18017; number_of_response:1; }","duration":"372.130316ms","start":"2026-10-14T16:18:12.362165Z","end":"2026-10-14T16:18:12.734295Z","steps":["trace[1840753790] 'process raft request' (duration: 277.100199ms)","trace[1840753790] 'compare' (duration: 94.14772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:18:12.73449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:12.362137Z","time spent":"372.241691ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:18:12.734545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.676057ms","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-10-14T16:18:12.734611Z","caller":"traceutil/trace.go:171","msg":"trace[886964827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18018; }","duration":"812.772341ms","start":"2026-10-14T16:18:11.921821Z","end":"2026-10-14T16:18:12.734593Z","steps":["trace[886964827] 'agreement among raft nodes before linearized reading' (duration: 812.585905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.73466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.921813Z","time spent":"812.835583ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T16:18:12.734823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.392299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:12.734875Z","caller":"traceutil/trace.go:171","msg":"trace[558352817] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:18018; }","duration":"522.492502ms","start":"2026-10-14T16:18:12.212368Z","end":"2026-10-14T16:18:12.73486Z","steps":["trace[558352817] 'agreement among raft nodes before linearized reading' (duration: 522.389049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.73493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:12.212351Z","time spent":"522.564845ms","remote":"127.0.0.1:43066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:18:12.735188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.729041ms","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-10-14T16:18:12.735273Z","caller":"traceutil/trace.go:171","msg":"trace[348882944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18018; }","duration":"813.847965ms","start":"2026-10-14T16:18:11.92141Z","end":"2026-10-14T16:18:12.735258Z","steps":["trace[348882944] 'agreement among raft nodes before linearized reading' (duration: 813.495204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.735282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.385626ms","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-10-14T16:18:12.735336Z","caller":"traceutil/trace.go:171","msg":"trace[248039897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18018; }","duration":"697.441158ms","start":"2026-10-14T16:18:12.037881Z","end":"2026-10-14T16:18:12.735322Z","steps":["trace[248039897] 'agreement among raft nodes before linearized reading' (duration: 697.282283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.73534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.921393Z","time spent":"813.904156ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T16:18:12.735371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:12.037868Z","time spent":"697.494769ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T16:18:12.735554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.400766ms","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-10-14T16:18:12.735592Z","caller":"traceutil/trace.go:171","msg":"trace[869231002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18018; }","duration":"742.459887ms","start":"2026-10-14T16:18:11.993122Z","end":"2026-10-14T16:18:12.735582Z","steps":["trace[869231002] 'agreement among raft nodes before linearized reading' (duration: 742.328923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.735615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.993112Z","time spent":"742.497209ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T16:18:12.73574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.712215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kcqft\" ","response":"range_response_count:1 size:9793"} {"level":"warn","ts":"2026-10-14T16:18:12.735831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.461136ms","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-10-14T16:18:12.735863Z","caller":"traceutil/trace.go:171","msg":"trace[1352941215] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18018; }","duration":"776.525278ms","start":"2026-10-14T16:18:11.959327Z","end":"2026-10-14T16:18:12.735852Z","steps":["trace[1352941215] 'agreement among raft nodes before linearized reading' (duration: 776.411905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.735889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.959309Z","time spent":"776.57115ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:18:12.735884Z","caller":"traceutil/trace.go:171","msg":"trace[1065307505] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kcqft; range_end:; response_count:1; response_revision:18018; }","duration":"850.833389ms","start":"2026-10-14T16:18:11.884954Z","end":"2026-10-14T16:18:12.735787Z","steps":["trace[1065307505] 'agreement among raft nodes before linearized reading' (duration: 850.617613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.735967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.884937Z","time spent":"851.018385ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9817,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-kcqft\" "} {"level":"warn","ts":"2026-10-14T16:18:12.736623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.504836ms","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-10-14T16:18:12.736818Z","caller":"traceutil/trace.go:171","msg":"trace[2068375095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18018; }","duration":"912.796405ms","start":"2026-10-14T16:18:11.824009Z","end":"2026-10-14T16:18:12.736805Z","steps":["trace[2068375095] 'agreement among raft nodes before linearized reading' (duration: 912.419673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.736926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:11.82399Z","time spent":"912.92428ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T16:18:12.737008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.851324ms","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-10-14T16:18:12.737053Z","caller":"traceutil/trace.go:171","msg":"trace[1259793693] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18018; }","duration":"147.922447ms","start":"2026-10-14T16:18:12.58912Z","end":"2026-10-14T16:18:12.737043Z","steps":["trace[1259793693] 'agreement among raft nodes before linearized reading' (duration: 147.637538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.737023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.686281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:12.737199Z","caller":"traceutil/trace.go:171","msg":"trace[2016147650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18018; }","duration":"360.000862ms","start":"2026-10-14T16:18:12.377179Z","end":"2026-10-14T16:18:12.737179Z","steps":["trace[2016147650] 'agreement among raft nodes before linearized reading' (duration: 359.514276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:12.737297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:18:12.377164Z","time spent":"360.120606ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:18:21.670091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.499163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5q9xp\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-10-14T16:18:21.670171Z","caller":"traceutil/trace.go:171","msg":"trace[767814886] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5q9xp; range_end:; response_count:1; response_revision:18213; }","duration":"101.591116ms","start":"2026-10-14T16:18:21.568561Z","end":"2026-10-14T16:18:21.670152Z","steps":["trace[767814886] 'range keys from in-memory index tree' (duration: 101.243445ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:57.105338Z","caller":"traceutil/trace.go:171","msg":"trace[1235967733] transaction","detail":"{read_only:false; response_revision:18579; number_of_response:1; }","duration":"164.398456ms","start":"2026-10-14T16:18:56.940915Z","end":"2026-10-14T16:18:57.105313Z","steps":["trace[1235967733] 'process raft request' (duration: 164.22095ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:57.143437Z","caller":"traceutil/trace.go:171","msg":"trace[390646860] linearizableReadLoop","detail":"{readStateIndex:19204; appliedIndex:19202; }","duration":"192.014077ms","start":"2026-10-14T16:18:56.951399Z","end":"2026-10-14T16:18:57.143413Z","steps":["trace[390646860] 'read index received' (duration: 153.747588ms)","trace[390646860] 'applied index is now lower than readState.Index' (duration: 38.265319ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:18:57.143518Z","caller":"traceutil/trace.go:171","msg":"trace[1838261119] transaction","detail":"{read_only:false; response_revision:18580; number_of_response:1; }","duration":"202.001714ms","start":"2026-10-14T16:18:56.941481Z","end":"2026-10-14T16:18:57.143483Z","steps":["trace[1838261119] 'process raft request' (duration: 201.800318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:57.143587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.169421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:57.143626Z","caller":"traceutil/trace.go:171","msg":"trace[1560945942] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18580; }","duration":"192.268544ms","start":"2026-10-14T16:18:56.951348Z","end":"2026-10-14T16:18:57.143616Z","steps":["trace[1560945942] 'agreement among raft nodes before linearized reading' (duration: 192.191082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:57.143712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.207303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:18:57.143836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.256382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:18:57.143776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.333524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:57.143886Z","caller":"traceutil/trace.go:171","msg":"trace[502640972] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18580; }","duration":"191.323024ms","start":"2026-10-14T16:18:56.95255Z","end":"2026-10-14T16:18:57.143873Z","steps":["trace[502640972] 'agreement among raft nodes before linearized reading' (duration: 191.221021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:57.143898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.544641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:18:57.143945Z","caller":"traceutil/trace.go:171","msg":"trace[604611470] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18580; }","duration":"191.604743ms","start":"2026-10-14T16:18:56.952328Z","end":"2026-10-14T16:18:57.143933Z","steps":["trace[604611470] 'agreement among raft nodes before linearized reading' (duration: 191.535891ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:57.143904Z","caller":"traceutil/trace.go:171","msg":"trace[817155378] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18580; }","duration":"191.51932ms","start":"2026-10-14T16:18:56.952368Z","end":"2026-10-14T16:18:57.143887Z","steps":["trace[817155378] 'agreement among raft nodes before linearized reading' (duration: 191.322004ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:57.143824Z","caller":"traceutil/trace.go:171","msg":"trace[808474039] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18580; }","duration":"192.383918ms","start":"2026-10-14T16:18:56.951415Z","end":"2026-10-14T16:18:57.143799Z","steps":["trace[808474039] 'agreement among raft nodes before linearized reading' (duration: 192.195882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:18:57.144132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.792439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:18:57.144166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.037015ms","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-10-14T16:18:57.144221Z","caller":"traceutil/trace.go:171","msg":"trace[1677712319] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18580; }","duration":"121.130338ms","start":"2026-10-14T16:18:57.023076Z","end":"2026-10-14T16:18:57.144207Z","steps":["trace[1677712319] 'agreement among raft nodes before linearized reading' (duration: 120.606421ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:18:57.144276Z","caller":"traceutil/trace.go:171","msg":"trace[803388790] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:18580; }","duration":"191.918233ms","start":"2026-10-14T16:18:56.952281Z","end":"2026-10-14T16:18:57.144199Z","steps":["trace[803388790] 'agreement among raft nodes before linearized reading' (duration: 191.685685ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:20:41.951726Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:20:41.967712Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T16:20:41.967883Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T16:20:45.923803Z","caller":"traceutil/trace.go:171","msg":"trace[145471822] linearizableReadLoop","detail":"{readStateIndex:20025; appliedIndex:20024; }","duration":"119.351952ms","start":"2026-10-14T16:20:45.804427Z","end":"2026-10-14T16:20:45.923779Z","steps":["trace[145471822] 'read index received' (duration: 119.214497ms)","trace[145471822] 'applied index is now lower than readState.Index' (duration: 135.865µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:20:45.924245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.788745ms","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-10-14T16:20:45.924368Z","caller":"traceutil/trace.go:171","msg":"trace[262864099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19377; }","duration":"119.93973ms","start":"2026-10-14T16:20:45.804395Z","end":"2026-10-14T16:20:45.924335Z","steps":["trace[262864099] 'agreement among raft nodes before linearized reading' (duration: 119.596309ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:20:45.924565Z","caller":"traceutil/trace.go:171","msg":"trace[288639828] transaction","detail":"{read_only:false; response_revision:19377; number_of_response:1; }","duration":"126.011923ms","start":"2026-10-14T16:20:45.797854Z","end":"2026-10-14T16:20:45.923866Z","steps":["trace[288639828] 'process raft request' (duration: 125.783416ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:20:45.925711Z","caller":"traceutil/trace.go:171","msg":"trace[390628925] transaction","detail":"{read_only:false; response_revision:19378; number_of_response:1; }","duration":"121.19526ms","start":"2026-10-14T16:20:45.804499Z","end":"2026-10-14T16:20:45.925695Z","steps":["trace[390628925] 'process raft request' (duration: 120.725735ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:20:48.209641Z","caller":"traceutil/trace.go:171","msg":"trace[1368003719] transaction","detail":"{read_only:false; response_revision:19393; number_of_response:1; }","duration":"112.40269ms","start":"2026-10-14T16:20:48.097215Z","end":"2026-10-14T16:20:48.209618Z","steps":["trace[1368003719] 'process raft request' (duration: 88.54677ms)","trace[1368003719] 'compare' (duration: 23.734636ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:52.292533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17465} {"level":"info","ts":"2026-10-14T16:21:52.39735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17465,"took":"100.812691ms","hash":3976086271,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":34127872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T16:21:52.397422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3976086271,"revision":17465,"compact-revision":15263} {"level":"info","ts":"2026-10-14T16:23:35.50613Z","caller":"traceutil/trace.go:171","msg":"trace[958932685] linearizableReadLoop","detail":"{readStateIndex:21268; appliedIndex:21267; }","duration":"217.521387ms","start":"2026-10-14T16:23:35.288577Z","end":"2026-10-14T16:23:35.506099Z","steps":["trace[958932685] 'read index received' (duration: 217.112283ms)","trace[958932685] 'applied index is now lower than readState.Index' (duration: 407.744µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:23:35.506204Z","caller":"traceutil/trace.go:171","msg":"trace[870565941] transaction","detail":"{read_only:false; response_revision:20577; number_of_response:1; }","duration":"244.70843ms","start":"2026-10-14T16:23:35.261459Z","end":"2026-10-14T16:23:35.506167Z","steps":["trace[870565941] 'process raft request' (duration: 244.380668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:23:35.506463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.857877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:23:35.506591Z","caller":"traceutil/trace.go:171","msg":"trace[1779314565] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20577; }","duration":"218.037612ms","start":"2026-10-14T16:23:35.28852Z","end":"2026-10-14T16:23:35.506557Z","steps":["trace[1779314565] 'agreement among raft nodes before linearized reading' (duration: 217.758693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:23:52.651957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.342367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:23:52.652088Z","caller":"traceutil/trace.go:171","msg":"trace[23030371] linearizableReadLoop","detail":"{readStateIndex:21358; appliedIndex:21357; }","duration":"181.018248ms","start":"2026-10-14T16:23:52.47105Z","end":"2026-10-14T16:23:52.652068Z","steps":["trace[23030371] 'read index received' (duration: 5.250387ms)","trace[23030371] 'applied index is now lower than readState.Index' (duration: 175.76581ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:23:52.6522Z","caller":"traceutil/trace.go:171","msg":"trace[1819924365] transaction","detail":"{read_only:false; response_revision:20663; number_of_response:1; }","duration":"283.17894ms","start":"2026-10-14T16:23:52.369004Z","end":"2026-10-14T16:23:52.652183Z","steps":["trace[1819924365] 'process raft request' (duration: 107.263165ms)","trace[1819924365] 'compare' (duration: 174.658266ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:23:52.652422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.408169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:23:52.65247Z","caller":"traceutil/trace.go:171","msg":"trace[2124635635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20663; }","duration":"181.496443ms","start":"2026-10-14T16:23:52.470961Z","end":"2026-10-14T16:23:52.652458Z","steps":["trace[2124635635] 'agreement among raft nodes before linearized reading' (duration: 181.40444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:23:53.001333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.01613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:23:53.001704Z","caller":"traceutil/trace.go:171","msg":"trace[960807412] transaction","detail":"{read_only:false; response_revision:20665; number_of_response:1; }","duration":"200.916489ms","start":"2026-10-14T16:23:52.800762Z","end":"2026-10-14T16:23:53.001679Z","steps":["trace[960807412] 'process raft request' (duration: 200.664621ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:23:53.0017Z","caller":"traceutil/trace.go:171","msg":"trace[1784164193] transaction","detail":"{read_only:false; response_revision:20664; number_of_response:1; }","duration":"249.17448ms","start":"2026-10-14T16:23:52.752484Z","end":"2026-10-14T16:23:53.001658Z","steps":["trace[1784164193] 'process raft request' (duration: 105.722516ms)","trace[1784164193] 'compare' (duration: 142.889547ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:24:49.668231Z","caller":"traceutil/trace.go:171","msg":"trace[802391944] transaction","detail":"{read_only:false; response_revision:21228; number_of_response:1; }","duration":"101.831393ms","start":"2026-10-14T16:24:49.566373Z","end":"2026-10-14T16:24:49.668204Z","steps":["trace[802391944] 'process raft request' (duration: 101.645298ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:52.361866Z","caller":"traceutil/trace.go:171","msg":"trace[352606691] transaction","detail":"{read_only:false; response_revision:22074; number_of_response:1; }","duration":"156.693163ms","start":"2026-10-14T16:26:52.205147Z","end":"2026-10-14T16:26:52.36184Z","steps":["trace[352606691] 'process raft request' (duration: 145.523978ms)","trace[352606691] 'compare' (duration: 11.000169ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:52.36217Z","caller":"traceutil/trace.go:171","msg":"trace[1669128455] linearizableReadLoop","detail":"{readStateIndex:22818; appliedIndex:22816; }","duration":"149.07458ms","start":"2026-10-14T16:26:52.213075Z","end":"2026-10-14T16:26:52.362149Z","steps":["trace[1669128455] 'read index received' (duration: 137.590646ms)","trace[1669128455] 'applied index is now lower than readState.Index' (duration: 11.483054ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:52.362491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.39614ms","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":"warn","ts":"2026-10-14T16:26:52.362526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.845709ms","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-10-14T16:26:52.362542Z","caller":"traceutil/trace.go:171","msg":"trace[197219481] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22076; }","duration":"149.477193ms","start":"2026-10-14T16:26:52.213052Z","end":"2026-10-14T16:26:52.362529Z","steps":["trace[197219481] 'agreement among raft nodes before linearized reading' (duration: 149.296157ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:52.362581Z","caller":"traceutil/trace.go:171","msg":"trace[403399585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22076; }","duration":"146.913621ms","start":"2026-10-14T16:26:52.215638Z","end":"2026-10-14T16:26:52.362552Z","steps":["trace[403399585] 'agreement among raft nodes before linearized reading' (duration: 146.776617ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:52.362575Z","caller":"traceutil/trace.go:171","msg":"trace[330893430] transaction","detail":"{read_only:false; response_revision:22075; number_of_response:1; }","duration":"153.729278ms","start":"2026-10-14T16:26:52.208828Z","end":"2026-10-14T16:26:52.362557Z","steps":["trace[330893430] 'process raft request' (duration: 152.977714ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:52.381859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19848} {"level":"info","ts":"2026-10-14T16:26:52.504023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19848,"took":"116.467246ms","hash":994945713,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":30482432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T16:26:52.504091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":994945713,"revision":19848,"compact-revision":17465} {"level":"info","ts":"2026-10-14T16:27:54.078167Z","caller":"traceutil/trace.go:171","msg":"trace[1229654304] linearizableReadLoop","detail":"{readStateIndex:23928; appliedIndex:23927; }","duration":"235.175033ms","start":"2026-10-14T16:27:53.842962Z","end":"2026-10-14T16:27:54.078137Z","steps":["trace[1229654304] 'read index received' (duration: 235.017228ms)","trace[1229654304] 'applied index is now lower than readState.Index' (duration: 156.715µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:27:54.078229Z","caller":"traceutil/trace.go:171","msg":"trace[428559398] transaction","detail":"{read_only:false; response_revision:23158; number_of_response:1; }","duration":"354.150809ms","start":"2026-10-14T16:27:53.724044Z","end":"2026-10-14T16:27:54.078195Z","steps":["trace[428559398] 'process raft request' (duration: 353.925122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:27:54.078344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.391429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:27:54.078391Z","caller":"traceutil/trace.go:171","msg":"trace[357247439] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:23158; }","duration":"235.460672ms","start":"2026-10-14T16:27:53.84292Z","end":"2026-10-14T16:27:54.078381Z","steps":["trace[357247439] 'agreement among raft nodes before linearized reading' (duration: 235.356788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:27:54.078482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:27:53.724013Z","time spent":"354.303124ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:27:54.121627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.385871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:27:54.121713Z","caller":"traceutil/trace.go:171","msg":"trace[1634415689] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:23160; }","duration":"276.537215ms","start":"2026-10-14T16:27:53.845154Z","end":"2026-10-14T16:27:54.121691Z","steps":["trace[1634415689] 'agreement among raft nodes before linearized reading' (duration: 276.390411ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:27:54.121735Z","caller":"traceutil/trace.go:171","msg":"trace[880704791] transaction","detail":"{read_only:false; response_revision:23159; number_of_response:1; }","duration":"138.692401ms","start":"2026-10-14T16:27:53.982983Z","end":"2026-10-14T16:27:54.121675Z","steps":["trace[880704791] 'process raft request' (duration: 138.327189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:27:54.121999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.19626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-14T16:27:54.122001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.971652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-10-14T16:27:54.122176Z","caller":"traceutil/trace.go:171","msg":"trace[389185095] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:23160; }","duration":"181.185659ms","start":"2026-10-14T16:27:53.940969Z","end":"2026-10-14T16:27:54.122154Z","steps":["trace[389185095] 'agreement among raft nodes before linearized reading' (duration: 180.664943ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:27:54.122039Z","caller":"traceutil/trace.go:171","msg":"trace[776969495] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:23160; }","duration":"250.275512ms","start":"2026-10-14T16:27:53.871752Z","end":"2026-10-14T16:27:54.122028Z","steps":["trace[776969495] 'agreement among raft nodes before linearized reading' (duration: 250.164028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:10.74106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.249431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:10082"} {"level":"info","ts":"2026-10-14T16:31:10.741215Z","caller":"traceutil/trace.go:171","msg":"trace[1164095157] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:25124; }","duration":"149.420056ms","start":"2026-10-14T16:31:10.59176Z","end":"2026-10-14T16:31:10.74118Z","steps":["trace[1164095157] 'range keys from in-memory index tree' (duration: 148.92675ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:31:10.741914Z","caller":"traceutil/trace.go:171","msg":"trace[1194511829] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"104.999166ms","start":"2026-10-14T16:31:10.636805Z","end":"2026-10-14T16:31:10.741804Z","steps":["trace[1194511829] 'process raft request' (duration: 104.367125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.116309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.640196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:31:20.11641Z","caller":"traceutil/trace.go:171","msg":"trace[2132730230] linearizableReadLoop","detail":"{readStateIndex:26016; appliedIndex:26015; }","duration":"302.818289ms","start":"2026-10-14T16:31:19.813576Z","end":"2026-10-14T16:31:20.116394Z","steps":["trace[2132730230] 'read index received' (duration: 122.933685ms)","trace[2132730230] 'applied index is now lower than readState.Index' (duration: 179.883324ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:31:20.116736Z","caller":"traceutil/trace.go:171","msg":"trace[91444950] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"345.750212ms","start":"2026-10-14T16:31:19.770952Z","end":"2026-10-14T16:31:20.116703Z","steps":["trace[91444950] 'process raft request' (duration: 165.6174ms)","trace[91444950] 'compare' (duration: 179.164911ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:31:20.117022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.907263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-14T16:31:20.117092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:31:19.770912Z","time spent":"346.093802ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:31:20.117113Z","caller":"traceutil/trace.go:171","msg":"trace[2024623578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25197; }","duration":"208.025487ms","start":"2026-10-14T16:31:19.909072Z","end":"2026-10-14T16:31:20.117098Z","steps":["trace[2024623578] 'agreement among raft nodes before linearized reading' (duration: 207.681766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.117183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.693666ms","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-10-14T16:31:20.117212Z","caller":"traceutil/trace.go:171","msg":"trace[963017466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25197; }","duration":"201.745118ms","start":"2026-10-14T16:31:19.915457Z","end":"2026-10-14T16:31:20.117202Z","steps":["trace[963017466] 'agreement among raft nodes before linearized reading' (duration: 201.648315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.117405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.551688ms","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-10-14T16:31:20.117517Z","caller":"traceutil/trace.go:171","msg":"trace[1704570226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25197; }","duration":"193.674832ms","start":"2026-10-14T16:31:19.923816Z","end":"2026-10-14T16:31:20.117491Z","steps":["trace[1704570226] 'agreement among raft nodes before linearized reading' (duration: 193.281529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.117001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.18603ms","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-10-14T16:31:20.118434Z","caller":"traceutil/trace.go:171","msg":"trace[769877414] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25197; }","duration":"304.871884ms","start":"2026-10-14T16:31:19.813527Z","end":"2026-10-14T16:31:20.118399Z","steps":["trace[769877414] 'agreement among raft nodes before linearized reading' (duration: 303.134998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.11853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:31:19.813508Z","time spent":"304.964417ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:31:20.443476Z","caller":"traceutil/trace.go:171","msg":"trace[1028541560] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"321.538943ms","start":"2026-10-14T16:31:20.121903Z","end":"2026-10-14T16:31:20.443442Z","steps":["trace[1028541560] 'process raft request' (duration: 321.312836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.443651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:31:20.121885Z","time spent":"321.675728ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:31:20.44379Z","caller":"traceutil/trace.go:171","msg":"trace[104389659] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"321.485241ms","start":"2026-10-14T16:31:20.12228Z","end":"2026-10-14T16:31:20.443765Z","steps":["trace[104389659] 'process raft request' (duration: 321.103329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.443959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:31:20.12227Z","time spent":"321.591034ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:31:20.443991Z","caller":"traceutil/trace.go:171","msg":"trace[1255274190] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"320.165169ms","start":"2026-10-14T16:31:20.123805Z","end":"2026-10-14T16:31:20.44397Z","steps":["trace[1255274190] 'process raft request' (duration: 319.783837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.444088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:31:20.12378Z","time spent":"320.257212ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:31:20.444481Z","caller":"traceutil/trace.go:171","msg":"trace[1397125026] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"307.194617ms","start":"2026-10-14T16:31:20.137267Z","end":"2026-10-14T16:31:20.444461Z","steps":["trace[1397125026] 'process raft request' (duration: 306.605439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:20.444568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:31:20.137243Z","time spent":"307.28171ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:31:45.170496Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066b8.wal"} {"level":"info","ts":"2026-10-14T16:31:52.388191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22076} {"level":"info","ts":"2026-10-14T16:31:52.50338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22076,"took":"111.208733ms","hash":917744379,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":44797952,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-14T16:31:52.503448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":917744379,"revision":22076,"compact-revision":19848} {"level":"info","ts":"2026-10-14T16:32:18.769603Z","caller":"traceutil/trace.go:171","msg":"trace[1759932571] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"111.167431ms","start":"2026-10-14T16:32:18.658411Z","end":"2026-10-14T16:32:18.769578Z","steps":["trace[1759932571] 'process raft request' (duration: 111.007226ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:32:18.863588Z","caller":"traceutil/trace.go:171","msg":"trace[1593397693] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"172.057047ms","start":"2026-10-14T16:32:18.691489Z","end":"2026-10-14T16:32:18.863546Z","steps":["trace[1593397693] 'process raft request' (duration: 161.236033ms)","trace[1593397693] 'compare' (duration: 10.641128ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:32:24.026857Z","caller":"traceutil/trace.go:171","msg":"trace[425525784] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"176.081883ms","start":"2026-10-14T16:32:23.850753Z","end":"2026-10-14T16:32:24.026835Z","steps":["trace[425525784] 'process raft request' (duration: 175.939478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:24.395791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.022412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:32:24.396358Z","caller":"traceutil/trace.go:171","msg":"trace[945363448] linearizableReadLoop","detail":"{readStateIndex:26605; appliedIndex:26604; }","duration":"303.650846ms","start":"2026-10-14T16:32:24.092683Z","end":"2026-10-14T16:32:24.396334Z","steps":["trace[945363448] 'read index received' (duration: 114.92806ms)","trace[945363448] 'applied index is now lower than readState.Index' (duration: 188.720495ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:32:24.396376Z","caller":"traceutil/trace.go:171","msg":"trace[376478755] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"343.490501ms","start":"2026-10-14T16:32:24.052836Z","end":"2026-10-14T16:32:24.396326Z","steps":["trace[376478755] 'process raft request' (duration: 154.834258ms)","trace[376478755] 'compare' (duration: 187.874018ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:32:24.396706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.052797Z","time spent":"343.833082ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:32:24.396859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.046748ms","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-10-14T16:32:24.397133Z","caller":"traceutil/trace.go:171","msg":"trace[357651656] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25770; }","duration":"304.472041ms","start":"2026-10-14T16:32:24.092639Z","end":"2026-10-14T16:32:24.397111Z","steps":["trace[357651656] 'agreement among raft nodes before linearized reading' (duration: 303.889743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:24.39734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.092614Z","time spent":"304.708359ms","remote":"127.0.0.1:42188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":20,"response size":32,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:32:25.005297Z","caller":"traceutil/trace.go:171","msg":"trace[79896178] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"510.712542ms","start":"2026-10-14T16:32:24.494554Z","end":"2026-10-14T16:32:25.005267Z","steps":["trace[79896178] 'process raft request' (duration: 510.536316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.005508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.869452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-14T16:32:25.005526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.494502Z","time spent":"510.889927ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:32:25.005558Z","caller":"traceutil/trace.go:171","msg":"trace[1295967507] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25771; }","duration":"479.941924ms","start":"2026-10-14T16:32:24.525607Z","end":"2026-10-14T16:32:25.005549Z","steps":["trace[1295967507] 'agreement among raft nodes before linearized reading' (duration: 479.774939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.005591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.525556Z","time spent":"480.026327ms","remote":"127.0.0.1:42032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":32,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:32:25.005324Z","caller":"traceutil/trace.go:171","msg":"trace[1810959917] linearizableReadLoop","detail":"{readStateIndex:26606; appliedIndex:26606; }","duration":"479.670465ms","start":"2026-10-14T16:32:24.525613Z","end":"2026-10-14T16:32:25.005283Z","steps":["trace[1810959917] 'read index received' (duration: 479.651035ms)","trace[1810959917] 'applied index is now lower than readState.Index' (duration: 16.63µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:32:25.085696Z","caller":"traceutil/trace.go:171","msg":"trace[263676925] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"205.295531ms","start":"2026-10-14T16:32:24.880371Z","end":"2026-10-14T16:32:25.085666Z","steps":["trace[263676925] 'process raft request' (duration: 205.195478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.085758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.991378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:32:25.085836Z","caller":"traceutil/trace.go:171","msg":"trace[347578611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25773; }","duration":"532.110522ms","start":"2026-10-14T16:32:24.553701Z","end":"2026-10-14T16:32:25.085812Z","steps":["trace[347578611] 'agreement among raft nodes before linearized reading' (duration: 531.913196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.085873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.553681Z","time spent":"532.182594ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T16:32:25.086083Z","caller":"traceutil/trace.go:171","msg":"trace[750314583] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"290.909421ms","start":"2026-10-14T16:32:24.795162Z","end":"2026-10-14T16:32:25.086071Z","steps":["trace[750314583] 'process raft request' (duration: 289.235367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.08617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.89515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:32:25.086203Z","caller":"traceutil/trace.go:171","msg":"trace[1506716908] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25774; }","duration":"126.976203ms","start":"2026-10-14T16:32:24.959216Z","end":"2026-10-14T16:32:25.086192Z","steps":["trace[1506716908] 'agreement among raft nodes before linearized reading' (duration: 126.89684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.08643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.286931ms","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-10-14T16:32:25.086469Z","caller":"traceutil/trace.go:171","msg":"trace[1489155387] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25774; }","duration":"188.348292ms","start":"2026-10-14T16:32:24.898112Z","end":"2026-10-14T16:32:25.08646Z","steps":["trace[1489155387] 'agreement among raft nodes before linearized reading' (duration: 188.250899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.08651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.520596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:32:25.086542Z","caller":"traceutil/trace.go:171","msg":"trace[1187634871] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25774; }","duration":"459.631879ms","start":"2026-10-14T16:32:24.6269Z","end":"2026-10-14T16:32:25.086532Z","steps":["trace[1187634871] 'agreement among raft nodes before linearized reading' (duration: 459.570427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.086559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.626873Z","time spent":"459.680471ms","remote":"127.0.0.1:42326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:32:25.086705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.39784ms","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-10-14T16:32:25.08672Z","caller":"traceutil/trace.go:171","msg":"trace[386652346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25774; }","duration":"191.42569ms","start":"2026-10-14T16:32:24.895289Z","end":"2026-10-14T16:32:25.086715Z","steps":["trace[386652346] 'agreement among raft nodes before linearized reading' (duration: 191.354868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.086917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.643607ms","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-10-14T16:32:25.086937Z","caller":"traceutil/trace.go:171","msg":"trace[791037931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25774; }","duration":"191.692739ms","start":"2026-10-14T16:32:24.895239Z","end":"2026-10-14T16:32:25.086932Z","steps":["trace[791037931] 'agreement among raft nodes before linearized reading' (duration: 191.631657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.086968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.522088ms","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-10-14T16:32:25.087068Z","caller":"traceutil/trace.go:171","msg":"trace[921892742] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25774; }","duration":"449.677943ms","start":"2026-10-14T16:32:24.637375Z","end":"2026-10-14T16:32:25.087053Z","steps":["trace[921892742] 'agreement among raft nodes before linearized reading' (duration: 449.468316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:32:25.087119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:32:24.637359Z","time spent":"449.744345ms","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-10-14T16:35:58.36127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.753708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:35:58.361395Z","caller":"traceutil/trace.go:171","msg":"trace[1796767789] linearizableReadLoop","detail":"{readStateIndex:28760; appliedIndex:28759; }","duration":"249.192601ms","start":"2026-10-14T16:35:58.112184Z","end":"2026-10-14T16:35:58.361376Z","steps":["trace[1796767789] 'read index received' (duration: 97.405012ms)","trace[1796767789] 'applied index is now lower than readState.Index' (duration: 151.786169ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:35:58.36149Z","caller":"traceutil/trace.go:171","msg":"trace[18567252] transaction","detail":"{read_only:false; response_revision:27874; number_of_response:1; }","duration":"294.182469ms","start":"2026-10-14T16:35:58.067279Z","end":"2026-10-14T16:35:58.361461Z","steps":["trace[18567252] 'process raft request' (duration: 142.148442ms)","trace[18567252] 'compare' (duration: 151.558111ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:35:58.361627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.443879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5d9b4fdc76-qzlrw.18de708e46760c96\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-10-14T16:35:58.361677Z","caller":"traceutil/trace.go:171","msg":"trace[925695983] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5d9b4fdc76-qzlrw.18de708e46760c96; range_end:; response_count:1; response_revision:27874; }","duration":"249.528801ms","start":"2026-10-14T16:35:58.11213Z","end":"2026-10-14T16:35:58.361659Z","steps":["trace[925695983] 'agreement among raft nodes before linearized reading' (duration: 249.327955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:58.862545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906287314990272930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-14T16:35:59.240089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.503229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:35:59.24041Z","caller":"traceutil/trace.go:171","msg":"trace[1838396796] linearizableReadLoop","detail":"{readStateIndex:28764; appliedIndex:28760; }","duration":"878.965953ms","start":"2026-10-14T16:35:58.361427Z","end":"2026-10-14T16:35:59.240393Z","steps":["trace[1838396796] 'read index received' (duration: 613.091032ms)","trace[1838396796] 'applied index is now lower than readState.Index' (duration: 265.873951ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:35:59.2405Z","caller":"traceutil/trace.go:171","msg":"trace[664286315] transaction","detail":"{read_only:false; response_revision:27875; number_of_response:1; }","duration":"1.090081926s","start":"2026-10-14T16:35:58.150408Z","end":"2026-10-14T16:35:59.24049Z","steps":["trace[664286315] 'process raft request' (duration: 824.094882ms)","trace[664286315] 'compare' (duration: 265.180328ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:35:59.240554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.150383Z","time spent":"1.090139398s","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:35:59.240845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007924568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-14T16:35:59.24088Z","caller":"traceutil/trace.go:171","msg":"trace[768722338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27878; }","duration":"1.008032331s","start":"2026-10-14T16:35:58.232839Z","end":"2026-10-14T16:35:59.240871Z","steps":["trace[768722338] 'agreement among raft nodes before linearized reading' (duration: 1.007894736s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.240905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.23282Z","time spent":"1.008078453s","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T16:35:59.240978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.01497ms","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-10-14T16:35:59.241069Z","caller":"traceutil/trace.go:171","msg":"trace[436839451] transaction","detail":"{read_only:false; response_revision:27876; number_of_response:1; }","duration":"1.064199424s","start":"2026-10-14T16:35:58.176847Z","end":"2026-10-14T16:35:59.241046Z","steps":["trace[436839451] 'process raft request' (duration: 1.063386488s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.241042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117046882s","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-10-14T16:35:59.241124Z","caller":"traceutil/trace.go:171","msg":"trace[891673112] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27878; }","duration":"1.117184316s","start":"2026-10-14T16:35:58.123931Z","end":"2026-10-14T16:35:59.241116Z","steps":["trace[891673112] 'agreement among raft nodes before linearized reading' (duration: 1.117047472s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.241138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.713225ms","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-10-14T16:35:59.241155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.12391Z","time spent":"1.117234037s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T16:35:59.241169Z","caller":"traceutil/trace.go:171","msg":"trace[185360361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27878; }","duration":"780.781117ms","start":"2026-10-14T16:35:58.460378Z","end":"2026-10-14T16:35:59.241159Z","steps":["trace[185360361] 'agreement among raft nodes before linearized reading' (duration: 780.708464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.241171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.176819Z","time spent":"1.064296727s","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:35:59.241192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.460358Z","time spent":"780.827729ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T16:35:59.24109Z","caller":"traceutil/trace.go:171","msg":"trace[2032796229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27878; }","duration":"916.189115ms","start":"2026-10-14T16:35:58.324883Z","end":"2026-10-14T16:35:59.241073Z","steps":["trace[2032796229] 'agreement among raft nodes before linearized reading' (duration: 915.852114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.241286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.324865Z","time spent":"916.416133ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T16:35:59.241347Z","caller":"traceutil/trace.go:171","msg":"trace[1136701696] transaction","detail":"{read_only:false; response_revision:27877; number_of_response:1; }","duration":"877.164786ms","start":"2026-10-14T16:35:58.364171Z","end":"2026-10-14T16:35:59.241336Z","steps":["trace[1136701696] 'process raft request' (duration: 876.126923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.241423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.364149Z","time spent":"877.217868ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:35:59.241497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.547755ms","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-10-14T16:35:59.241523Z","caller":"traceutil/trace.go:171","msg":"trace[2017143315] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27878; }","duration":"634.610167ms","start":"2026-10-14T16:35:58.606903Z","end":"2026-10-14T16:35:59.241513Z","steps":["trace[2017143315] 'agreement among raft nodes before linearized reading' (duration: 634.530634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.24154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.606881Z","time spent":"634.653969ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T16:35:59.241618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.726109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:35:59.241635Z","caller":"traceutil/trace.go:171","msg":"trace[446746624] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:27878; }","duration":"169.788541ms","start":"2026-10-14T16:35:59.07184Z","end":"2026-10-14T16:35:59.241628Z","steps":["trace[446746624] 'agreement among raft nodes before linearized reading' (duration: 169.740959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.241697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.191244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:35:59.241796Z","caller":"traceutil/trace.go:171","msg":"trace[179680709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27878; }","duration":"726.328398ms","start":"2026-10-14T16:35:58.515445Z","end":"2026-10-14T16:35:59.241774Z","steps":["trace[179680709] 'agreement among raft nodes before linearized reading' (duration: 726.030319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.24185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.515422Z","time spent":"726.41238ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T16:35:59.241496Z","caller":"traceutil/trace.go:171","msg":"trace[36803691] transaction","detail":"{read_only:false; response_revision:27878; number_of_response:1; }","duration":"420.073916ms","start":"2026-10-14T16:35:58.821388Z","end":"2026-10-14T16:35:59.241462Z","steps":["trace[36803691] 'process raft request' (duration: 418.9664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:35:59.242149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:35:58.821366Z","time spent":"420.694385ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:35:59.443087Z","caller":"traceutil/trace.go:171","msg":"trace[1715684989] transaction","detail":"{read_only:false; response_revision:27885; number_of_response:1; }","duration":"121.515078ms","start":"2026-10-14T16:35:59.321531Z","end":"2026-10-14T16:35:59.443046Z","steps":["trace[1715684989] 'process raft request' (duration: 66.289494ms)","trace[1715684989] 'compare' (duration: 55.020508ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:36:04.085093Z","caller":"traceutil/trace.go:171","msg":"trace[631096795] transaction","detail":"{read_only:false; response_revision:27914; number_of_response:1; }","duration":"108.000489ms","start":"2026-10-14T16:36:03.977068Z","end":"2026-10-14T16:36:04.085068Z","steps":["trace[631096795] 'process raft request' (duration: 107.818364ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:36:11.491735Z","caller":"traceutil/trace.go:171","msg":"trace[149051390] transaction","detail":"{read_only:false; response_revision:27974; number_of_response:1; }","duration":"105.079817ms","start":"2026-10-14T16:36:11.386625Z","end":"2026-10-14T16:36:11.491705Z","steps":["trace[149051390] 'process raft request' (duration: 104.916582ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:36:11.491776Z","caller":"traceutil/trace.go:171","msg":"trace[1232253351] transaction","detail":"{read_only:false; response_revision:27973; number_of_response:1; }","duration":"105.84703ms","start":"2026-10-14T16:36:11.385902Z","end":"2026-10-14T16:36:11.491749Z","steps":["trace[1232253351] 'process raft request' (duration: 40.180645ms)","trace[1232253351] 'compare' (duration: 65.18258ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:36:52.398525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25523} {"level":"info","ts":"2026-10-14T16:36:52.579287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25523,"took":"174.094527ms","hash":132755950,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":35864576,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T16:36:52.579384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":132755950,"revision":25523,"compact-revision":22076} {"level":"info","ts":"2026-10-14T16:38:25.768592Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:38:25.773563Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T16:38:25.773713Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T16:41:52.40814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28390} {"level":"info","ts":"2026-10-14T16:41:52.560273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28390,"took":"145.651443ms","hash":4213143718,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T16:41:52.560336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4213143718,"revision":28390,"compact-revision":25523} {"level":"info","ts":"2026-10-14T16:46:52.416788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30627} {"level":"info","ts":"2026-10-14T16:46:52.539747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30627,"took":"118.998006ms","hash":245245193,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":32989184,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T16:46:52.539794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":245245193,"revision":30627,"compact-revision":28390} {"level":"info","ts":"2026-10-14T16:51:52.424339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32883} {"level":"info","ts":"2026-10-14T16:51:52.583394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32883,"took":"153.67283ms","hash":1719334160,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:51:52.583459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1719334160,"revision":32883,"compact-revision":30627} {"level":"info","ts":"2026-10-14T16:56:52.435732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35143} {"level":"info","ts":"2026-10-14T16:56:52.580582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35143,"took":"138.970911ms","hash":3642886371,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":32706560,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T16:56:52.580732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3642886371,"revision":35143,"compact-revision":32883} {"level":"info","ts":"2026-10-14T16:59:56.983877Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:59:56.990102Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T16:59:56.990294Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T17:01:52.443387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37410} {"level":"info","ts":"2026-10-14T17:01:52.578664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37410,"took":"131.387628ms","hash":126966272,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":32440320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T17:01:52.578725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":126966272,"revision":37410,"compact-revision":35143}