{"level":"warn","ts":"2026-08-05T11:22:50.562264Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:22:50.562381Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.206:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.206:2380","--initial-cluster=instance=https://199.19.213.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.206: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-08-05T11:22:50.562463Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:22:50.56248Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-08-05T11:22:50.562501Z","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-08-05T11:22:50.563177Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"]} {"level":"info","ts":"2026-08-05T11:22:50.563261Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.206: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-08-05T11:22:50.566405Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.847152ms"} {"level":"info","ts":"2026-08-05T11:22:50.571336Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b696d7a595057bfe","cluster-id":"9b00ba6f3f3b7ac2"} {"level":"info","ts":"2026-08-05T11:22:50.571472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:22:50.571504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 0"} {"level":"info","ts":"2026-08-05T11:22:50.571551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b696d7a595057bfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:22:50.571562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 1"} {"level":"info","ts":"2026-08-05T11:22:50.571618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"warn","ts":"2026-08-05T11:22:50.584293Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:22:50.586082Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:22:50.589022Z","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-08-05T11:22:50.591485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b696d7a595057bfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:22:50.59175Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b696d7a595057bfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:22:50.591779Z","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-08-05T11:22:50.591883Z","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-08-05T11:22:50.5919Z","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-08-05T11:22:50.593581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"info","ts":"2026-08-05T11:22:50.594302Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","added-peer-id":"b696d7a595057bfe","added-peer-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-08-05T11:22:50.596887Z","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-08-05T11:22:50.597021Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-08-05T11:22:50.597069Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-08-05T11:22:50.599163Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b696d7a595057bfe","initial-advertise-peer-urls":["https://199.19.213.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:22:50.599223Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:22:51.372378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:22:51.372423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:22:51.372438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgPreVoteResp from b696d7a595057bfe at term 1"} {"level":"info","ts":"2026-08-05T11:22:51.372452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:22:51.372457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgVoteResp from b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-08-05T11:22:51.372467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became leader at term 2"} {"level":"info","ts":"2026-08-05T11:22:51.372475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b696d7a595057bfe elected leader b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-08-05T11:22:51.373547Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:51.37485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:22:51.374851Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b696d7a595057bfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.206:2379]}","request-path":"/0/members/b696d7a595057bfe/attributes","cluster-id":"9b00ba6f3f3b7ac2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:22:51.374904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:22:51.375228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:22:51.375356Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:22:51.37551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:51.375712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:51.375771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:51.376514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.206:2379"} {"level":"info","ts":"2026-08-05T11:22:51.378213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T11:23:28.154069Z","caller":"traceutil/trace.go:171","msg":"trace[212312549] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"239.014074ms","start":"2026-08-05T11:23:27.915012Z","end":"2026-08-05T11:23:28.154026Z","steps":["trace[212312549] 'process raft request' (duration: 238.629265ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.154312Z","caller":"traceutil/trace.go:171","msg":"trace[453888907] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:517; }","duration":"225.421411ms","start":"2026-08-05T11:23:27.928865Z","end":"2026-08-05T11:23:28.154287Z","steps":["trace[453888907] 'read index received' (duration: 224.830516ms)","trace[453888907] 'applied index is now lower than readState.Index' (duration: 589.985µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:28.154316Z","caller":"traceutil/trace.go:171","msg":"trace[1165838256] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"233.826683ms","start":"2026-08-05T11:23:27.920436Z","end":"2026-08-05T11:23:28.154263Z","steps":["trace[1165838256] 'process raft request' (duration: 233.497765ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.154421Z","caller":"traceutil/trace.go:171","msg":"trace[1534048158] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"230.369215ms","start":"2026-08-05T11:23:27.92404Z","end":"2026-08-05T11:23:28.154409Z","steps":["trace[1534048158] 'process raft request' (duration: 230.207311ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.154539Z","caller":"traceutil/trace.go:171","msg":"trace[1606404548] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"231.067833ms","start":"2026-08-05T11:23:27.9234Z","end":"2026-08-05T11:23:28.154468Z","steps":["trace[1606404548] 'process raft request' (duration: 230.776205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:28.154946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.753519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-08-05T11:23:28.155156Z","caller":"traceutil/trace.go:171","msg":"trace[950905179] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:501; }","duration":"226.277822ms","start":"2026-08-05T11:23:27.928859Z","end":"2026-08-05T11:23:28.155137Z","steps":["trace[950905179] 'agreement among raft nodes before linearized reading' (duration: 225.589825ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169195Z","caller":"traceutil/trace.go:171","msg":"trace[1980278633] transaction","detail":"{read_only:false; number_of_response:0; response_revision:501; }","duration":"239.954058ms","start":"2026-08-05T11:23:27.929227Z","end":"2026-08-05T11:23:28.169181Z","steps":["trace[1980278633] 'process raft request' (duration: 239.845555ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169204Z","caller":"traceutil/trace.go:171","msg":"trace[1807055678] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"240.317018ms","start":"2026-08-05T11:23:27.928865Z","end":"2026-08-05T11:23:28.169182Z","steps":["trace[1807055678] 'process raft request' (duration: 240.129433ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169322Z","caller":"traceutil/trace.go:171","msg":"trace[2085244536] transaction","detail":"{read_only:false; number_of_response:0; response_revision:501; }","duration":"239.600668ms","start":"2026-08-05T11:23:27.929605Z","end":"2026-08-05T11:23:28.169206Z","steps":["trace[2085244536] 'process raft request' (duration: 239.489436ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169295Z","caller":"traceutil/trace.go:171","msg":"trace[496797178] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"238.999344ms","start":"2026-08-05T11:23:27.930264Z","end":"2026-08-05T11:23:28.169263Z","steps":["trace[496797178] 'process raft request' (duration: 238.919232ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169448Z","caller":"traceutil/trace.go:171","msg":"trace[1065184849] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"239.25473ms","start":"2026-08-05T11:23:27.93011Z","end":"2026-08-05T11:23:28.169365Z","steps":["trace[1065184849] 'process raft request' (duration: 239.009084ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169649Z","caller":"traceutil/trace.go:171","msg":"trace[56004614] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"236.867201ms","start":"2026-08-05T11:23:27.932765Z","end":"2026-08-05T11:23:28.169632Z","steps":["trace[56004614] 'process raft request' (duration: 236.45266ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169753Z","caller":"traceutil/trace.go:171","msg":"trace[1452484942] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"236.935342ms","start":"2026-08-05T11:23:27.932806Z","end":"2026-08-05T11:23:28.169742Z","steps":["trace[1452484942] 'process raft request' (duration: 236.748027ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.169921Z","caller":"traceutil/trace.go:171","msg":"trace[61102483] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"228.669192ms","start":"2026-08-05T11:23:27.941238Z","end":"2026-08-05T11:23:28.169907Z","steps":["trace[61102483] 'process raft request' (duration: 228.515078ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.170031Z","caller":"traceutil/trace.go:171","msg":"trace[372804098] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"228.345104ms","start":"2026-08-05T11:23:27.94167Z","end":"2026-08-05T11:23:28.170015Z","steps":["trace[372804098] 'process raft request' (duration: 228.151269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:28.170087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.462431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:23:28.170106Z","caller":"traceutil/trace.go:171","msg":"trace[815246257] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"107.756209ms","start":"2026-08-05T11:23:28.062328Z","end":"2026-08-05T11:23:28.170084Z","steps":["trace[815246257] 'process raft request' (duration: 107.585255ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:28.170138Z","caller":"traceutil/trace.go:171","msg":"trace[285103698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"150.562454ms","start":"2026-08-05T11:23:28.019563Z","end":"2026-08-05T11:23:28.170126Z","steps":["trace[285103698] 'agreement among raft nodes before linearized reading' (duration: 150.470111ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:34.363419Z","caller":"traceutil/trace.go:171","msg":"trace[1590926654] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"321.549864ms","start":"2026-08-05T11:23:34.041799Z","end":"2026-08-05T11:23:34.363349Z","steps":["trace[1590926654] 'process raft request' (duration: 320.948319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:34.364525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:34.041777Z","time spent":"321.78005ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:23:34.739462Z","caller":"traceutil/trace.go:171","msg":"trace[1866364020] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"352.870299ms","start":"2026-08-05T11:23:34.386546Z","end":"2026-08-05T11:23:34.739417Z","steps":["trace[1866364020] 'process raft request' (duration: 293.410862ms)","trace[1866364020] 'compare' (duration: 59.278882ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:34.739738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:34.386517Z","time spent":"353.100144ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:23:34.931842Z","caller":"traceutil/trace.go:171","msg":"trace[1365259411] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"410.045556ms","start":"2026-08-05T11:23:34.521767Z","end":"2026-08-05T11:23:34.931812Z","steps":["trace[1365259411] 'read index received' (duration: 158.202777ms)","trace[1365259411] 'applied index is now lower than readState.Index' (duration: 251.841799ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:34.931972Z","caller":"traceutil/trace.go:171","msg":"trace[2004545813] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"507.67572ms","start":"2026-08-05T11:23:34.424229Z","end":"2026-08-05T11:23:34.931905Z","steps":["trace[2004545813] 'process raft request' (duration: 504.646654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:34.932209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.708051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-05T11:23:34.932187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.400686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-05T11:23:34.932266Z","caller":"traceutil/trace.go:171","msg":"trace[105031686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"282.805354ms","start":"2026-08-05T11:23:34.649437Z","end":"2026-08-05T11:23:34.932242Z","steps":["trace[105031686] 'agreement among raft nodes before linearized reading' (duration: 282.545957ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:34.93229Z","caller":"traceutil/trace.go:171","msg":"trace[159815093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"410.553239ms","start":"2026-08-05T11:23:34.521709Z","end":"2026-08-05T11:23:34.932262Z","steps":["trace[159815093] 'agreement among raft nodes before linearized reading' (duration: 410.251621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:34.932354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:34.521686Z","time spent":"410.650171ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T11:23:34.932558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:34.424201Z","time spent":"508.057989ms","remote":"127.0.0.1:36630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:23:49.623825Z","caller":"traceutil/trace.go:171","msg":"trace[335466743] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"110.750255ms","start":"2026-08-05T11:23:49.513044Z","end":"2026-08-05T11:23:49.623794Z","steps":["trace[335466743] 'read index received' (duration: 110.389306ms)","trace[335466743] 'applied index is now lower than readState.Index' (duration: 359.259µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:49.623919Z","caller":"traceutil/trace.go:171","msg":"trace[2125421936] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"205.048364ms","start":"2026-08-05T11:23:49.418812Z","end":"2026-08-05T11:23:49.62386Z","steps":["trace[2125421936] 'process raft request' (duration: 204.805708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:49.6242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.272687ms","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-08-05T11:23:49.624283Z","caller":"traceutil/trace.go:171","msg":"trace[230634840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"105.40021ms","start":"2026-08-05T11:23:49.518861Z","end":"2026-08-05T11:23:49.624261Z","steps":["trace[230634840] 'agreement among raft nodes before linearized reading' (duration: 105.202745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:49.624379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.341231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-05T11:23:49.62454Z","caller":"traceutil/trace.go:171","msg":"trace[684359536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"111.556916ms","start":"2026-08-05T11:23:49.512963Z","end":"2026-08-05T11:23:49.62452Z","steps":["trace[684359536] 'agreement among raft nodes before linearized reading' (duration: 111.017702ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:49.747694Z","caller":"traceutil/trace.go:171","msg":"trace[13925630] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"117.686481ms","start":"2026-08-05T11:23:49.629978Z","end":"2026-08-05T11:23:49.747665Z","steps":["trace[13925630] 'process raft request' (duration: 51.608477ms)","trace[13925630] 'compare' (duration: 65.863778ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:49.74789Z","caller":"traceutil/trace.go:171","msg":"trace[778064594] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"117.716352ms","start":"2026-08-05T11:23:49.630149Z","end":"2026-08-05T11:23:49.747865Z","steps":["trace[778064594] 'process raft request' (duration: 117.457466ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:05.640615Z","caller":"traceutil/trace.go:171","msg":"trace[837730431] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1861; }","duration":"199.143019ms","start":"2026-08-05T11:25:05.441433Z","end":"2026-08-05T11:25:05.640576Z","steps":["trace[837730431] 'read index received' (duration: 198.76104ms)","trace[837730431] 'applied index is now lower than readState.Index' (duration: 380.009µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:05.640673Z","caller":"traceutil/trace.go:171","msg":"trace[436967718] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"199.56978ms","start":"2026-08-05T11:25:05.441066Z","end":"2026-08-05T11:25:05.640636Z","steps":["trace[436967718] 'process raft request' (duration: 199.193481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:05.640868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.439747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T11:25:05.640942Z","caller":"traceutil/trace.go:171","msg":"trace[674350920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1803; }","duration":"199.544019ms","start":"2026-08-05T11:25:05.441383Z","end":"2026-08-05T11:25:05.640927Z","steps":["trace[674350920] 'agreement among raft nodes before linearized reading' (duration: 199.316793ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:12.037602Z","caller":"traceutil/trace.go:171","msg":"trace[1442015231] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"127.391617ms","start":"2026-08-05T11:25:11.910175Z","end":"2026-08-05T11:25:12.037567Z","steps":["trace[1442015231] 'process raft request' (duration: 117.002084ms)","trace[1442015231] 'compare' (duration: 10.125276ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:12.053699Z","caller":"traceutil/trace.go:171","msg":"trace[1172637178] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"143.419713ms","start":"2026-08-05T11:25:11.910251Z","end":"2026-08-05T11:25:12.05367Z","steps":["trace[1172637178] 'process raft request' (duration: 143.233358ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:12.053939Z","caller":"traceutil/trace.go:171","msg":"trace[2016899022] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"143.223458ms","start":"2026-08-05T11:25:11.910681Z","end":"2026-08-05T11:25:12.053904Z","steps":["trace[2016899022] 'process raft request' (duration: 142.9348ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:12.053986Z","caller":"traceutil/trace.go:171","msg":"trace[682271426] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"142.912481ms","start":"2026-08-05T11:25:11.911052Z","end":"2026-08-05T11:25:12.053964Z","steps":["trace[682271426] 'process raft request' (duration: 142.761566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:48.785212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.262155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-05T11:25:48.785319Z","caller":"traceutil/trace.go:171","msg":"trace[172345623] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2145; }","duration":"103.45346ms","start":"2026-08-05T11:25:48.681842Z","end":"2026-08-05T11:25:48.785296Z","steps":["trace[172345623] 'range keys from in-memory index tree' (duration: 103.032629ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:48.961477Z","caller":"traceutil/trace.go:171","msg":"trace[1601742617] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"149.834095ms","start":"2026-08-05T11:25:48.811619Z","end":"2026-08-05T11:25:48.961453Z","steps":["trace[1601742617] 'read index received' (duration: 149.700262ms)","trace[1601742617] 'applied index is now lower than readState.Index' (duration: 132.663µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:48.961546Z","caller":"traceutil/trace.go:171","msg":"trace[221471252] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"168.167889ms","start":"2026-08-05T11:25:48.79333Z","end":"2026-08-05T11:25:48.961498Z","steps":["trace[221471252] 'process raft request' (duration: 167.954874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:48.961807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.172124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T11:25:48.961852Z","caller":"traceutil/trace.go:171","msg":"trace[2130535848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2146; }","duration":"150.272746ms","start":"2026-08-05T11:25:48.811568Z","end":"2026-08-05T11:25:48.961841Z","steps":["trace[2130535848] 'agreement among raft nodes before linearized reading' (duration: 150.06547ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:49.138922Z","caller":"traceutil/trace.go:171","msg":"trace[1088636229] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"169.938855ms","start":"2026-08-05T11:25:48.968938Z","end":"2026-08-05T11:25:49.138877Z","steps":["trace[1088636229] 'process raft request' (duration: 148.151283ms)","trace[1088636229] 'compare' (duration: 21.361341ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:25.355144Z","caller":"traceutil/trace.go:171","msg":"trace[1645937227] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"189.36002ms","start":"2026-08-05T11:26:25.165753Z","end":"2026-08-05T11:26:25.355113Z","steps":["trace[1645937227] 'process raft request' (duration: 189.183625ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:25.383166Z","caller":"traceutil/trace.go:171","msg":"trace[1912875731] linearizableReadLoop","detail":"{readStateIndex:2540; appliedIndex:2538; }","duration":"106.166252ms","start":"2026-08-05T11:26:25.276959Z","end":"2026-08-05T11:26:25.383126Z","steps":["trace[1912875731] 'read index received' (duration: 78.021069ms)","trace[1912875731] 'applied index is now lower than readState.Index' (duration: 28.143603ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:25.383315Z","caller":"traceutil/trace.go:171","msg":"trace[2016866927] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"114.397479ms","start":"2026-08-05T11:26:25.268879Z","end":"2026-08-05T11:26:25.383277Z","steps":["trace[2016866927] 'process raft request' (duration: 99.430801ms)","trace[2016866927] 'compare' (duration: 14.61909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:26:25.38346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.474489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-08-05T11:26:25.383613Z","caller":"traceutil/trace.go:171","msg":"trace[1240070575] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2447; }","duration":"106.646593ms","start":"2026-08-05T11:26:25.276925Z","end":"2026-08-05T11:26:25.383572Z","steps":["trace[1240070575] 'agreement among raft nodes before linearized reading' (duration: 106.353886ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:56.730391Z","caller":"traceutil/trace.go:171","msg":"trace[800231148] linearizableReadLoop","detail":"{readStateIndex:2770; appliedIndex:2769; }","duration":"174.623506ms","start":"2026-08-05T11:26:56.555746Z","end":"2026-08-05T11:26:56.730369Z","steps":["trace[800231148] 'read index received' (duration: 174.39812ms)","trace[800231148] 'applied index is now lower than readState.Index' (duration: 224.316µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:56.730508Z","caller":"traceutil/trace.go:171","msg":"trace[170849123] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"322.689619ms","start":"2026-08-05T11:26:56.407783Z","end":"2026-08-05T11:26:56.730472Z","steps":["trace[170849123] 'process raft request' (duration: 322.389541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:56.730566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.814661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:26:56.730609Z","caller":"traceutil/trace.go:171","msg":"trace[1959546440] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2664; }","duration":"174.938234ms","start":"2026-08-05T11:26:56.555658Z","end":"2026-08-05T11:26:56.730596Z","steps":["trace[1959546440] 'agreement among raft nodes before linearized reading' (duration: 174.845752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:56.730689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:56.407772Z","time spent":"322.825932ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16842,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:28:03.615068Z","caller":"traceutil/trace.go:171","msg":"trace[1264104639] transaction","detail":"{read_only:false; response_revision:3291; number_of_response:1; }","duration":"218.233201ms","start":"2026-08-05T11:28:03.396804Z","end":"2026-08-05T11:28:03.615037Z","steps":["trace[1264104639] 'process raft request' (duration: 216.851656ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:29:19.17829Z","caller":"traceutil/trace.go:171","msg":"trace[1177351345] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"135.78075ms","start":"2026-08-05T11:29:19.042474Z","end":"2026-08-05T11:29:19.178255Z","steps":["trace[1177351345] 'process raft request' (duration: 81.903845ms)","trace[1177351345] 'compare' (duration: 53.725722ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:29:19.204843Z","caller":"traceutil/trace.go:171","msg":"trace[21424453] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"120.918575ms","start":"2026-08-05T11:29:19.083897Z","end":"2026-08-05T11:29:19.204816Z","steps":["trace[21424453] 'process raft request' (duration: 120.652818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:29:21.576931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.449158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:29:21.577069Z","caller":"traceutil/trace.go:171","msg":"trace[738646429] linearizableReadLoop","detail":"{readStateIndex:4712; appliedIndex:4711; }","duration":"138.403727ms","start":"2026-08-05T11:29:21.438618Z","end":"2026-08-05T11:29:21.577022Z","steps":["trace[738646429] 'read index received' (duration: 69.111µs)","trace[738646429] 'applied index is now lower than readState.Index' (duration: 138.332926ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:29:21.577102Z","caller":"traceutil/trace.go:171","msg":"trace[152667749] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"238.975646ms","start":"2026-08-05T11:29:21.338078Z","end":"2026-08-05T11:29:21.577054Z","steps":["trace[152667749] 'process raft request' (duration: 99.710056ms)","trace[152667749] 'compare' (duration: 137.963297ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:29:21.577317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.682544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-652bec06-d204-4c5b-8033-0624e3489319\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-05T11:29:21.577386Z","caller":"traceutil/trace.go:171","msg":"trace[1306976454] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-652bec06-d204-4c5b-8033-0624e3489319; range_end:; response_count:1; response_revision:4518; }","duration":"138.817227ms","start":"2026-08-05T11:29:21.438552Z","end":"2026-08-05T11:29:21.577369Z","steps":["trace[1306976454] 'agreement among raft nodes before linearized reading' (duration: 138.555511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:29:21.577936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.676061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-05T11:29:21.577978Z","caller":"traceutil/trace.go:171","msg":"trace[500153072] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4518; }","duration":"132.766065ms","start":"2026-08-05T11:29:21.445199Z","end":"2026-08-05T11:29:21.577965Z","steps":["trace[500153072] 'agreement among raft nodes before linearized reading' (duration: 131.993695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:29:36.330303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.140599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-05T11:29:36.330525Z","caller":"traceutil/trace.go:171","msg":"trace[1353127788] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5006; }","duration":"312.31845ms","start":"2026-08-05T11:29:36.018187Z","end":"2026-08-05T11:29:36.330505Z","steps":["trace[1353127788] 'read index received' (duration: 203.913565ms)","trace[1353127788] 'applied index is now lower than readState.Index' (duration: 108.403625ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:29:36.33075Z","caller":"traceutil/trace.go:171","msg":"trace[1854131219] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4801; }","duration":"378.876034ms","start":"2026-08-05T11:29:35.951861Z","end":"2026-08-05T11:29:36.330737Z","steps":["trace[1854131219] 'process raft request' (duration: 270.190652ms)","trace[1854131219] 'compare' (duration: 108.101007ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:29:36.330792Z","caller":"traceutil/trace.go:171","msg":"trace[1238747500] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"175.333597ms","start":"2026-08-05T11:29:36.155408Z","end":"2026-08-05T11:29:36.330741Z","steps":["trace[1238747500] 'process raft request' (duration: 175.011539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:29:36.330815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:29:35.951834Z","time spent":"378.953496ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T11:29:36.331045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.869545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:29:36.331085Z","caller":"traceutil/trace.go:171","msg":"trace[157891983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4802; }","duration":"312.910576ms","start":"2026-08-05T11:29:36.018164Z","end":"2026-08-05T11:29:36.331074Z","steps":["trace[157891983] 'agreement among raft nodes before linearized reading' (duration: 312.827214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:29:36.331117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:29:36.018021Z","time spent":"313.090391ms","remote":"127.0.0.1:36108","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-08-05T11:29:36.331303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.702801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2bkwx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-05T11:29:36.331344Z","caller":"traceutil/trace.go:171","msg":"trace[1593152461] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2bkwx; range_end:; response_count:1; response_revision:4802; }","duration":"240.783343ms","start":"2026-08-05T11:29:36.09055Z","end":"2026-08-05T11:29:36.331333Z","steps":["trace[1593152461] 'agreement among raft nodes before linearized reading' (duration: 240.66484ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:29:36.47602Z","caller":"traceutil/trace.go:171","msg":"trace[661134321] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"138.253775ms","start":"2026-08-05T11:29:36.337729Z","end":"2026-08-05T11:29:36.475982Z","steps":["trace[661134321] 'process raft request' (duration: 133.855955ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:29:36.476832Z","caller":"traceutil/trace.go:171","msg":"trace[211830588] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"132.44356ms","start":"2026-08-05T11:29:36.344358Z","end":"2026-08-05T11:29:36.476801Z","steps":["trace[211830588] 'process raft request' (duration: 132.172902ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:29:36.476641Z","caller":"traceutil/trace.go:171","msg":"trace[1748984995] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"133.819795ms","start":"2026-08-05T11:29:36.342808Z","end":"2026-08-05T11:29:36.476627Z","steps":["trace[1748984995] 'process raft request' (duration: 133.63322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:29:42.989545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.473465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:29:42.990111Z","caller":"traceutil/trace.go:171","msg":"trace[919036961] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:4963; }","duration":"101.115772ms","start":"2026-08-05T11:29:42.888965Z","end":"2026-08-05T11:29:42.990081Z","steps":["trace[919036961] 'count revisions from in-memory index tree' (duration: 100.305481ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:29:43.480877Z","caller":"traceutil/trace.go:171","msg":"trace[1033623948] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"111.325618ms","start":"2026-08-05T11:29:43.369513Z","end":"2026-08-05T11:29:43.480839Z","steps":["trace[1033623948] 'process raft request' (duration: 108.61173ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:29:43.48284Z","caller":"traceutil/trace.go:171","msg":"trace[462708649] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"112.941759ms","start":"2026-08-05T11:29:43.36985Z","end":"2026-08-05T11:29:43.482792Z","steps":["trace[462708649] 'process raft request' (duration: 112.685553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:30:32.911131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.811958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-05T11:30:32.911223Z","caller":"traceutil/trace.go:171","msg":"trace[1672573308] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5505; }","duration":"128.949742ms","start":"2026-08-05T11:30:32.782254Z","end":"2026-08-05T11:30:32.911204Z","steps":["trace[1672573308] 'range keys from in-memory index tree' (duration: 128.586582ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:30:33.28768Z","caller":"traceutil/trace.go:171","msg":"trace[1179276730] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5508; }","duration":"185.989664ms","start":"2026-08-05T11:30:33.10166Z","end":"2026-08-05T11:30:33.28765Z","steps":["trace[1179276730] 'process raft request' (duration: 123.668368ms)","trace[1179276730] 'compare' (duration: 62.263325ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:32:51.40945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3207} {"level":"info","ts":"2026-08-05T11:32:51.490999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3207,"took":"79.850894ms","hash":3213490014,"current-db-size-bytes":61440000,"current-db-size":"61 MB","current-db-size-in-use-bytes":33927168,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T11:32:51.491054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3213490014,"revision":3207,"compact-revision":-1} {"level":"info","ts":"2026-08-05T11:35:33.390739Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002137.wal"} {"level":"info","ts":"2026-08-05T11:35:38.001769Z","caller":"traceutil/trace.go:171","msg":"trace[1633492499] linearizableReadLoop","detail":"{readStateIndex:8543; appliedIndex:8542; }","duration":"276.283262ms","start":"2026-08-05T11:35:37.725458Z","end":"2026-08-05T11:35:38.001741Z","steps":["trace[1633492499] 'read index received' (duration: 276.030405ms)","trace[1633492499] 'applied index is now lower than readState.Index' (duration: 251.137µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:35:38.001876Z","caller":"traceutil/trace.go:171","msg":"trace[945218385] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"303.178193ms","start":"2026-08-05T11:35:37.698664Z","end":"2026-08-05T11:35:38.001842Z","steps":["trace[945218385] 'process raft request' (duration: 302.910446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:35:38.002043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.567748ms","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-08-05T11:35:38.002098Z","caller":"traceutil/trace.go:171","msg":"trace[1187321622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8179; }","duration":"276.6412ms","start":"2026-08-05T11:35:37.725443Z","end":"2026-08-05T11:35:38.002084Z","steps":["trace[1187321622] 'agreement among raft nodes before linearized reading' (duration: 276.453395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:35:38.002102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:35:37.698623Z","time spent":"303.327666ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:35:38.002248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.198083ms","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-08-05T11:35:38.002369Z","caller":"traceutil/trace.go:171","msg":"trace[302895662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8179; }","duration":"275.378548ms","start":"2026-08-05T11:35:37.726965Z","end":"2026-08-05T11:35:38.002344Z","steps":["trace[302895662] 'agreement among raft nodes before linearized reading' (duration: 274.984417ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:35:38.126382Z","caller":"traceutil/trace.go:171","msg":"trace[1164867778] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"118.905177ms","start":"2026-08-05T11:35:38.006821Z","end":"2026-08-05T11:35:38.125726Z","steps":["trace[1164867778] 'process raft request' (duration: 107.485628ms)","trace[1164867778] 'compare' (duration: 11.130432ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:35:38.12667Z","caller":"traceutil/trace.go:171","msg":"trace[1796027585] linearizableReadLoop","detail":"{readStateIndex:8545; appliedIndex:8543; }","duration":"111.003897ms","start":"2026-08-05T11:35:38.015632Z","end":"2026-08-05T11:35:38.126636Z","steps":["trace[1796027585] 'read index received' (duration: 98.698955ms)","trace[1796027585] 'applied index is now lower than readState.Index' (duration: 12.303502ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:35:38.12687Z","caller":"traceutil/trace.go:171","msg":"trace[2124454438] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"119.463691ms","start":"2026-08-05T11:35:38.007392Z","end":"2026-08-05T11:35:38.126856Z","steps":["trace[2124454438] 'process raft request' (duration: 119.06932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:35:38.127107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.46758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-05T11:35:38.127186Z","caller":"traceutil/trace.go:171","msg":"trace[434807340] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8181; }","duration":"111.595883ms","start":"2026-08-05T11:35:38.01557Z","end":"2026-08-05T11:35:38.127166Z","steps":["trace[434807340] 'agreement among raft nodes before linearized reading' (duration: 111.335236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:35:38.127291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.265467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-05T11:35:38.127404Z","caller":"traceutil/trace.go:171","msg":"trace[1403842621] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8181; }","duration":"106.432841ms","start":"2026-08-05T11:35:38.020944Z","end":"2026-08-05T11:35:38.127377Z","steps":["trace[1403842621] 'agreement among raft nodes before linearized reading' (duration: 105.981519ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:35:50.351102Z","caller":"traceutil/trace.go:171","msg":"trace[1074306316] linearizableReadLoop","detail":"{readStateIndex:8630; appliedIndex:8629; }","duration":"161.876316ms","start":"2026-08-05T11:35:50.189199Z","end":"2026-08-05T11:35:50.351076Z","steps":["trace[1074306316] 'read index received' (duration: 161.633301ms)","trace[1074306316] 'applied index is now lower than readState.Index' (duration: 242.005µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:35:50.351221Z","caller":"traceutil/trace.go:171","msg":"trace[2037584221] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"300.089695ms","start":"2026-08-05T11:35:50.051092Z","end":"2026-08-05T11:35:50.351182Z","steps":["trace[2037584221] 'process raft request' (duration: 299.806947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:35:50.351367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.521927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-05T11:35:50.351413Z","caller":"traceutil/trace.go:171","msg":"trace[629876327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8260; }","duration":"157.601529ms","start":"2026-08-05T11:35:50.193799Z","end":"2026-08-05T11:35:50.3514Z","steps":["trace[629876327] 'agreement among raft nodes before linearized reading' (duration: 157.425485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:35:50.351441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:35:50.051069Z","time spent":"300.250998ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:35:50.351425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.213596ms","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-08-05T11:35:50.351628Z","caller":"traceutil/trace.go:171","msg":"trace[1700953459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8260; }","duration":"162.450702ms","start":"2026-08-05T11:35:50.189123Z","end":"2026-08-05T11:35:50.351574Z","steps":["trace[1700953459] 'agreement among raft nodes before linearized reading' (duration: 162.083723ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:18.717335Z","caller":"traceutil/trace.go:171","msg":"trace[990339575] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8883; }","duration":"164.273395ms","start":"2026-08-05T11:36:18.553036Z","end":"2026-08-05T11:36:18.717309Z","steps":["trace[990339575] 'read index received' (duration: 164.088561ms)","trace[990339575] 'applied index is now lower than readState.Index' (duration: 183.794µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:36:18.717449Z","caller":"traceutil/trace.go:171","msg":"trace[15855879] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"248.962672ms","start":"2026-08-05T11:36:18.468447Z","end":"2026-08-05T11:36:18.71741Z","steps":["trace[15855879] 'process raft request' (duration: 248.692515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:18.717576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.529181ms","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-08-05T11:36:18.717623Z","caller":"traceutil/trace.go:171","msg":"trace[1064519786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8502; }","duration":"164.639934ms","start":"2026-08-05T11:36:18.552971Z","end":"2026-08-05T11:36:18.717611Z","steps":["trace[1064519786] 'agreement among raft nodes before linearized reading' (duration: 164.48218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:18.717674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.265123ms","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-08-05T11:36:18.717703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.244684ms","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":"info","ts":"2026-08-05T11:36:18.71774Z","caller":"traceutil/trace.go:171","msg":"trace[1347714849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8502; }","duration":"162.372017ms","start":"2026-08-05T11:36:18.555354Z","end":"2026-08-05T11:36:18.717726Z","steps":["trace[1347714849] 'agreement among raft nodes before linearized reading' (duration: 162.161381ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:18.717789Z","caller":"traceutil/trace.go:171","msg":"trace[1410349383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8502; }","duration":"162.358757ms","start":"2026-08-05T11:36:18.55541Z","end":"2026-08-05T11:36:18.717769Z","steps":["trace[1410349383] 'agreement among raft nodes before linearized reading' (duration: 162.139381ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:32.064422Z","caller":"traceutil/trace.go:171","msg":"trace[1019386642] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"302.817768ms","start":"2026-08-05T11:36:31.761581Z","end":"2026-08-05T11:36:32.064398Z","steps":["trace[1019386642] 'process raft request' (duration: 302.654194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:32.064586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:31.761552Z","time spent":"302.950282ms","remote":"127.0.0.1:36382","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-08-05T11:36:32.180079Z","caller":"traceutil/trace.go:171","msg":"trace[488546598] linearizableReadLoop","detail":"{readStateIndex:9023; appliedIndex:9021; }","duration":"335.536237ms","start":"2026-08-05T11:36:31.844514Z","end":"2026-08-05T11:36:32.18005Z","steps":["trace[488546598] 'read index received' (duration: 219.792173ms)","trace[488546598] 'applied index is now lower than readState.Index' (duration: 115.741724ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:36:32.18009Z","caller":"traceutil/trace.go:171","msg":"trace[1537015861] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"115.910489ms","start":"2026-08-05T11:36:32.064151Z","end":"2026-08-05T11:36:32.180061Z","steps":["trace[1537015861] 'process raft request' (duration: 115.834437ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:32.180141Z","caller":"traceutil/trace.go:171","msg":"trace[2040645996] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"417.280121ms","start":"2026-08-05T11:36:31.762776Z","end":"2026-08-05T11:36:32.180056Z","steps":["trace[2040645996] 'process raft request' (duration: 391.816594ms)","trace[2040645996] 'compare' (duration: 25.243361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:36:32.180494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.951079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rcxt6\" ","response":"range_response_count:1 size:4607"} {"level":"info","ts":"2026-08-05T11:36:32.180564Z","caller":"traceutil/trace.go:171","msg":"trace[2069733099] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rcxt6; range_end:; response_count:1; response_revision:8634; }","duration":"336.091532ms","start":"2026-08-05T11:36:31.844453Z","end":"2026-08-05T11:36:32.180544Z","steps":["trace[2069733099] 'agreement among raft nodes before linearized reading' (duration: 335.703052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:32.180598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:31.76276Z","time spent":"417.558898ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:36:32.180693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:31.844432Z","time spent":"336.213295ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4631,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rcxt6\" "} {"level":"info","ts":"2026-08-05T11:36:33.068354Z","caller":"traceutil/trace.go:171","msg":"trace[917727604] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"340.975905ms","start":"2026-08-05T11:36:32.727351Z","end":"2026-08-05T11:36:33.068327Z","steps":["trace[917727604] 'process raft request' (duration: 340.77113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.068519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.727327Z","time spent":"341.104249ms","remote":"127.0.0.1:36596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:36:33.160291Z","caller":"traceutil/trace.go:171","msg":"trace[221991253] linearizableReadLoop","detail":"{readStateIndex:9028; appliedIndex:9026; }","duration":"349.967543ms","start":"2026-08-05T11:36:32.810277Z","end":"2026-08-05T11:36:33.160245Z","steps":["trace[221991253] 'read index received' (duration: 257.93692ms)","trace[221991253] 'applied index is now lower than readState.Index' (duration: 92.029013ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:36:33.160428Z","caller":"traceutil/trace.go:171","msg":"trace[936233348] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"394.022669ms","start":"2026-08-05T11:36:32.766368Z","end":"2026-08-05T11:36:33.160391Z","steps":["trace[936233348] 'process raft request' (duration: 384.393905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.160592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.766332Z","time spent":"394.174633ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:36:33.160675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.345923ms","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-08-05T11:36:33.16073Z","caller":"traceutil/trace.go:171","msg":"trace[505450988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8639; }","duration":"350.460186ms","start":"2026-08-05T11:36:32.810254Z","end":"2026-08-05T11:36:33.160714Z","steps":["trace[505450988] 'agreement among raft nodes before linearized reading' (duration: 350.186478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.160779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.810214Z","time spent":"350.556128ms","remote":"127.0.0.1:36382","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":"info","ts":"2026-08-05T11:36:33.160894Z","caller":"traceutil/trace.go:171","msg":"trace[1462146133] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"308.939043ms","start":"2026-08-05T11:36:32.851515Z","end":"2026-08-05T11:36:33.160454Z","steps":["trace[1462146133] 'process raft request' (duration: 308.659066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.161136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.851491Z","time spent":"309.533608ms","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:36:33.195367Z","caller":"traceutil/trace.go:171","msg":"trace[1398207045] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8639; }","duration":"119.860739ms","start":"2026-08-05T11:36:33.075485Z","end":"2026-08-05T11:36:33.195346Z","steps":["trace[1398207045] 'process raft request' (duration: 119.774517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.196142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.491284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rcxt6\" ","response":"range_response_count:1 size:4612"} {"level":"warn","ts":"2026-08-05T11:36:33.196168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.863999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-08-05T11:36:33.196175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.28701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2bkwx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-05T11:36:33.19624Z","caller":"traceutil/trace.go:171","msg":"trace[588133610] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8639; }","duration":"204.000583ms","start":"2026-08-05T11:36:32.992219Z","end":"2026-08-05T11:36:33.196219Z","steps":["trace[588133610] 'agreement among raft nodes before linearized reading' (duration: 203.702776ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:33.196283Z","caller":"traceutil/trace.go:171","msg":"trace[1119881087] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rcxt6; range_end:; response_count:1; response_revision:8639; }","duration":"344.644758ms","start":"2026-08-05T11:36:32.851552Z","end":"2026-08-05T11:36:33.196197Z","steps":["trace[1119881087] 'agreement among raft nodes before linearized reading' (duration: 344.365762ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:33.196305Z","caller":"traceutil/trace.go:171","msg":"trace[43846287] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2bkwx; range_end:; response_count:1; response_revision:8639; }","duration":"277.431544ms","start":"2026-08-05T11:36:32.91882Z","end":"2026-08-05T11:36:33.196252Z","steps":["trace[43846287] 'agreement among raft nodes before linearized reading' (duration: 277.125716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.196346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.851504Z","time spent":"344.832225ms","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4636,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rcxt6\" "} {"level":"warn","ts":"2026-08-05T11:36:33.19649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.151932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:36:33.196527Z","caller":"traceutil/trace.go:171","msg":"trace[636932340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8639; }","duration":"177.260444ms","start":"2026-08-05T11:36:33.019257Z","end":"2026-08-05T11:36:33.196517Z","steps":["trace[636932340] 'agreement among raft nodes before linearized reading' (duration: 177.192532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.196571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.068835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-05T11:36:33.196636Z","caller":"traceutil/trace.go:171","msg":"trace[779037024] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8639; }","duration":"206.147567ms","start":"2026-08-05T11:36:32.990469Z","end":"2026-08-05T11:36:33.196617Z","steps":["trace[779037024] 'agreement among raft nodes before linearized reading' (duration: 206.030984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.196693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.087603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:36:33.19672Z","caller":"traceutil/trace.go:171","msg":"trace[216971986] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8639; }","duration":"187.140195ms","start":"2026-08-05T11:36:33.009572Z","end":"2026-08-05T11:36:33.196712Z","steps":["trace[216971986] 'agreement among raft nodes before linearized reading' (duration: 187.089734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.196872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.024416ms","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-08-05T11:36:33.196909Z","caller":"traceutil/trace.go:171","msg":"trace[584351976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8639; }","duration":"313.095869ms","start":"2026-08-05T11:36:32.883805Z","end":"2026-08-05T11:36:33.196901Z","steps":["trace[584351976] 'agreement among raft nodes before linearized reading' (duration: 312.730549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.196929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.88379Z","time spent":"313.13479ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T11:36:33.196155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.738772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-08-05T11:36:33.197102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.750056ms","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":"info","ts":"2026-08-05T11:36:33.197118Z","caller":"traceutil/trace.go:171","msg":"trace[1481149719] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8639; }","duration":"151.788609ms","start":"2026-08-05T11:36:33.045319Z","end":"2026-08-05T11:36:33.197107Z","steps":["trace[1481149719] 'agreement among raft nodes before linearized reading' (duration: 150.67625ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:33.197123Z","caller":"traceutil/trace.go:171","msg":"trace[1712101956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8639; }","duration":"319.810679ms","start":"2026-08-05T11:36:32.877305Z","end":"2026-08-05T11:36:33.197115Z","steps":["trace[1712101956] 'agreement among raft nodes before linearized reading' (duration: 319.745087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:33.197143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:36:32.877284Z","time spent":"319.85475ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T11:37:42.662338Z","caller":"traceutil/trace.go:171","msg":"trace[2042481782] transaction","detail":"{read_only:false; response_revision:9384; number_of_response:1; }","duration":"133.213497ms","start":"2026-08-05T11:37:42.529092Z","end":"2026-08-05T11:37:42.662306Z","steps":["trace[2042481782] 'process raft request' (duration: 119.13644ms)","trace[2042481782] 'compare' (duration: 13.730108ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:37:51.421163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6568} {"level":"info","ts":"2026-08-05T11:37:51.530748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6568,"took":"106.681465ms","hash":158685522,"current-db-size-bytes":61440000,"current-db-size":"61 MB","current-db-size-in-use-bytes":26894336,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-05T11:37:51.530796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":158685522,"revision":6568,"compact-revision":3207} {"level":"info","ts":"2026-08-05T11:38:02.261335Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b696d7a595057bfe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:38:02.270358Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T11:38:02.270588Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T11:42:51.430302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9466} {"level":"info","ts":"2026-08-05T11:42:51.510024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9466,"took":"77.43851ms","hash":3371640430,"current-db-size-bytes":61440000,"current-db-size":"61 MB","current-db-size-in-use-bytes":28545024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T11:42:51.510103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3371640430,"revision":9466,"compact-revision":6568} {"level":"warn","ts":"2026-08-05T11:45:38.493665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934754433589433801,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T11:45:38.553471Z","caller":"traceutil/trace.go:171","msg":"trace[1594888434] linearizableReadLoop","detail":"{readStateIndex:14183; appliedIndex:14182; }","duration":"560.353935ms","start":"2026-08-05T11:45:37.993085Z","end":"2026-08-05T11:45:38.553439Z","steps":["trace[1594888434] 'read index received' (duration: 560.11379ms)","trace[1594888434] 'applied index is now lower than readState.Index' (duration: 238.775µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:38.553683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.572591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:45:38.553729Z","caller":"traceutil/trace.go:171","msg":"trace[920760880] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:13554; }","duration":"560.696944ms","start":"2026-08-05T11:45:37.993021Z","end":"2026-08-05T11:45:38.553718Z","steps":["trace[920760880] 'agreement among raft nodes before linearized reading' (duration: 560.58678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.553769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:37.992997Z","time spent":"560.762016ms","remote":"127.0.0.1:36406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:45:38.553829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.301541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:45:38.553888Z","caller":"traceutil/trace.go:171","msg":"trace[583036153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13554; }","duration":"535.350133ms","start":"2026-08-05T11:45:38.01852Z","end":"2026-08-05T11:45:38.55387Z","steps":["trace[583036153] 'agreement among raft nodes before linearized reading' (duration: 535.281131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.553898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.789215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-08-05T11:45:38.553893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.76235ms","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-08-05T11:45:38.553933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:38.018479Z","time spent":"535.446175ms","remote":"127.0.0.1:36108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T11:45:38.553959Z","caller":"traceutil/trace.go:171","msg":"trace[546260147] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:13554; }","duration":"101.874947ms","start":"2026-08-05T11:45:38.452067Z","end":"2026-08-05T11:45:38.553942Z","steps":["trace[546260147] 'agreement among raft nodes before linearized reading' (duration: 101.685023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.553941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.367ms","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":"info","ts":"2026-08-05T11:45:38.553976Z","caller":"traceutil/trace.go:171","msg":"trace[1167491526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13554; }","duration":"104.901284ms","start":"2026-08-05T11:45:38.449057Z","end":"2026-08-05T11:45:38.553958Z","steps":["trace[1167491526] 'agreement among raft nodes before linearized reading' (duration: 104.659118ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:38.554022Z","caller":"traceutil/trace.go:171","msg":"trace[426200075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13554; }","duration":"102.518594ms","start":"2026-08-05T11:45:38.451484Z","end":"2026-08-05T11:45:38.554003Z","steps":["trace[426200075] 'agreement among raft nodes before linearized reading' (duration: 102.321929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.553748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.412957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:45:38.554098Z","caller":"traceutil/trace.go:171","msg":"trace[1798206302] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:13554; }","duration":"545.838327ms","start":"2026-08-05T11:45:38.00824Z","end":"2026-08-05T11:45:38.554078Z","steps":["trace[1798206302] 'agreement among raft nodes before linearized reading' (duration: 545.425258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.554144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:38.008202Z","time spent":"545.93101ms","remote":"127.0.0.1:44642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:45:38.554131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.528398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:45:38.554288Z","caller":"traceutil/trace.go:171","msg":"trace[1151313545] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:13554; }","duration":"388.794035ms","start":"2026-08-05T11:45:38.165452Z","end":"2026-08-05T11:45:38.554246Z","steps":["trace[1151313545] 'agreement among raft nodes before linearized reading' (duration: 388.360354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.554309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.008322ms","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-08-05T11:45:38.554345Z","caller":"traceutil/trace.go:171","msg":"trace[774523255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13554; }","duration":"170.044533ms","start":"2026-08-05T11:45:38.38429Z","end":"2026-08-05T11:45:38.554335Z","steps":["trace[774523255] 'agreement among raft nodes before linearized reading' (duration: 169.911699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.554364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:38.16543Z","time spent":"388.920048ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:45:38.553682Z","caller":"traceutil/trace.go:171","msg":"trace[2023217475] transaction","detail":"{read_only:false; response_revision:13554; number_of_response:1; }","duration":"574.205216ms","start":"2026-08-05T11:45:37.979431Z","end":"2026-08-05T11:45:38.553636Z","steps":["trace[2023217475] 'process raft request' (duration: 573.767785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:38.55476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:37.979407Z","time spent":"575.151259ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6862,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:47:51.436876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12293} {"level":"info","ts":"2026-08-05T11:47:51.528963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12293,"took":"89.294738ms","hash":3288096791,"current-db-size-bytes":61440000,"current-db-size":"61 MB","current-db-size-in-use-bytes":25362432,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-05T11:47:51.529052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3288096791,"revision":12293,"compact-revision":9466} {"level":"info","ts":"2026-08-05T11:47:53.227627Z","caller":"traceutil/trace.go:171","msg":"trace[643048984] transaction","detail":"{read_only:false; response_revision:14707; number_of_response:1; }","duration":"176.536186ms","start":"2026-08-05T11:47:53.051062Z","end":"2026-08-05T11:47:53.227598Z","steps":["trace[643048984] 'process raft request' (duration: 176.002912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:48:10.43003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.888753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:48:10.430154Z","caller":"traceutil/trace.go:171","msg":"trace[1103942832] transaction","detail":"{read_only:false; response_revision:14840; number_of_response:1; }","duration":"477.393617ms","start":"2026-08-05T11:48:09.952736Z","end":"2026-08-05T11:48:10.43013Z","steps":["trace[1103942832] 'process raft request' (duration: 321.309079ms)","trace[1103942832] 'compare' (duration: 155.774931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:48:10.430216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:48:09.952716Z","time spent":"477.471848ms","remote":"127.0.0.1:36382","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-08-05T11:48:10.437131Z","caller":"traceutil/trace.go:171","msg":"trace[1941531174] linearizableReadLoop","detail":"{readStateIndex:15541; appliedIndex:15539; }","duration":"177.348046ms","start":"2026-08-05T11:48:10.259747Z","end":"2026-08-05T11:48:10.437095Z","steps":["trace[1941531174] 'read index received' (duration: 14.316222ms)","trace[1941531174] 'applied index is now lower than readState.Index' (duration: 163.030764ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:48:10.437266Z","caller":"traceutil/trace.go:171","msg":"trace[353692673] transaction","detail":"{read_only:false; response_revision:14841; number_of_response:1; }","duration":"482.481424ms","start":"2026-08-05T11:48:09.954711Z","end":"2026-08-05T11:48:10.437192Z","steps":["trace[353692673] 'process raft request' (duration: 482.227208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:48:10.4374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.92925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:48:10.437448Z","caller":"traceutil/trace.go:171","msg":"trace[1407982979] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:14841; }","duration":"141.987492ms","start":"2026-08-05T11:48:10.29544Z","end":"2026-08-05T11:48:10.437428Z","steps":["trace[1407982979] 'agreement among raft nodes before linearized reading' (duration: 141.935971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:48:10.437337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.563842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T11:48:10.437563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:48:09.95467Z","time spent":"482.743422ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:48:10.437598Z","caller":"traceutil/trace.go:171","msg":"trace[1332991855] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14841; }","duration":"177.85734ms","start":"2026-08-05T11:48:10.259691Z","end":"2026-08-05T11:48:10.437548Z","steps":["trace[1332991855] 'agreement among raft nodes before linearized reading' (duration: 177.572682ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:52:51.443074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14701} {"level":"info","ts":"2026-08-05T11:52:51.531166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14701,"took":"84.621753ms","hash":133164366,"current-db-size-bytes":61440000,"current-db-size":"61 MB","current-db-size-in-use-bytes":29712384,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:52:51.531221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":133164366,"revision":14701,"compact-revision":12293}