{"level":"warn","ts":"2026-10-14T17:41:53.220836Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:41:53.220955Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-14T17:41:53.221028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:41:53.221037Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-10-14T17:41:53.221059Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-14T17:41:53.221798Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-10-14T17:41:53.2219Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-14T17:41:53.224713Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.572494ms"} {"level":"info","ts":"2026-10-14T17:41:53.229926Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-10-14T17:41:53.230021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:41:53.230047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-10-14T17:41:53.230062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:41:53.230085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-10-14T17:41:53.230135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-10-14T17:41:53.242059Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:41:53.292202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:41:53.303304Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-14T17:41:53.369583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:41:53.370581Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:41:53.37069Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T17:41:53.37088Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T17:41:53.370899Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T17:41:53.373945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-10-14T17:41:53.374151Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-10-14T17:41:53.375754Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-14T17:41:53.376147Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:41:53.376203Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:41:53.376296Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-10-14T17:41:53.376319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-10-14T17:41:54.031064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:41:54.031161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:41:54.031193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-10-14T17:41:54.031215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:41:54.031235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-10-14T17:41:54.031257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-10-14T17:41:54.031276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-10-14T17:41:54.032741Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:41:54.032952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:41:54.033052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:41:54.033101Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:41:54.034408Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:41:54.03455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:41:54.034598Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:41:54.036458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-10-14T17:41:54.036573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:41:54.039639Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:41:54.039707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:41:55.728672Z","caller":"traceutil/trace.go:171","msg":"trace[2079473910] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"184.744879ms","start":"2026-10-14T17:41:55.543906Z","end":"2026-10-14T17:41:55.728651Z","steps":["trace[2079473910] 'read index received' (duration: 184.505053ms)","trace[2079473910] 'applied index is now lower than readState.Index' (duration: 238.886µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:55.728733Z","caller":"traceutil/trace.go:171","msg":"trace[2098820826] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"184.903293ms","start":"2026-10-14T17:41:55.543794Z","end":"2026-10-14T17:41:55.728697Z","steps":["trace[2098820826] 'process raft request' (duration: 184.696338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.72905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.121858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.729153Z","caller":"traceutil/trace.go:171","msg":"trace[1288557606] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"185.251431ms","start":"2026-10-14T17:41:55.543887Z","end":"2026-10-14T17:41:55.729139Z","steps":["trace[1288557606] 'agreement among raft nodes before linearized reading' (duration: 184.874502ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.733484Z","caller":"traceutil/trace.go:171","msg":"trace[2072329453] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"189.047107ms","start":"2026-10-14T17:41:55.544414Z","end":"2026-10-14T17:41:55.733461Z","steps":["trace[2072329453] 'process raft request' (duration: 188.646686ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.733553Z","caller":"traceutil/trace.go:171","msg":"trace[75936694] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"105.600099ms","start":"2026-10-14T17:41:55.627933Z","end":"2026-10-14T17:41:55.733533Z","steps":["trace[75936694] 'process raft request' (duration: 105.316652ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.733833Z","caller":"traceutil/trace.go:171","msg":"trace[442950802] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"103.809604ms","start":"2026-10-14T17:41:55.629993Z","end":"2026-10-14T17:41:55.733802Z","steps":["trace[442950802] 'process raft request' (duration: 103.372093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.733926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.797724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.733968Z","caller":"traceutil/trace.go:171","msg":"trace[457177226] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"103.859995ms","start":"2026-10-14T17:41:55.630095Z","end":"2026-10-14T17:41:55.733955Z","steps":["trace[457177226] 'agreement among raft nodes before linearized reading' (duration: 103.689631ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.734126Z","caller":"traceutil/trace.go:171","msg":"trace[1341921055] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"104.425939ms","start":"2026-10-14T17:41:55.629687Z","end":"2026-10-14T17:41:55.734113Z","steps":["trace[1341921055] 'process raft request' (duration: 103.598329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.734133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.484845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.734165Z","caller":"traceutil/trace.go:171","msg":"trace[1848684872] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"105.562687ms","start":"2026-10-14T17:41:55.628593Z","end":"2026-10-14T17:41:55.734155Z","steps":["trace[1848684872] 'agreement among raft nodes before linearized reading' (duration: 105.514456ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.734254Z","caller":"traceutil/trace.go:171","msg":"trace[1155365079] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"104.180592ms","start":"2026-10-14T17:41:55.630061Z","end":"2026-10-14T17:41:55.734242Z","steps":["trace[1155365079] 'process raft request' (duration: 103.25326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.734333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.467506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-14T17:41:55.734365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.821375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3175"} {"level":"info","ts":"2026-10-14T17:41:55.734396Z","caller":"traceutil/trace.go:171","msg":"trace[1904286484] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"189.884957ms","start":"2026-10-14T17:41:55.544502Z","end":"2026-10-14T17:41:55.734387Z","steps":["trace[1904286484] 'agreement among raft nodes before linearized reading' (duration: 189.774144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.734428Z","caller":"traceutil/trace.go:171","msg":"trace[712582051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"189.557619ms","start":"2026-10-14T17:41:55.544824Z","end":"2026-10-14T17:41:55.734382Z","steps":["trace[712582051] 'agreement among raft nodes before linearized reading' (duration: 189.436706ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.734425Z","caller":"traceutil/trace.go:171","msg":"trace[15418777] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"104.324526ms","start":"2026-10-14T17:41:55.630062Z","end":"2026-10-14T17:41:55.734386Z","steps":["trace[15418777] 'process raft request' (duration: 103.281781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.734611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.701937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.734637Z","caller":"traceutil/trace.go:171","msg":"trace[1329125933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"141.769279ms","start":"2026-10-14T17:41:55.592859Z","end":"2026-10-14T17:41:55.734628Z","steps":["trace[1329125933] 'agreement among raft nodes before linearized reading' (duration: 141.728008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.734688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.831811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.734748Z","caller":"traceutil/trace.go:171","msg":"trace[580085072] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"135.911493ms","start":"2026-10-14T17:41:55.598815Z","end":"2026-10-14T17:41:55.734726Z","steps":["trace[580085072] 'agreement among raft nodes before linearized reading' (duration: 135.769439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.734961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.812708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.734999Z","caller":"traceutil/trace.go:171","msg":"trace[674583597] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:0; response_revision:11; }","duration":"108.87012ms","start":"2026-10-14T17:41:55.626116Z","end":"2026-10-14T17:41:55.734986Z","steps":["trace[674583597] 'agreement among raft nodes before linearized reading' (duration: 108.814519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:55.735006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.118018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:41:55.735075Z","caller":"traceutil/trace.go:171","msg":"trace[19467435] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"136.224061ms","start":"2026-10-14T17:41:55.598833Z","end":"2026-10-14T17:41:55.735057Z","steps":["trace[19467435] 'agreement among raft nodes before linearized reading' (duration: 136.104588ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:55.73515Z","caller":"traceutil/trace.go:171","msg":"trace[1951688444] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"103.353012ms","start":"2026-10-14T17:41:55.630643Z","end":"2026-10-14T17:41:55.733996Z","steps":["trace[1951688444] 'process raft request' (duration: 102.745578ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:10.259748Z","caller":"traceutil/trace.go:171","msg":"trace[2087257876] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"108.052789ms","start":"2026-10-14T17:42:10.151665Z","end":"2026-10-14T17:42:10.259718Z","steps":["trace[2087257876] 'read index received' (duration: 107.811823ms)","trace[2087257876] 'applied index is now lower than readState.Index' (duration: 239.756µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:10.259799Z","caller":"traceutil/trace.go:171","msg":"trace[413508043] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"212.958071ms","start":"2026-10-14T17:42:10.046802Z","end":"2026-10-14T17:42:10.25976Z","steps":["trace[413508043] 'process raft request' (duration: 212.726026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:10.259937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.256455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-10-14T17:42:10.259992Z","caller":"traceutil/trace.go:171","msg":"trace[596317406] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:412; }","duration":"108.355017ms","start":"2026-10-14T17:42:10.151622Z","end":"2026-10-14T17:42:10.259977Z","steps":["trace[596317406] 'agreement among raft nodes before linearized reading' (duration: 108.212913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:10.80031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.510863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:42:10.800408Z","caller":"traceutil/trace.go:171","msg":"trace[292239082] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"522.051636ms","start":"2026-10-14T17:42:10.278341Z","end":"2026-10-14T17:42:10.800392Z","steps":["trace[292239082] 'read index received' (duration: 340.63268ms)","trace[292239082] 'applied index is now lower than readState.Index' (duration: 181.417816ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:10.800521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.18674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-14T17:42:10.800552Z","caller":"traceutil/trace.go:171","msg":"trace[1550518715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"522.261012ms","start":"2026-10-14T17:42:10.278282Z","end":"2026-10-14T17:42:10.800543Z","steps":["trace[1550518715] 'agreement among raft nodes before linearized reading' (duration: 522.19346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:10.800581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:10.278264Z","time spent":"522.310243ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T17:42:10.800611Z","caller":"traceutil/trace.go:171","msg":"trace[358057845] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"531.946973ms","start":"2026-10-14T17:42:10.268483Z","end":"2026-10-14T17:42:10.80043Z","steps":["trace[358057845] 'process raft request' (duration: 350.536977ms)","trace[358057845] 'compare' (duration: 180.37921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:10.800677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.505166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T17:42:10.800778Z","caller":"traceutil/trace.go:171","msg":"trace[274919415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"256.66064ms","start":"2026-10-14T17:42:10.544089Z","end":"2026-10-14T17:42:10.80075Z","steps":["trace[274919415] 'agreement among raft nodes before linearized reading' (duration: 256.440975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:10.800802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:10.26846Z","time spent":"532.24389ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:42:38.914032Z","caller":"traceutil/trace.go:171","msg":"trace[1460299075] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"104.741977ms","start":"2026-10-14T17:42:38.809264Z","end":"2026-10-14T17:42:38.914006Z","steps":["trace[1460299075] 'process raft request' (duration: 104.614894ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:38.925138Z","caller":"traceutil/trace.go:171","msg":"trace[293316848] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"101.586879ms","start":"2026-10-14T17:42:38.823519Z","end":"2026-10-14T17:42:38.925106Z","steps":["trace[293316848] 'process raft request' (duration: 100.944073ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:39.113418Z","caller":"traceutil/trace.go:171","msg":"trace[716036026] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"104.336037ms","start":"2026-10-14T17:42:39.009052Z","end":"2026-10-14T17:42:39.113388Z","steps":["trace[716036026] 'process raft request' (duration: 102.620525ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:39.117162Z","caller":"traceutil/trace.go:171","msg":"trace[934515441] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"100.01262ms","start":"2026-10-14T17:42:39.017115Z","end":"2026-10-14T17:42:39.117128Z","steps":["trace[934515441] 'process raft request' (duration: 99.751943ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:45.721148Z","caller":"traceutil/trace.go:171","msg":"trace[1756172211] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"116.618649ms","start":"2026-10-14T17:42:45.604504Z","end":"2026-10-14T17:42:45.721123Z","steps":["trace[1756172211] 'read index received' (duration: 115.86743ms)","trace[1756172211] 'applied index is now lower than readState.Index' (duration: 750.219µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:45.721343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.824834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4n9d4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T17:42:45.72142Z","caller":"traceutil/trace.go:171","msg":"trace[1797084912] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4n9d4; range_end:; response_count:1; response_revision:701; }","duration":"116.953947ms","start":"2026-10-14T17:42:45.604454Z","end":"2026-10-14T17:42:45.721408Z","steps":["trace[1797084912] 'agreement among raft nodes before linearized reading' (duration: 116.770153ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:45.721455Z","caller":"traceutil/trace.go:171","msg":"trace[951710486] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"473.721279ms","start":"2026-10-14T17:42:45.247693Z","end":"2026-10-14T17:42:45.721414Z","steps":["trace[951710486] 'process raft request' (duration: 472.885297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:45.721622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:45.247637Z","time spent":"473.898833ms","remote":"127.0.0.1:57940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:42:45.999208Z","caller":"traceutil/trace.go:171","msg":"trace[2115596384] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"260.479406ms","start":"2026-10-14T17:42:45.738706Z","end":"2026-10-14T17:42:45.999186Z","steps":["trace[2115596384] 'process raft request' (duration: 260.292841ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:46.008865Z","caller":"traceutil/trace.go:171","msg":"trace[257466294] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"209.130039ms","start":"2026-10-14T17:42:45.79969Z","end":"2026-10-14T17:42:46.00882Z","steps":["trace[257466294] 'process raft request' (duration: 208.962275ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:46.24592Z","caller":"traceutil/trace.go:171","msg":"trace[735393742] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"229.311161ms","start":"2026-10-14T17:42:46.016582Z","end":"2026-10-14T17:42:46.245893Z","steps":["trace[735393742] 'read index received' (duration: 217.2273ms)","trace[735393742] 'applied index is now lower than readState.Index' (duration: 12.082181ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:46.246087Z","caller":"traceutil/trace.go:171","msg":"trace[710843938] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"232.992524ms","start":"2026-10-14T17:42:46.013082Z","end":"2026-10-14T17:42:46.246074Z","steps":["trace[710843938] 'process raft request' (duration: 220.79697ms)","trace[710843938] 'compare' (duration: 11.599309ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:46.246276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.02685ms","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-10-14T17:42:46.246371Z","caller":"traceutil/trace.go:171","msg":"trace[1144173984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:704; }","duration":"191.172283ms","start":"2026-10-14T17:42:46.055176Z","end":"2026-10-14T17:42:46.246349Z","steps":["trace[1144173984] 'agreement among raft nodes before linearized reading' (duration: 190.954868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:46.2465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.895055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-10-14T17:42:46.246568Z","caller":"traceutil/trace.go:171","msg":"trace[1390847751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:704; }","duration":"230.021499ms","start":"2026-10-14T17:42:46.016534Z","end":"2026-10-14T17:42:46.246556Z","steps":["trace[1390847751] 'agreement among raft nodes before linearized reading' (duration: 229.867375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:46.874492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.54076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:42:46.874758Z","caller":"traceutil/trace.go:171","msg":"trace[1985599106] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:730; }","duration":"434.7864ms","start":"2026-10-14T17:42:46.439958Z","end":"2026-10-14T17:42:46.874745Z","steps":["trace[1985599106] 'read index received' (duration: 191.937122ms)","trace[1985599106] 'applied index is now lower than readState.Index' (duration: 242.848348ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:46.874746Z","caller":"traceutil/trace.go:171","msg":"trace[1925266054] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"588.240596ms","start":"2026-10-14T17:42:46.286462Z","end":"2026-10-14T17:42:46.874702Z","steps":["trace[1925266054] 'process raft request' (duration: 345.420689ms)","trace[1925266054] 'compare' (duration: 242.281834ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:46.874767Z","caller":"traceutil/trace.go:171","msg":"trace[493044386] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"588.206855ms","start":"2026-10-14T17:42:46.286527Z","end":"2026-10-14T17:42:46.874734Z","steps":["trace[493044386] 'process raft request' (duration: 588.062361ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:46.874832Z","caller":"traceutil/trace.go:171","msg":"trace[553290760] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"587.547608ms","start":"2026-10-14T17:42:46.287243Z","end":"2026-10-14T17:42:46.874791Z","steps":["trace[553290760] 'process raft request' (duration: 587.407165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:46.874929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:46.286437Z","time spent":"588.391969ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T17:42:46.874936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:46.286499Z","time spent":"588.366609ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T17:42:46.875016Z","caller":"traceutil/trace.go:171","msg":"trace[1003003038] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"586.912233ms","start":"2026-10-14T17:42:46.288087Z","end":"2026-10-14T17:42:46.874999Z","steps":["trace[1003003038] 'process raft request' (duration: 586.626205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:46.875026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:46.287234Z","time spent":"587.700602ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:42:46.875092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:46.288065Z","time spent":"586.984544ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:42:46.875237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.286343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T17:42:46.875259Z","caller":"traceutil/trace.go:171","msg":"trace[1045942286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:710; }","duration":"435.339955ms","start":"2026-10-14T17:42:46.439912Z","end":"2026-10-14T17:42:46.875252Z","steps":["trace[1045942286] 'agreement among raft nodes before linearized reading' (duration: 434.871053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:46.875297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:46.439892Z","time spent":"435.400986ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T17:43:31.829805Z","caller":"traceutil/trace.go:171","msg":"trace[280294229] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"281.685164ms","start":"2026-10-14T17:43:31.548089Z","end":"2026-10-14T17:43:31.829774Z","steps":["trace[280294229] 'process raft request' (duration: 281.53549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:10.657968Z","caller":"traceutil/trace.go:171","msg":"trace[1411369748] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"104.075469ms","start":"2026-10-14T17:44:10.553854Z","end":"2026-10-14T17:44:10.65793Z","steps":["trace[1411369748] 'process raft request' (duration: 103.825023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:18.557554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.501257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:44:18.5578Z","caller":"traceutil/trace.go:171","msg":"trace[721768645] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"443.645627ms","start":"2026-10-14T17:44:18.114112Z","end":"2026-10-14T17:44:18.557757Z","steps":["trace[721768645] 'process raft request' (duration: 154.843902ms)","trace[721768645] 'compare' (duration: 288.379134ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:44:18.557879Z","caller":"traceutil/trace.go:171","msg":"trace[568794307] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"199.620387ms","start":"2026-10-14T17:44:18.3582Z","end":"2026-10-14T17:44:18.55782Z","steps":["trace[568794307] 'process raft request' (duration: 199.532305ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:18.557874Z","caller":"traceutil/trace.go:171","msg":"trace[174841944] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"220.363682ms","start":"2026-10-14T17:44:18.337454Z","end":"2026-10-14T17:44:18.557818Z","steps":["trace[174841944] 'read index received' (duration: 110.533µs)","trace[174841944] 'applied index is now lower than readState.Index' (duration: 220.249799ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:44:18.558001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:44:18.114087Z","time spent":"443.825712ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:44:18.558172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.703491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T17:44:18.558236Z","caller":"traceutil/trace.go:171","msg":"trace[1438634166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"220.815163ms","start":"2026-10-14T17:44:18.337402Z","end":"2026-10-14T17:44:18.558217Z","steps":["trace[1438634166] 'agreement among raft nodes before linearized reading' (duration: 220.600887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:18.645255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.534685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a770e55-1917-4f98-b7b3-adcbf2c4636b\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-14T17:44:18.645338Z","caller":"traceutil/trace.go:171","msg":"trace[1332520764] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7a770e55-1917-4f98-b7b3-adcbf2c4636b; range_end:; response_count:1; response_revision:1891; }","duration":"275.670859ms","start":"2026-10-14T17:44:18.369647Z","end":"2026-10-14T17:44:18.645318Z","steps":["trace[1332520764] 'agreement among raft nodes before linearized reading' (duration: 275.464263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:19.077025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.484791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:44:19.077135Z","caller":"traceutil/trace.go:171","msg":"trace[1456924443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"172.646305ms","start":"2026-10-14T17:44:18.904462Z","end":"2026-10-14T17:44:19.077109Z","steps":["trace[1456924443] 'range keys from in-memory index tree' (duration: 172.314876ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:32.603087Z","caller":"traceutil/trace.go:171","msg":"trace[258999370] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"196.612492ms","start":"2026-10-14T17:44:32.40645Z","end":"2026-10-14T17:44:32.603062Z","steps":["trace[258999370] 'process raft request' (duration: 196.454748ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:32.603904Z","caller":"traceutil/trace.go:171","msg":"trace[917670171] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"169.440716ms","start":"2026-10-14T17:44:32.434443Z","end":"2026-10-14T17:44:32.603883Z","steps":["trace[917670171] 'process raft request' (duration: 169.311963ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:08.295707Z","caller":"traceutil/trace.go:171","msg":"trace[1777087599] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"140.300267ms","start":"2026-10-14T17:45:08.155382Z","end":"2026-10-14T17:45:08.295682Z","steps":["trace[1777087599] 'process raft request' (duration: 140.148985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:08.503231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.786654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:45:08.503553Z","caller":"traceutil/trace.go:171","msg":"trace[1830418130] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"169.538267ms","start":"2026-10-14T17:45:08.333942Z","end":"2026-10-14T17:45:08.50348Z","steps":["trace[1830418130] 'process raft request' (duration: 13.398334ms)","trace[1830418130] 'compare' (duration: 155.6545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:45:08.882656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.954382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:45:08.88274Z","caller":"traceutil/trace.go:171","msg":"trace[2066631437] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2210; }","duration":"260.108215ms","start":"2026-10-14T17:45:08.622612Z","end":"2026-10-14T17:45:08.88272Z","steps":["trace[2066631437] 'count revisions from in-memory index tree' (duration: 259.839809ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:46:27.201741Z","caller":"traceutil/trace.go:171","msg":"trace[938516024] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"116.305287ms","start":"2026-10-14T17:46:27.085408Z","end":"2026-10-14T17:46:27.201714Z","steps":["trace[938516024] 'process raft request' (duration: 95.494827ms)","trace[938516024] 'compare' (duration: 20.505252ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:49.228946Z","caller":"traceutil/trace.go:171","msg":"trace[587902109] transaction","detail":"{read_only:false; response_revision:3927; number_of_response:1; }","duration":"160.525568ms","start":"2026-10-14T17:49:49.068394Z","end":"2026-10-14T17:49:49.22892Z","steps":["trace[587902109] 'process raft request' (duration: 160.357664ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:54.704401Z","caller":"traceutil/trace.go:171","msg":"trace[1335130960] transaction","detail":"{read_only:false; response_revision:3955; number_of_response:1; }","duration":"113.059883ms","start":"2026-10-14T17:49:54.591313Z","end":"2026-10-14T17:49:54.704373Z","steps":["trace[1335130960] 'process raft request' (duration: 112.902749ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:54.785383Z","caller":"traceutil/trace.go:171","msg":"trace[205228791] transaction","detail":"{read_only:false; response_revision:3956; number_of_response:1; }","duration":"193.956053ms","start":"2026-10-14T17:49:54.591388Z","end":"2026-10-14T17:49:54.785344Z","steps":["trace[205228791] 'process raft request' (duration: 193.700908ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:12.342759Z","caller":"traceutil/trace.go:171","msg":"trace[1272486483] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"111.747799ms","start":"2026-10-14T17:51:12.230987Z","end":"2026-10-14T17:51:12.342735Z","steps":["trace[1272486483] 'process raft request' (duration: 103.619886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:21.237743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.517153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:51:21.237825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.891063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-14T17:51:21.237864Z","caller":"traceutil/trace.go:171","msg":"trace[639662916] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5116; }","duration":"105.975526ms","start":"2026-10-14T17:51:21.131877Z","end":"2026-10-14T17:51:21.237853Z","steps":["trace[639662916] 'range keys from in-memory index tree' (duration: 105.713819ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:21.237864Z","caller":"traceutil/trace.go:171","msg":"trace[1432802203] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5116; }","duration":"224.716468ms","start":"2026-10-14T17:51:21.013127Z","end":"2026-10-14T17:51:21.237843Z","steps":["trace[1432802203] 'count revisions from in-memory index tree' (duration: 224.38901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:23.555926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.73585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:51:23.556041Z","caller":"traceutil/trace.go:171","msg":"trace[1079155806] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5154; }","duration":"297.896824ms","start":"2026-10-14T17:51:23.258126Z","end":"2026-10-14T17:51:23.556022Z","steps":["trace[1079155806] 'count revisions from in-memory index tree' (duration: 297.591097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:23.556334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.74352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-14T17:51:23.5564Z","caller":"traceutil/trace.go:171","msg":"trace[1502805619] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5154; }","duration":"287.861283ms","start":"2026-10-14T17:51:23.268524Z","end":"2026-10-14T17:51:23.556386Z","steps":["trace[1502805619] 'range keys from in-memory index tree' (duration: 287.574016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:23.556404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.841105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T17:51:23.556478Z","caller":"traceutil/trace.go:171","msg":"trace[115588753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5154; }","duration":"219.945228ms","start":"2026-10-14T17:51:23.336515Z","end":"2026-10-14T17:51:23.556461Z","steps":["trace[115588753] 'range keys from in-memory index tree' (duration: 219.696182ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:23.93369Z","caller":"traceutil/trace.go:171","msg":"trace[1678526495] linearizableReadLoop","detail":"{readStateIndex:5354; appliedIndex:5351; }","duration":"123.566544ms","start":"2026-10-14T17:51:23.810082Z","end":"2026-10-14T17:51:23.933648Z","steps":["trace[1678526495] 'read index received' (duration: 17.047976ms)","trace[1678526495] 'applied index is now lower than readState.Index' (duration: 106.517758ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:51:23.933914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.81434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07aad67c-cfbf-470d-a7f3-860b91700047\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-10-14T17:51:23.933986Z","caller":"traceutil/trace.go:171","msg":"trace[986016793] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07aad67c-cfbf-470d-a7f3-860b91700047; range_end:; response_count:1; response_revision:5160; }","duration":"123.910673ms","start":"2026-10-14T17:51:23.810031Z","end":"2026-10-14T17:51:23.933942Z","steps":["trace[986016793] 'agreement among raft nodes before linearized reading' (duration: 123.724318ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:23.933993Z","caller":"traceutil/trace.go:171","msg":"trace[421810665] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"166.086125ms","start":"2026-10-14T17:51:23.767861Z","end":"2026-10-14T17:51:23.933947Z","steps":["trace[421810665] 'process raft request' (duration: 165.740316ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:23.933987Z","caller":"traceutil/trace.go:171","msg":"trace[945751687] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"166.084865ms","start":"2026-10-14T17:51:23.767815Z","end":"2026-10-14T17:51:23.9339Z","steps":["trace[945751687] 'process raft request' (duration: 112.066396ms)","trace[945751687] 'compare' (duration: 53.530276ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:51:37.837471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.750941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:51:37.837809Z","caller":"traceutil/trace.go:171","msg":"trace[389409999] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"425.155719ms","start":"2026-10-14T17:51:37.41259Z","end":"2026-10-14T17:51:37.837746Z","steps":["trace[389409999] 'process raft request' (duration: 425.001775ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:37.837846Z","caller":"traceutil/trace.go:171","msg":"trace[1947736034] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"425.227171ms","start":"2026-10-14T17:51:37.412593Z","end":"2026-10-14T17:51:37.83782Z","steps":["trace[1947736034] 'process raft request' (duration: 425.081327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:37.837958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:37.412563Z","time spent":"425.344684ms","remote":"127.0.0.1:58102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:37.837992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:37.412569Z","time spent":"425.331083ms","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:51:37.837768Z","caller":"traceutil/trace.go:171","msg":"trace[1580547990] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"425.356614ms","start":"2026-10-14T17:51:37.41237Z","end":"2026-10-14T17:51:37.837727Z","steps":["trace[1580547990] 'process raft request' (duration: 163.263714ms)","trace[1580547990] 'compare' (duration: 261.597737ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:37.838043Z","caller":"traceutil/trace.go:171","msg":"trace[918801459] linearizableReadLoop","detail":"{readStateIndex:5597; appliedIndex:5593; }","duration":"356.402653ms","start":"2026-10-14T17:51:37.481619Z","end":"2026-10-14T17:51:37.838022Z","steps":["trace[918801459] 'read index received' (duration: 93.983465ms)","trace[918801459] 'applied index is now lower than readState.Index' (duration: 262.417618ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:37.838074Z","caller":"traceutil/trace.go:171","msg":"trace[814965687] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"425.221421ms","start":"2026-10-14T17:51:37.41283Z","end":"2026-10-14T17:51:37.838051Z","steps":["trace[814965687] 'process raft request' (duration: 424.902203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:37.838178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:37.412357Z","time spent":"425.743774ms","remote":"127.0.0.1:58102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:37.838184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:37.412759Z","time spent":"425.368155ms","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:37.838304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.675461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-14T17:51:37.838366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.982969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-92twn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T17:51:37.838367Z","caller":"traceutil/trace.go:171","msg":"trace[120145657] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:5395; }","duration":"356.787774ms","start":"2026-10-14T17:51:37.481561Z","end":"2026-10-14T17:51:37.838349Z","steps":["trace[120145657] 'agreement among raft nodes before linearized reading' (duration: 356.607039ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:37.838395Z","caller":"traceutil/trace.go:171","msg":"trace[1354489493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-92twn; range_end:; response_count:1; response_revision:5395; }","duration":"129.028159ms","start":"2026-10-14T17:51:37.709356Z","end":"2026-10-14T17:51:37.838384Z","steps":["trace[1354489493] 'agreement among raft nodes before linearized reading' (duration: 128.935347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:37.83839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.828084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-10-14T17:51:37.838408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:37.481537Z","time spent":"356.859406ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"info","ts":"2026-10-14T17:51:37.838453Z","caller":"traceutil/trace.go:171","msg":"trace[1869724546] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5395; }","duration":"134.930677ms","start":"2026-10-14T17:51:37.703506Z","end":"2026-10-14T17:51:37.838437Z","steps":["trace[1869724546] 'agreement among raft nodes before linearized reading' (duration: 134.795713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:37.838498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.008249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-14T17:51:37.838398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.637452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7fb4954cdb-5cbcg\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-10-14T17:51:37.83856Z","caller":"traceutil/trace.go:171","msg":"trace[2086971602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5395; }","duration":"143.098481ms","start":"2026-10-14T17:51:37.695447Z","end":"2026-10-14T17:51:37.838546Z","steps":["trace[2086971602] 'agreement among raft nodes before linearized reading' (duration: 142.912327ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:37.838585Z","caller":"traceutil/trace.go:171","msg":"trace[1558998698] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7fb4954cdb-5cbcg; range_end:; response_count:1; response_revision:5395; }","duration":"167.872029ms","start":"2026-10-14T17:51:37.670702Z","end":"2026-10-14T17:51:37.838574Z","steps":["trace[1558998698] 'agreement among raft nodes before linearized reading' (duration: 167.552621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.149983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.90385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-858f8553-218b-45bd-822d-f4faf240904e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T17:51:45.150102Z","caller":"traceutil/trace.go:171","msg":"trace[966159044] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-858f8553-218b-45bd-822d-f4faf240904e; range_end:; response_count:1; response_revision:5524; }","duration":"144.068965ms","start":"2026-10-14T17:51:45.006007Z","end":"2026-10-14T17:51:45.150076Z","steps":["trace[966159044] 'range keys from in-memory index tree' (duration: 143.705555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.150267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.574807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:51:45.150346Z","caller":"traceutil/trace.go:171","msg":"trace[850198683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5524; }","duration":"158.69394ms","start":"2026-10-14T17:51:44.991633Z","end":"2026-10-14T17:51:45.150327Z","steps":["trace[850198683] 'range keys from in-memory index tree' (duration: 158.404862ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:45.514273Z","caller":"traceutil/trace.go:171","msg":"trace[1982482265] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5728; }","duration":"117.124412ms","start":"2026-10-14T17:51:45.397124Z","end":"2026-10-14T17:51:45.514248Z","steps":["trace[1982482265] 'read index received' (duration: 116.828555ms)","trace[1982482265] 'applied index is now lower than readState.Index' (duration: 294.697µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:45.514328Z","caller":"traceutil/trace.go:171","msg":"trace[1088557975] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"359.87861ms","start":"2026-10-14T17:51:45.154413Z","end":"2026-10-14T17:51:45.514292Z","steps":["trace[1088557975] 'process raft request' (duration: 359.647054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.514577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:45.154389Z","time spent":"360.066744ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:45.514611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.458931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-858f8553-218b-45bd-822d-f4faf240904e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T17:51:45.514885Z","caller":"traceutil/trace.go:171","msg":"trace[743162717] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-858f8553-218b-45bd-822d-f4faf240904e; range_end:; response_count:1; response_revision:5525; }","duration":"117.668617ms","start":"2026-10-14T17:51:45.397074Z","end":"2026-10-14T17:51:45.514742Z","steps":["trace[743162717] 'agreement among raft nodes before linearized reading' (duration: 117.270757ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:45.906439Z","caller":"traceutil/trace.go:171","msg":"trace[1089959146] linearizableReadLoop","detail":"{readStateIndex:5730; appliedIndex:5729; }","duration":"377.10048ms","start":"2026-10-14T17:51:45.529312Z","end":"2026-10-14T17:51:45.906413Z","steps":["trace[1089959146] 'read index received' (duration: 376.821143ms)","trace[1089959146] 'applied index is now lower than readState.Index' (duration: 278.117µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:45.906479Z","caller":"traceutil/trace.go:171","msg":"trace[2083168006] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"383.989062ms","start":"2026-10-14T17:51:45.522463Z","end":"2026-10-14T17:51:45.906452Z","steps":["trace[2083168006] 'process raft request' (duration: 383.752776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.906624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:45.522441Z","time spent":"384.100464ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:45.906662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.310775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:51:45.906726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.724618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T17:51:45.906804Z","caller":"traceutil/trace.go:171","msg":"trace[1551594479] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5526; }","duration":"210.829411ms","start":"2026-10-14T17:51:45.695934Z","end":"2026-10-14T17:51:45.906763Z","steps":["trace[1551594479] 'agreement among raft nodes before linearized reading' (duration: 210.627506ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:45.906775Z","caller":"traceutil/trace.go:171","msg":"trace[2125805673] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5526; }","duration":"377.461489ms","start":"2026-10-14T17:51:45.529285Z","end":"2026-10-14T17:51:45.906747Z","steps":["trace[2125805673] 'agreement among raft nodes before linearized reading' (duration: 377.254013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.906975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:45.529267Z","time spent":"377.650764ms","remote":"127.0.0.1:58272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":20,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:51:47.705401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.80622ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-14T17:51:47.705552Z","caller":"traceutil/trace.go:171","msg":"trace[480381983] linearizableReadLoop","detail":"{readStateIndex:5746; appliedIndex:5745; }","duration":"185.638832ms","start":"2026-10-14T17:51:47.519888Z","end":"2026-10-14T17:51:47.705527Z","steps":["trace[480381983] 'read index received' (duration: 79.588255ms)","trace[480381983] 'applied index is now lower than readState.Index' (duration: 106.048057ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:51:47.706054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.147164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:51:47.706126Z","caller":"traceutil/trace.go:171","msg":"trace[825567736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5541; }","duration":"186.270107ms","start":"2026-10-14T17:51:47.51984Z","end":"2026-10-14T17:51:47.70611Z","steps":["trace[825567736] 'agreement among raft nodes before linearized reading' (duration: 186.065122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:47.706038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.271807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18de76c83413fa01\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-10-14T17:51:47.706197Z","caller":"traceutil/trace.go:171","msg":"trace[1334327458] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18de76c83413fa01; range_end:; response_count:1; response_revision:5541; }","duration":"101.475562ms","start":"2026-10-14T17:51:47.6047Z","end":"2026-10-14T17:51:47.706176Z","steps":["trace[1334327458] 'agreement among raft nodes before linearized reading' (duration: 101.146704ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:54.064951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2828} {"level":"info","ts":"2026-10-14T17:51:54.140974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2828,"took":"74.475969ms","hash":878757375,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T17:51:54.141042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":878757375,"revision":2828,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:52:01.945559Z","caller":"traceutil/trace.go:171","msg":"trace[1529255759] transaction","detail":"{read_only:false; response_revision:5750; number_of_response:1; }","duration":"140.608189ms","start":"2026-10-14T17:52:01.804912Z","end":"2026-10-14T17:52:01.94552Z","steps":["trace[1529255759] 'process raft request' (duration: 140.289581ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:58.478946Z","caller":"traceutil/trace.go:171","msg":"trace[1057873110] transaction","detail":"{read_only:false; response_revision:6213; number_of_response:1; }","duration":"106.821862ms","start":"2026-10-14T17:52:58.372093Z","end":"2026-10-14T17:52:58.478915Z","steps":["trace[1057873110] 'process raft request' (duration: 88.32911ms)","trace[1057873110] 'compare' (duration: 18.219345ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:56:54.071128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5659} {"level":"info","ts":"2026-10-14T17:56:54.169271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5659,"took":"96.273483ms","hash":4022642381,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":25804800,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T17:56:54.169351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4022642381,"revision":5659,"compact-revision":2828} {"level":"warn","ts":"2026-10-14T17:57:55.314389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997605499758977023,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T17:57:55.541346Z","caller":"traceutil/trace.go:171","msg":"trace[421238130] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8711; }","duration":"727.780642ms","start":"2026-10-14T17:57:54.813542Z","end":"2026-10-14T17:57:55.541323Z","steps":["trace[421238130] 'read index received' (duration: 727.565097ms)","trace[421238130] 'applied index is now lower than readState.Index' (duration: 214.835µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:55.541454Z","caller":"traceutil/trace.go:171","msg":"trace[957488664] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"823.198634ms","start":"2026-10-14T17:57:54.718245Z","end":"2026-10-14T17:57:55.541444Z","steps":["trace[957488664] 'process raft request' (duration: 822.932618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:55.541571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:54.718226Z","time spent":"823.253255ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:57:55.541684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.126971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:57:55.54176Z","caller":"traceutil/trace.go:171","msg":"trace[1825801116] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8415; }","duration":"728.272344ms","start":"2026-10-14T17:57:54.813458Z","end":"2026-10-14T17:57:55.54173Z","steps":["trace[1825801116] 'agreement among raft nodes before linearized reading' (duration: 728.1282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:55.541789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:54.813425Z","time spent":"728.356856ms","remote":"127.0.0.1:58370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:57:55.541694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.105525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-14T17:57:55.541771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.218537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-766488547f-tr584.18de771c3cb197d3\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-14T17:57:55.541835Z","caller":"traceutil/trace.go:171","msg":"trace[334010811] range","detail":"{range_begin:/registry/events/openstack/barbican-api-766488547f-tr584.18de771c3cb197d3; range_end:; response_count:1; response_revision:8415; }","duration":"638.3429ms","start":"2026-10-14T17:57:54.903474Z","end":"2026-10-14T17:57:55.541817Z","steps":["trace[334010811] 'agreement among raft nodes before linearized reading' (duration: 638.151245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:55.541837Z","caller":"traceutil/trace.go:171","msg":"trace[1754097484] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8415; }","duration":"727.292889ms","start":"2026-10-14T17:57:54.814518Z","end":"2026-10-14T17:57:55.54181Z","steps":["trace[1754097484] 'agreement among raft nodes before linearized reading' (duration: 727.040933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:55.541864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:54.903447Z","time spent":"638.410821ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-766488547f-tr584.18de771c3cb197d3\" "} {"level":"warn","ts":"2026-10-14T17:57:55.541879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:54.814495Z","time spent":"727.373142ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T17:57:55.698183Z","caller":"traceutil/trace.go:171","msg":"trace[1348501849] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"153.368994ms","start":"2026-10-14T17:57:55.544787Z","end":"2026-10-14T17:57:55.698156Z","steps":["trace[1348501849] 'process raft request' (duration: 153.310142ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:55.698251Z","caller":"traceutil/trace.go:171","msg":"trace[909029168] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"154.102593ms","start":"2026-10-14T17:57:55.544129Z","end":"2026-10-14T17:57:55.698231Z","steps":["trace[909029168] 'process raft request' (duration: 112.474059ms)","trace[909029168] 'compare' (duration: 41.356648ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:06.204021Z","caller":"traceutil/trace.go:171","msg":"trace[750358377] linearizableReadLoop","detail":"{readStateIndex:8773; appliedIndex:8770; }","duration":"272.711926ms","start":"2026-10-14T17:58:05.931283Z","end":"2026-10-14T17:58:06.203995Z","steps":["trace[750358377] 'read index received' (duration: 20.657948ms)","trace[750358377] 'applied index is now lower than readState.Index' (duration: 252.049808ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:06.204061Z","caller":"traceutil/trace.go:171","msg":"trace[1408765603] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"291.102937ms","start":"2026-10-14T17:58:05.912925Z","end":"2026-10-14T17:58:06.204028Z","steps":["trace[1408765603] 'process raft request' (duration: 290.790149ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.20414Z","caller":"traceutil/trace.go:171","msg":"trace[2010173806] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"261.72764ms","start":"2026-10-14T17:58:05.942401Z","end":"2026-10-14T17:58:06.204129Z","steps":["trace[2010173806] 'process raft request' (duration: 261.545556ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.2041Z","caller":"traceutil/trace.go:171","msg":"trace[990057169] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"290.471882ms","start":"2026-10-14T17:58:05.913595Z","end":"2026-10-14T17:58:06.204067Z","steps":["trace[990057169] 'process raft request' (duration: 290.299477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:06.204424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.125406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-14T17:58:06.204435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.598673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-14T17:58:06.204476Z","caller":"traceutil/trace.go:171","msg":"trace[1941981184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8474; }","duration":"273.219169ms","start":"2026-10-14T17:58:05.931241Z","end":"2026-10-14T17:58:06.20446Z","steps":["trace[1941981184] 'agreement among raft nodes before linearized reading' (duration: 272.990813ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.204479Z","caller":"traceutil/trace.go:171","msg":"trace[755287894] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8474; }","duration":"193.730096ms","start":"2026-10-14T17:58:06.010736Z","end":"2026-10-14T17:58:06.204466Z","steps":["trace[755287894] 'agreement among raft nodes before linearized reading' (duration: 193.521111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:06.204792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.389258ms","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-10-14T17:58:06.204863Z","caller":"traceutil/trace.go:171","msg":"trace[1238673322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8474; }","duration":"213.816388ms","start":"2026-10-14T17:58:05.991028Z","end":"2026-10-14T17:58:06.204844Z","steps":["trace[1238673322] 'agreement among raft nodes before linearized reading' (duration: 213.191374ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.358076Z","caller":"traceutil/trace.go:171","msg":"trace[321432185] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"149.130558ms","start":"2026-10-14T17:58:06.208916Z","end":"2026-10-14T17:58:06.358047Z","steps":["trace[321432185] 'process raft request' (duration: 124.160232ms)","trace[321432185] 'compare' (duration: 24.858163ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:06.410802Z","caller":"traceutil/trace.go:171","msg":"trace[2078871470] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"200.882794ms","start":"2026-10-14T17:58:06.209895Z","end":"2026-10-14T17:58:06.410778Z","steps":["trace[2078871470] 'process raft request' (duration: 200.781352ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.410803Z","caller":"traceutil/trace.go:171","msg":"trace[682864745] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"201.003948ms","start":"2026-10-14T17:58:06.209776Z","end":"2026-10-14T17:58:06.41078Z","steps":["trace[682864745] 'process raft request' (duration: 200.782872ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.411044Z","caller":"traceutil/trace.go:171","msg":"trace[100953182] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"201.031949ms","start":"2026-10-14T17:58:06.209993Z","end":"2026-10-14T17:58:06.411025Z","steps":["trace[100953182] 'process raft request' (duration: 200.725401ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.411059Z","caller":"traceutil/trace.go:171","msg":"trace[53982109] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"200.939026ms","start":"2026-10-14T17:58:06.210098Z","end":"2026-10-14T17:58:06.411037Z","steps":["trace[53982109] 'process raft request' (duration: 200.819543ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:06.411053Z","caller":"traceutil/trace.go:171","msg":"trace[738251540] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8774; }","duration":"113.241179ms","start":"2026-10-14T17:58:06.297789Z","end":"2026-10-14T17:58:06.41103Z","steps":["trace[738251540] 'read index received' (duration: 35.357927ms)","trace[738251540] 'applied index is now lower than readState.Index' (duration: 77.881712ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:06.411244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.446184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-92twn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T17:58:06.41129Z","caller":"traceutil/trace.go:171","msg":"trace[1543665914] range","detail":"{range_begin:/registry/pods/openstack/keepalived-92twn; range_end:; response_count:1; response_revision:8479; }","duration":"113.563597ms","start":"2026-10-14T17:58:06.297715Z","end":"2026-10-14T17:58:06.411279Z","steps":["trace[1543665914] 'agreement among raft nodes before linearized reading' (duration: 113.387053ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:08.669621Z","caller":"traceutil/trace.go:171","msg":"trace[1891328246] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"458.235305ms","start":"2026-10-14T17:58:08.211363Z","end":"2026-10-14T17:58:08.669598Z","steps":["trace[1891328246] 'process raft request' (duration: 458.091801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:08.669773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.211337Z","time spent":"458.344968ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:58:09.073672Z","caller":"traceutil/trace.go:171","msg":"trace[1416326406] linearizableReadLoop","detail":"{readStateIndex:8785; appliedIndex:8783; }","duration":"778.241337ms","start":"2026-10-14T17:58:08.295407Z","end":"2026-10-14T17:58:09.073648Z","steps":["trace[1416326406] 'read index received' (duration: 374.059826ms)","trace[1416326406] 'applied index is now lower than readState.Index' (duration: 404.180691ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:09.074045Z","caller":"traceutil/trace.go:171","msg":"trace[2067383160] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"861.446092ms","start":"2026-10-14T17:58:08.212581Z","end":"2026-10-14T17:58:09.074027Z","steps":["trace[2067383160] 'process raft request' (duration: 821.155962ms)","trace[2067383160] 'compare' (duration: 39.815318ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:09.074059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.281009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:09.074102Z","caller":"traceutil/trace.go:171","msg":"trace[815200164] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8484; }","duration":"634.403922ms","start":"2026-10-14T17:58:08.439686Z","end":"2026-10-14T17:58:09.07409Z","steps":["trace[815200164] 'agreement among raft nodes before linearized reading' (duration: 634.283369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.074104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.327116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:58:09.074134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.439669Z","time spent":"634.453723ms","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:58:09.074112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.52875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:09.074151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.212556Z","time spent":"861.533385ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:58:09.074127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.136851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:58:09.074163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.6829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-14T17:58:09.074183Z","caller":"traceutil/trace.go:171","msg":"trace[13982471] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8484; }","duration":"126.43793ms","start":"2026-10-14T17:58:08.947718Z","end":"2026-10-14T17:58:09.074156Z","steps":["trace[13982471] 'agreement among raft nodes before linearized reading' (duration: 126.310686ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.074186Z","caller":"traceutil/trace.go:171","msg":"trace[985797232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8484; }","duration":"169.649403ms","start":"2026-10-14T17:58:08.904519Z","end":"2026-10-14T17:58:09.074168Z","steps":["trace[985797232] 'agreement among raft nodes before linearized reading' (duration: 169.51675ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.074203Z","caller":"traceutil/trace.go:171","msg":"trace[1613097319] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8484; }","duration":"489.231333ms","start":"2026-10-14T17:58:08.584952Z","end":"2026-10-14T17:58:09.074184Z","steps":["trace[1613097319] 'agreement among raft nodes before linearized reading' (duration: 489.074379ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.074214Z","caller":"traceutil/trace.go:171","msg":"trace[185939496] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8484; }","duration":"113.775903ms","start":"2026-10-14T17:58:08.96042Z","end":"2026-10-14T17:58:09.074196Z","steps":["trace[185939496] 'agreement among raft nodes before linearized reading' (duration: 113.595698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.074251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.584893Z","time spent":"489.347717ms","remote":"127.0.0.1:58116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:58:09.074279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.321134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-92twn\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-14T17:58:09.074329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.550786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T17:58:09.074294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.743691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T17:58:09.07434Z","caller":"traceutil/trace.go:171","msg":"trace[654734042] range","detail":"{range_begin:/registry/pods/openstack/keepalived-92twn; range_end:; response_count:1; response_revision:8484; }","duration":"651.411277ms","start":"2026-10-14T17:58:08.422909Z","end":"2026-10-14T17:58:09.074321Z","steps":["trace[654734042] 'agreement among raft nodes before linearized reading' (duration: 651.240373ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.07436Z","caller":"traceutil/trace.go:171","msg":"trace[299081966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8484; }","duration":"658.618048ms","start":"2026-10-14T17:58:08.415732Z","end":"2026-10-14T17:58:09.07435Z","steps":["trace[299081966] 'agreement among raft nodes before linearized reading' (duration: 658.505896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.074367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.594661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-14T17:58:09.07438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.422895Z","time spent":"651.477129ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-92twn\" "} {"level":"warn","ts":"2026-10-14T17:58:09.074397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.415723Z","time spent":"658.665458ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T17:58:09.074398Z","caller":"traceutil/trace.go:171","msg":"trace[783826460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8484; }","duration":"658.879204ms","start":"2026-10-14T17:58:08.415487Z","end":"2026-10-14T17:58:09.074366Z","steps":["trace[783826460] 'agreement among raft nodes before linearized reading' (duration: 658.629269ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.074402Z","caller":"traceutil/trace.go:171","msg":"trace[1161869866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8484; }","duration":"712.690013ms","start":"2026-10-14T17:58:08.361702Z","end":"2026-10-14T17:58:09.074392Z","steps":["trace[1161869866] 'agreement among raft nodes before linearized reading' (duration: 712.59962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.07443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.415466Z","time spent":"658.956406ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T17:58:09.074431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.361683Z","time spent":"712.740784ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T17:58:09.074458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.063304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-14T17:58:09.074515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.983206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-14T17:58:09.07455Z","caller":"traceutil/trace.go:171","msg":"trace[1348979196] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8484; }","duration":"659.080009ms","start":"2026-10-14T17:58:08.415459Z","end":"2026-10-14T17:58:09.074539Z","steps":["trace[1348979196] 'agreement among raft nodes before linearized reading' (duration: 658.982887ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.074549Z","caller":"traceutil/trace.go:171","msg":"trace[767614688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8484; }","duration":"660.212208ms","start":"2026-10-14T17:58:08.414312Z","end":"2026-10-14T17:58:09.074524Z","steps":["trace[767614688] 'agreement among raft nodes before linearized reading' (duration: 659.905541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.074575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.41544Z","time spent":"659.12826ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T17:58:09.074593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.414288Z","time spent":"660.29648ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T17:58:09.07468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.689958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:58:09.07483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.391548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:58:09.074871Z","caller":"traceutil/trace.go:171","msg":"trace[2113784479] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:8484; }","duration":"746.47232ms","start":"2026-10-14T17:58:08.328385Z","end":"2026-10-14T17:58:09.074857Z","steps":["trace[2113784479] 'agreement among raft nodes before linearized reading' (duration: 745.569358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.074898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.328367Z","time spent":"746.522481ms","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":51,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:58:09.075075Z","caller":"traceutil/trace.go:171","msg":"trace[775529505] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8484; }","duration":"779.467987ms","start":"2026-10-14T17:58:08.29536Z","end":"2026-10-14T17:58:09.074828Z","steps":["trace[775529505] 'agreement among raft nodes before linearized reading' (duration: 778.672227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.075181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:08.29534Z","time spent":"779.820486ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":11,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:58:09.326584Z","caller":"traceutil/trace.go:171","msg":"trace[2024505470] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"247.337019ms","start":"2026-10-14T17:58:09.079223Z","end":"2026-10-14T17:58:09.32656Z","steps":["trace[2024505470] 'process raft request' (duration: 247.166155ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.326918Z","caller":"traceutil/trace.go:171","msg":"trace[837523502] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"247.38729ms","start":"2026-10-14T17:58:09.079512Z","end":"2026-10-14T17:58:09.3269Z","steps":["trace[837523502] 'process raft request' (duration: 247.00911ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.327022Z","caller":"traceutil/trace.go:171","msg":"trace[2126421095] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"247.321268ms","start":"2026-10-14T17:58:09.079678Z","end":"2026-10-14T17:58:09.327Z","steps":["trace[2126421095] 'process raft request' (duration: 247.056571ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.327116Z","caller":"traceutil/trace.go:171","msg":"trace[5585180] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"247.37531ms","start":"2026-10-14T17:58:09.079727Z","end":"2026-10-14T17:58:09.327103Z","steps":["trace[5585180] 'process raft request' (duration: 247.233496ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.327222Z","caller":"traceutil/trace.go:171","msg":"trace[1804282719] linearizableReadLoop","detail":"{readStateIndex:8791; appliedIndex:8785; }","duration":"241.907173ms","start":"2026-10-14T17:58:09.085306Z","end":"2026-10-14T17:58:09.327213Z","steps":["trace[1804282719] 'read index received' (duration: 37.422537ms)","trace[1804282719] 'applied index is now lower than readState.Index' (duration: 204.483836ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:09.327284Z","caller":"traceutil/trace.go:171","msg":"trace[1975151101] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"247.469562ms","start":"2026-10-14T17:58:09.079803Z","end":"2026-10-14T17:58:09.327272Z","steps":["trace[1975151101] 'process raft request' (duration: 247.254487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.327392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.871555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T17:58:09.327427Z","caller":"traceutil/trace.go:171","msg":"trace[114013945] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8490; }","duration":"125.977728ms","start":"2026-10-14T17:58:09.201441Z","end":"2026-10-14T17:58:09.327419Z","steps":["trace[114013945] 'agreement among raft nodes before linearized reading' (duration: 125.839974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.327537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.22594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207030"} {"level":"info","ts":"2026-10-14T17:58:09.327599Z","caller":"traceutil/trace.go:171","msg":"trace[910763059] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8490; }","duration":"242.325783ms","start":"2026-10-14T17:58:09.085264Z","end":"2026-10-14T17:58:09.327589Z","steps":["trace[910763059] 'agreement among raft nodes before linearized reading' (duration: 242.007125ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:09.631442Z","caller":"traceutil/trace.go:171","msg":"trace[2078216088] linearizableReadLoop","detail":"{readStateIndex:8792; appliedIndex:8791; }","duration":"172.111623ms","start":"2026-10-14T17:58:09.459307Z","end":"2026-10-14T17:58:09.631419Z","steps":["trace[2078216088] 'read index received' (duration: 171.925899ms)","trace[2078216088] 'applied index is now lower than readState.Index' (duration: 184.394µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:09.631493Z","caller":"traceutil/trace.go:171","msg":"trace[269681913] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"174.197605ms","start":"2026-10-14T17:58:09.457262Z","end":"2026-10-14T17:58:09.631459Z","steps":["trace[269681913] 'process raft request' (duration: 173.995621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:09.631551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.232476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:09.631608Z","caller":"traceutil/trace.go:171","msg":"trace[1708364991] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8491; }","duration":"172.327399ms","start":"2026-10-14T17:58:09.459271Z","end":"2026-10-14T17:58:09.631598Z","steps":["trace[1708364991] 'agreement among raft nodes before linearized reading' (duration: 172.232396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.324827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.415955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-14T17:58:38.324932Z","caller":"traceutil/trace.go:171","msg":"trace[1047196580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8689; }","duration":"777.551119ms","start":"2026-10-14T17:58:37.547362Z","end":"2026-10-14T17:58:38.324914Z","steps":["trace[1047196580] 'range keys from in-memory index tree' (duration: 777.235611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.324975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.547352Z","time spent":"777.609291ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T17:58:38.324971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.238103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:38.325032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.357714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T17:58:38.32509Z","caller":"traceutil/trace.go:171","msg":"trace[1757747784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8689; }","duration":"777.445586ms","start":"2026-10-14T17:58:37.547631Z","end":"2026-10-14T17:58:38.325076Z","steps":["trace[1757747784] 'range keys from in-memory index tree' (duration: 777.229051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.325119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.547559Z","time spent":"777.553139ms","remote":"127.0.0.1:58090","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-10-14T17:58:38.32519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.735207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-14T17:58:38.325249Z","caller":"traceutil/trace.go:171","msg":"trace[99987562] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8689; }","duration":"835.832619ms","start":"2026-10-14T17:58:37.489401Z","end":"2026-10-14T17:58:38.325234Z","steps":["trace[99987562] 'range keys from in-memory index tree' (duration: 835.585363ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.325289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.489382Z","time spent":"835.897801ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T17:58:38.32531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.780289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-92twn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T17:58:38.32534Z","caller":"traceutil/trace.go:171","msg":"trace[808293153] range","detail":"{range_begin:/registry/pods/openstack/keepalived-92twn; range_end:; response_count:1; response_revision:8689; }","duration":"766.81736ms","start":"2026-10-14T17:58:37.558513Z","end":"2026-10-14T17:58:38.325331Z","steps":["trace[808293153] 'range keys from in-memory index tree' (duration: 766.698677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.325373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.558509Z","time spent":"766.859061ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-92twn\" "} {"level":"warn","ts":"2026-10-14T17:58:38.325438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.436031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T17:58:38.325473Z","caller":"traceutil/trace.go:171","msg":"trace[367228467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8689; }","duration":"835.494491ms","start":"2026-10-14T17:58:37.48997Z","end":"2026-10-14T17:58:38.325464Z","steps":["trace[367228467] 'range keys from in-memory index tree' (duration: 835.350379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.325496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.489954Z","time spent":"835.535383ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T17:58:38.325546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.363447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T17:58:38.325596Z","caller":"traceutil/trace.go:171","msg":"trace[1680104236] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:8689; }","duration":"821.4728ms","start":"2026-10-14T17:58:37.504112Z","end":"2026-10-14T17:58:38.325585Z","steps":["trace[1680104236] 'range keys from in-memory index tree' (duration: 821.180933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.32563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.504091Z","time spent":"821.532201ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"info","ts":"2026-10-14T17:58:38.325046Z","caller":"traceutil/trace.go:171","msg":"trace[1603227998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8689; }","duration":"422.347546ms","start":"2026-10-14T17:58:37.902677Z","end":"2026-10-14T17:58:38.325025Z","steps":["trace[1603227998] 'range keys from in-memory index tree' (duration: 422.151621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.325666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.124446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:38.325686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.902662Z","time spent":"423.013873ms","remote":"127.0.0.1:57830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T17:58:38.325764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.347482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:38.32581Z","caller":"traceutil/trace.go:171","msg":"trace[2029482215] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8689; }","duration":"818.439814ms","start":"2026-10-14T17:58:37.507357Z","end":"2026-10-14T17:58:38.325797Z","steps":["trace[2029482215] 'count revisions from in-memory index tree' (duration: 818.295651ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.325696Z","caller":"traceutil/trace.go:171","msg":"trace[1052310470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8689; }","duration":"206.197638ms","start":"2026-10-14T17:58:38.119488Z","end":"2026-10-14T17:58:38.325686Z","steps":["trace[1052310470] 'count revisions from in-memory index tree' (duration: 206.003434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.325846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.507347Z","time spent":"818.490016ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:58:38.325189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.831369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T17:58:38.326178Z","caller":"traceutil/trace.go:171","msg":"trace[1160037217] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8689; }","duration":"215.011239ms","start":"2026-10-14T17:58:38.111072Z","end":"2026-10-14T17:58:38.326083Z","steps":["trace[1160037217] 'range keys from in-memory index tree' (duration: 213.645255ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.538786Z","caller":"traceutil/trace.go:171","msg":"trace[1010423092] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"305.675072ms","start":"2026-10-14T17:58:38.233057Z","end":"2026-10-14T17:58:38.538732Z","steps":["trace[1010423092] 'process raft request' (duration: 305.520668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.538944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:38.233031Z","time spent":"305.836266ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:58:38.583238Z","caller":"traceutil/trace.go:171","msg":"trace[560268802] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"250.146601ms","start":"2026-10-14T17:58:38.333066Z","end":"2026-10-14T17:58:38.583213Z","steps":["trace[560268802] 'process raft request' (duration: 250.069969ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.583252Z","caller":"traceutil/trace.go:171","msg":"trace[13731935] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"250.255563ms","start":"2026-10-14T17:58:38.332975Z","end":"2026-10-14T17:58:38.583231Z","steps":["trace[13731935] 'process raft request' (duration: 250.063288ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.583479Z","caller":"traceutil/trace.go:171","msg":"trace[1017405150] linearizableReadLoop","detail":"{readStateIndex:8998; appliedIndex:8996; }","duration":"256.069689ms","start":"2026-10-14T17:58:38.327398Z","end":"2026-10-14T17:58:38.583467Z","steps":["trace[1017405150] 'read index received' (duration: 211.137193ms)","trace[1017405150] 'applied index is now lower than readState.Index' (duration: 44.931286ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:38.583487Z","caller":"traceutil/trace.go:171","msg":"trace[1376635742] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"250.339615ms","start":"2026-10-14T17:58:38.33313Z","end":"2026-10-14T17:58:38.583469Z","steps":["trace[1376635742] 'process raft request' (duration: 250.224482ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.583473Z","caller":"traceutil/trace.go:171","msg":"trace[1638332865] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"250.342386ms","start":"2026-10-14T17:58:38.333098Z","end":"2026-10-14T17:58:38.583441Z","steps":["trace[1638332865] 'process raft request' (duration: 250.072489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.583638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.228763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:38.583607Z","caller":"traceutil/trace.go:171","msg":"trace[1792696833] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"250.344445ms","start":"2026-10-14T17:58:38.333198Z","end":"2026-10-14T17:58:38.583543Z","steps":["trace[1792696833] 'process raft request' (duration: 250.229052ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.583673Z","caller":"traceutil/trace.go:171","msg":"trace[1242975961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8695; }","duration":"256.270254ms","start":"2026-10-14T17:58:38.327391Z","end":"2026-10-14T17:58:38.583661Z","steps":["trace[1242975961] 'agreement among raft nodes before linearized reading' (duration: 256.157961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.584073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.104539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211761"} {"level":"info","ts":"2026-10-14T17:58:38.58412Z","caller":"traceutil/trace.go:171","msg":"trace[1049462527] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8695; }","duration":"252.167271ms","start":"2026-10-14T17:58:38.331939Z","end":"2026-10-14T17:58:38.584106Z","steps":["trace[1049462527] 'agreement among raft nodes before linearized reading' (duration: 251.74179ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.901069Z","caller":"traceutil/trace.go:171","msg":"trace[642517897] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"114.981242ms","start":"2026-10-14T17:58:38.786062Z","end":"2026-10-14T17:58:38.901044Z","steps":["trace[642517897] 'process raft request' (duration: 114.785917ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:39.457111Z","caller":"traceutil/trace.go:171","msg":"trace[2079954160] linearizableReadLoop","detail":"{readStateIndex:9007; appliedIndex:9006; }","duration":"114.830108ms","start":"2026-10-14T17:58:39.342254Z","end":"2026-10-14T17:58:39.457084Z","steps":["trace[2079954160] 'read index received' (duration: 98.200401ms)","trace[2079954160] 'applied index is now lower than readState.Index' (duration: 16.628537ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:39.457134Z","caller":"traceutil/trace.go:171","msg":"trace[2037175288] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"161.138679ms","start":"2026-10-14T17:58:39.295961Z","end":"2026-10-14T17:58:39.4571Z","steps":["trace[2037175288] 'process raft request' (duration: 144.512303ms)","trace[2037175288] 'compare' (duration: 16.491423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:39.457367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.095034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-14T17:58:39.457433Z","caller":"traceutil/trace.go:171","msg":"trace[624067778] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8699; }","duration":"115.204717ms","start":"2026-10-14T17:58:39.342206Z","end":"2026-10-14T17:58:39.457411Z","steps":["trace[624067778] 'agreement among raft nodes before linearized reading' (duration: 114.957061ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.45013Z","caller":"traceutil/trace.go:171","msg":"trace[2068686499] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"507.370898ms","start":"2026-10-14T17:58:51.942725Z","end":"2026-10-14T17:58:52.450096Z","steps":["trace[2068686499] 'process raft request' (duration: 443.395984ms)","trace[2068686499] 'compare' (duration: 63.42021ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:52.450303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:51.942708Z","time spent":"507.505841ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4621,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:58:52.450279Z","caller":"traceutil/trace.go:171","msg":"trace[1351742301] linearizableReadLoop","detail":"{readStateIndex:9115; appliedIndex:9113; }","duration":"502.907976ms","start":"2026-10-14T17:58:51.947341Z","end":"2026-10-14T17:58:52.450249Z","steps":["trace[1351742301] 'read index received' (duration: 2.578294ms)","trace[1351742301] 'applied index is now lower than readState.Index' (duration: 500.328402ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:52.450511Z","caller":"traceutil/trace.go:171","msg":"trace[1138687314] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8801; }","duration":"506.916356ms","start":"2026-10-14T17:58:51.943581Z","end":"2026-10-14T17:58:52.450497Z","steps":["trace[1138687314] 'process raft request' (duration: 506.432344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.450563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:51.94357Z","time spent":"506.964177ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4670,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:58:52.450655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.761486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:52.450653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.289735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-b84xq\" ","response":"range_response_count:1 size:4636"} {"level":"warn","ts":"2026-10-14T17:58:52.450691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.873438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-10-14T17:58:52.450705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.272435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-10-14T17:58:52.450736Z","caller":"traceutil/trace.go:171","msg":"trace[155080520] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8801; }","duration":"497.940111ms","start":"2026-10-14T17:58:51.952784Z","end":"2026-10-14T17:58:52.450724Z","steps":["trace[155080520] 'agreement among raft nodes before linearized reading' (duration: 497.804187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.450714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.634455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:52.450736Z","caller":"traceutil/trace.go:171","msg":"trace[114201277] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-b84xq; range_end:; response_count:1; response_revision:8801; }","duration":"503.385017ms","start":"2026-10-14T17:58:51.947335Z","end":"2026-10-14T17:58:52.45072Z","steps":["trace[114201277] 'agreement among raft nodes before linearized reading' (duration: 503.209413ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.450785Z","caller":"traceutil/trace.go:171","msg":"trace[504379759] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8801; }","duration":"503.369717ms","start":"2026-10-14T17:58:51.947403Z","end":"2026-10-14T17:58:52.450773Z","steps":["trace[504379759] 'agreement among raft nodes before linearized reading' (duration: 503.178612ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.450728Z","caller":"traceutil/trace.go:171","msg":"trace[802165469] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:8801; }","duration":"207.88231ms","start":"2026-10-14T17:58:52.242821Z","end":"2026-10-14T17:58:52.450703Z","steps":["trace[802165469] 'agreement among raft nodes before linearized reading' (duration: 207.765427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.450797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:51.952773Z","time spent":"498.016572ms","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":554,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"info","ts":"2026-10-14T17:58:52.450813Z","caller":"traceutil/trace.go:171","msg":"trace[247010621] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8801; }","duration":"140.829369ms","start":"2026-10-14T17:58:52.309966Z","end":"2026-10-14T17:58:52.450795Z","steps":["trace[247010621] 'agreement among raft nodes before linearized reading' (duration: 140.631694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.450823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:51.947396Z","time spent":"503.421408ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3089,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-14T17:58:52.450844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:51.947302Z","time spent":"503.527191ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4659,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-b84xq\" "} {"level":"warn","ts":"2026-10-14T17:58:52.450868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.397932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-10-14T17:58:52.450916Z","caller":"traceutil/trace.go:171","msg":"trace[2147278308] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8801; }","duration":"498.487624ms","start":"2026-10-14T17:58:51.952416Z","end":"2026-10-14T17:58:52.450904Z","steps":["trace[2147278308] 'agreement among raft nodes before linearized reading' (duration: 498.225818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.450952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:51.952399Z","time spent":"498.543656ms","remote":"127.0.0.1:57940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":736,"request content":"key:\"/registry/secrets/openstack/rook-ceph-mon\" "} {"level":"info","ts":"2026-10-14T17:58:52.79385Z","caller":"traceutil/trace.go:171","msg":"trace[1939617702] linearizableReadLoop","detail":"{readStateIndex:9116; appliedIndex:9115; }","duration":"337.4893ms","start":"2026-10-14T17:58:52.45633Z","end":"2026-10-14T17:58:52.793819Z","steps":["trace[1939617702] 'read index received' (duration: 316.899253ms)","trace[1939617702] 'applied index is now lower than readState.Index' (duration: 20.588747ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:52.79397Z","caller":"traceutil/trace.go:171","msg":"trace[1095390529] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"338.260668ms","start":"2026-10-14T17:58:52.455669Z","end":"2026-10-14T17:58:52.79393Z","steps":["trace[1095390529] 'process raft request' (duration: 317.547799ms)","trace[1095390529] 'compare' (duration: 20.450823ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:52.794113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.766466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-14T17:58:52.794166Z","caller":"traceutil/trace.go:171","msg":"trace[1967118399] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8802; }","duration":"337.863929ms","start":"2026-10-14T17:58:52.456286Z","end":"2026-10-14T17:58:52.79415Z","steps":["trace[1967118399] 'agreement among raft nodes before linearized reading' (duration: 337.671954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.794209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:52.45627Z","time spent":"337.92667ms","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":554,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-14T17:58:52.794372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:52.455647Z","time spent":"338.407062ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:58:52.807442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.07897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-mon-endpoints\" ","response":"range_response_count:1 size:389"} {"level":"warn","ts":"2026-10-14T17:58:52.807463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.715672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T17:58:52.807522Z","caller":"traceutil/trace.go:171","msg":"trace[552333913] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-mon-endpoints; range_end:; response_count:1; response_revision:8803; }","duration":"351.216203ms","start":"2026-10-14T17:58:52.456281Z","end":"2026-10-14T17:58:52.807497Z","steps":["trace[552333913] 'agreement among raft nodes before linearized reading' (duration: 350.951377ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.807518Z","caller":"traceutil/trace.go:171","msg":"trace[333597414] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"216.963368ms","start":"2026-10-14T17:58:52.590527Z","end":"2026-10-14T17:58:52.80749Z","steps":["trace[333597414] 'process raft request' (duration: 216.199939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.807588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:52.456266Z","time spent":"351.290106ms","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":412,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-mon-endpoints\" "} {"level":"info","ts":"2026-10-14T17:58:52.807553Z","caller":"traceutil/trace.go:171","msg":"trace[85012049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8803; }","duration":"131.856565ms","start":"2026-10-14T17:58:52.675674Z","end":"2026-10-14T17:58:52.80753Z","steps":["trace[85012049] 'agreement among raft nodes before linearized reading' (duration: 131.625779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.80746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.275715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:52.807463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.849164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T17:58:52.807736Z","caller":"traceutil/trace.go:171","msg":"trace[1201486621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8803; }","duration":"110.158901ms","start":"2026-10-14T17:58:52.697563Z","end":"2026-10-14T17:58:52.807721Z","steps":["trace[1201486621] 'agreement among raft nodes before linearized reading' (duration: 109.624557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.807726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.573887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-14T17:58:52.807786Z","caller":"traceutil/trace.go:171","msg":"trace[1463893881] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8803; }","duration":"110.533871ms","start":"2026-10-14T17:58:52.697158Z","end":"2026-10-14T17:58:52.807692Z","steps":["trace[1463893881] 'agreement among raft nodes before linearized reading' (duration: 110.193083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.807808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.498059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-b84xq\" ","response":"range_response_count:1 size:4641"} {"level":"info","ts":"2026-10-14T17:58:52.807909Z","caller":"traceutil/trace.go:171","msg":"trace[1287155470] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-b84xq; range_end:; response_count:1; response_revision:8803; }","duration":"349.862039ms","start":"2026-10-14T17:58:52.458026Z","end":"2026-10-14T17:58:52.807888Z","steps":["trace[1287155470] 'agreement among raft nodes before linearized reading' (duration: 349.397877ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.807826Z","caller":"traceutil/trace.go:171","msg":"trace[1179373813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8803; }","duration":"101.697479ms","start":"2026-10-14T17:58:52.706112Z","end":"2026-10-14T17:58:52.807809Z","steps":["trace[1179373813] 'agreement among raft nodes before linearized reading' (duration: 101.523995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:52.807959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:52.458007Z","time spent":"349.942651ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4664,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-b84xq\" "} {"level":"info","ts":"2026-10-14T17:58:53.915405Z","caller":"traceutil/trace.go:171","msg":"trace[2040507181] linearizableReadLoop","detail":"{readStateIndex:9131; appliedIndex:9130; }","duration":"347.024338ms","start":"2026-10-14T17:58:53.568356Z","end":"2026-10-14T17:58:53.915381Z","steps":["trace[2040507181] 'read index received' (duration: 346.862134ms)","trace[2040507181] 'applied index is now lower than readState.Index' (duration: 160.924µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:53.915452Z","caller":"traceutil/trace.go:171","msg":"trace[1117611878] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"457.435005ms","start":"2026-10-14T17:58:53.457986Z","end":"2026-10-14T17:58:53.915421Z","steps":["trace[1117611878] 'process raft request' (duration: 457.227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:53.915636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:53.457959Z","time spent":"457.556648ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:58:53.915665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.160556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:53.915686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.331296ms","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-10-14T17:58:53.915736Z","caller":"traceutil/trace.go:171","msg":"trace[302602207] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8817; }","duration":"271.252258ms","start":"2026-10-14T17:58:53.644467Z","end":"2026-10-14T17:58:53.915719Z","steps":["trace[302602207] 'agreement among raft nodes before linearized reading' (duration: 271.113075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:53.915637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.868152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:53.915761Z","caller":"traceutil/trace.go:171","msg":"trace[113934904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8817; }","duration":"347.425258ms","start":"2026-10-14T17:58:53.568308Z","end":"2026-10-14T17:58:53.915733Z","steps":["trace[113934904] 'agreement among raft nodes before linearized reading' (duration: 347.228173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:53.915842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:53.568288Z","time spent":"347.527311ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T17:58:53.915835Z","caller":"traceutil/trace.go:171","msg":"trace[651063035] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8817; }","duration":"305.080757ms","start":"2026-10-14T17:58:53.610711Z","end":"2026-10-14T17:58:53.915792Z","steps":["trace[651063035] 'agreement among raft nodes before linearized reading' (duration: 304.827861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:53.915928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:53.610696Z","time spent":"305.19537ms","remote":"127.0.0.1:58664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:58:54.595367Z","caller":"traceutil/trace.go:171","msg":"trace[907670414] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"113.184497ms","start":"2026-10-14T17:58:54.482156Z","end":"2026-10-14T17:58:54.595341Z","steps":["trace[907670414] 'process raft request' (duration: 112.950011ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:54.595669Z","caller":"traceutil/trace.go:171","msg":"trace[544217446] linearizableReadLoop","detail":"{readStateIndex:9134; appliedIndex:9133; }","duration":"110.656353ms","start":"2026-10-14T17:58:54.484995Z","end":"2026-10-14T17:58:54.595652Z","steps":["trace[544217446] 'read index received' (duration: 110.16947ms)","trace[544217446] 'applied index is now lower than readState.Index' (duration: 485.573µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:54.595837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.777097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:58:54.595928Z","caller":"traceutil/trace.go:171","msg":"trace[502633072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8819; }","duration":"110.94314ms","start":"2026-10-14T17:58:54.484969Z","end":"2026-10-14T17:58:54.595912Z","steps":["trace[502633072] 'agreement among raft nodes before linearized reading' (duration: 110.741415ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:11.537997Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002490.wal"} {"level":"info","ts":"2026-10-14T18:00:21.342187Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:00:21.349351Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T18:00:21.349461Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T18:01:54.077048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7864} {"level":"info","ts":"2026-10-14T18:01:54.145824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7864,"took":"66.742025ms","hash":3171067164,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":28459008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:01:54.145893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3171067164,"revision":7864,"compact-revision":5659} {"level":"info","ts":"2026-10-14T18:03:48.602579Z","caller":"traceutil/trace.go:171","msg":"trace[1979632122] transaction","detail":"{read_only:false; response_revision:11420; number_of_response:1; }","duration":"148.826127ms","start":"2026-10-14T18:03:48.453718Z","end":"2026-10-14T18:03:48.602544Z","steps":["trace[1979632122] 'process raft request' (duration: 75.464495ms)","trace[1979632122] 'compare' (duration: 73.139846ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:03:48.602825Z","caller":"traceutil/trace.go:171","msg":"trace[921494140] linearizableReadLoop","detail":"{readStateIndex:11814; appliedIndex:11812; }","duration":"145.79798ms","start":"2026-10-14T18:03:48.457015Z","end":"2026-10-14T18:03:48.602813Z","steps":["trace[921494140] 'read index received' (duration: 72.197542ms)","trace[921494140] 'applied index is now lower than readState.Index' (duration: 73.599178ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:03:48.602918Z","caller":"traceutil/trace.go:171","msg":"trace[141402572] transaction","detail":"{read_only:false; response_revision:11421; number_of_response:1; }","duration":"148.17039ms","start":"2026-10-14T18:03:48.454628Z","end":"2026-10-14T18:03:48.602799Z","steps":["trace[141402572] 'process raft request' (duration: 147.865812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:03:48.603074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.505842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T18:03:48.603116Z","caller":"traceutil/trace.go:171","msg":"trace[1017026261] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11421; }","duration":"135.593135ms","start":"2026-10-14T18:03:48.467507Z","end":"2026-10-14T18:03:48.6031Z","steps":["trace[1017026261] 'agreement among raft nodes before linearized reading' (duration: 135.41555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:03:48.60314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.092568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-14T18:03:48.603253Z","caller":"traceutil/trace.go:171","msg":"trace[145360976] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11421; }","duration":"146.240902ms","start":"2026-10-14T18:03:48.456982Z","end":"2026-10-14T18:03:48.603223Z","steps":["trace[145360976] 'agreement among raft nodes before linearized reading' (duration: 145.924474ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:03:48.799758Z","caller":"traceutil/trace.go:171","msg":"trace[720402848] transaction","detail":"{read_only:false; response_revision:11422; number_of_response:1; }","duration":"190.612135ms","start":"2026-10-14T18:03:48.609114Z","end":"2026-10-14T18:03:48.799726Z","steps":["trace[720402848] 'process raft request' (duration: 118.999937ms)","trace[720402848] 'compare' (duration: 71.313611ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:03:48.799979Z","caller":"traceutil/trace.go:171","msg":"trace[1224311797] transaction","detail":"{read_only:false; response_revision:11423; number_of_response:1; }","duration":"190.202466ms","start":"2026-10-14T18:03:48.609733Z","end":"2026-10-14T18:03:48.799935Z","steps":["trace[1224311797] 'process raft request' (duration: 189.917709ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:06:54.08452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10358} {"level":"info","ts":"2026-10-14T18:06:54.173713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10358,"took":"86.805648ms","hash":1283957979,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":27652096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:06:54.173797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283957979,"revision":10358,"compact-revision":7864} {"level":"info","ts":"2026-10-14T18:09:40.36574Z","caller":"traceutil/trace.go:171","msg":"trace[197215846] linearizableReadLoop","detail":"{readStateIndex:14325; appliedIndex:14324; }","duration":"187.772962ms","start":"2026-10-14T18:09:40.177937Z","end":"2026-10-14T18:09:40.365709Z","steps":["trace[197215846] 'read index received' (duration: 187.206718ms)","trace[197215846] 'applied index is now lower than readState.Index' (duration: 564.714µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:09:40.365893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.943326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:09:40.365932Z","caller":"traceutil/trace.go:171","msg":"trace[1398728507] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13849; }","duration":"188.029248ms","start":"2026-10-14T18:09:40.17789Z","end":"2026-10-14T18:09:40.365919Z","steps":["trace[1398728507] 'agreement among raft nodes before linearized reading' (duration: 187.925956ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:09:40.366387Z","caller":"traceutil/trace.go:171","msg":"trace[1729928193] transaction","detail":"{read_only:false; response_revision:13849; number_of_response:1; }","duration":"206.264845ms","start":"2026-10-14T18:09:40.160104Z","end":"2026-10-14T18:09:40.366369Z","steps":["trace[1729928193] 'process raft request' (duration: 205.229358ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:00.566042Z","caller":"traceutil/trace.go:171","msg":"trace[109360504] linearizableReadLoop","detail":"{readStateIndex:14439; appliedIndex:14438; }","duration":"101.082184ms","start":"2026-10-14T18:10:00.464934Z","end":"2026-10-14T18:10:00.566016Z","steps":["trace[109360504] 'read index received' (duration: 100.862838ms)","trace[109360504] 'applied index is now lower than readState.Index' (duration: 218.306µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:10:00.56617Z","caller":"traceutil/trace.go:171","msg":"trace[1835414816] transaction","detail":"{read_only:false; response_revision:13959; number_of_response:1; }","duration":"163.251146ms","start":"2026-10-14T18:10:00.402908Z","end":"2026-10-14T18:10:00.566159Z","steps":["trace[1835414816] 'process raft request' (duration: 162.947638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:00.566614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.666469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-14T18:10:00.566676Z","caller":"traceutil/trace.go:171","msg":"trace[354942653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13959; }","duration":"101.769302ms","start":"2026-10-14T18:10:00.464889Z","end":"2026-10-14T18:10:00.566658Z","steps":["trace[354942653] 'agreement among raft nodes before linearized reading' (duration: 101.620468ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:00.738837Z","caller":"traceutil/trace.go:171","msg":"trace[1237946867] linearizableReadLoop","detail":"{readStateIndex:14440; appliedIndex:14439; }","duration":"139.916822ms","start":"2026-10-14T18:10:00.598899Z","end":"2026-10-14T18:10:00.738815Z","steps":["trace[1237946867] 'read index received' (duration: 139.120671ms)","trace[1237946867] 'applied index is now lower than readState.Index' (duration: 795.441µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:10:00.739036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.122278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T18:10:00.738964Z","caller":"traceutil/trace.go:171","msg":"trace[787436872] transaction","detail":"{read_only:false; response_revision:13960; number_of_response:1; }","duration":"168.153927ms","start":"2026-10-14T18:10:00.570768Z","end":"2026-10-14T18:10:00.738922Z","steps":["trace[787436872] 'process raft request' (duration: 167.299694ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:00.739064Z","caller":"traceutil/trace.go:171","msg":"trace[736947892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13960; }","duration":"140.19793ms","start":"2026-10-14T18:10:00.598856Z","end":"2026-10-14T18:10:00.739054Z","steps":["trace[736947892] 'agreement among raft nodes before linearized reading' (duration: 140.054716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:00.739079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.22963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T18:10:00.73913Z","caller":"traceutil/trace.go:171","msg":"trace[1035955674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13960; }","duration":"134.313012ms","start":"2026-10-14T18:10:00.604803Z","end":"2026-10-14T18:10:00.739116Z","steps":["trace[1035955674] 'agreement among raft nodes before linearized reading' (duration: 134.161848ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:16.381538Z","caller":"traceutil/trace.go:171","msg":"trace[1009530362] linearizableReadLoop","detail":"{readStateIndex:14540; appliedIndex:14539; }","duration":"352.683464ms","start":"2026-10-14T18:10:16.028831Z","end":"2026-10-14T18:10:16.381515Z","steps":["trace[1009530362] 'read index received' (duration: 352.467418ms)","trace[1009530362] 'applied index is now lower than readState.Index' (duration: 215.066µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:10:16.381603Z","caller":"traceutil/trace.go:171","msg":"trace[841361630] transaction","detail":"{read_only:false; response_revision:14056; number_of_response:1; }","duration":"442.229768ms","start":"2026-10-14T18:10:15.939347Z","end":"2026-10-14T18:10:16.381577Z","steps":["trace[841361630] 'process raft request' (duration: 442.024244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:16.381747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.259533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-92twn\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-14T18:10:16.381748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:15.93929Z","time spent":"442.371482ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:10:16.381765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.92631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T18:10:16.381788Z","caller":"traceutil/trace.go:171","msg":"trace[535659474] range","detail":"{range_begin:/registry/pods/openstack/keepalived-92twn; range_end:; response_count:1; response_revision:14056; }","duration":"244.347905ms","start":"2026-10-14T18:10:16.137431Z","end":"2026-10-14T18:10:16.381779Z","steps":["trace[535659474] 'agreement among raft nodes before linearized reading' (duration: 244.205871ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:16.381826Z","caller":"traceutil/trace.go:171","msg":"trace[1486199025] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14056; }","duration":"353.023772ms","start":"2026-10-14T18:10:16.028783Z","end":"2026-10-14T18:10:16.381806Z","steps":["trace[1486199025] 'agreement among raft nodes before linearized reading' (duration: 352.853167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:16.38187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:16.028765Z","time spent":"353.092213ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T18:10:18.102958Z","caller":"traceutil/trace.go:171","msg":"trace[766331347] transaction","detail":"{read_only:false; response_revision:14072; number_of_response:1; }","duration":"105.264026ms","start":"2026-10-14T18:10:17.997668Z","end":"2026-10-14T18:10:18.102932Z","steps":["trace[766331347] 'process raft request' (duration: 105.05219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:26.919716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.043199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:10:26.919755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.815259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T18:10:26.919803Z","caller":"traceutil/trace.go:171","msg":"trace[90316496] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:14218; }","duration":"237.166292ms","start":"2026-10-14T18:10:26.682612Z","end":"2026-10-14T18:10:26.919779Z","steps":["trace[90316496] 'count revisions from in-memory index tree' (duration: 236.957386ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:26.919817Z","caller":"traceutil/trace.go:171","msg":"trace[1292306489] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14218; }","duration":"320.930173ms","start":"2026-10-14T18:10:26.598872Z","end":"2026-10-14T18:10:26.919802Z","steps":["trace[1292306489] 'range keys from in-memory index tree' (duration: 320.647954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:26.919851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.598842Z","time spent":"321.003014ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T18:10:26.919654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.0975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:10:26.920014Z","caller":"traceutil/trace.go:171","msg":"trace[72242004] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:14218; }","duration":"237.512891ms","start":"2026-10-14T18:10:26.682479Z","end":"2026-10-14T18:10:26.919991Z","steps":["trace[72242004] 'count revisions from in-memory index tree' (duration: 236.987537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:27.161817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.532764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T18:10:27.161913Z","caller":"traceutil/trace.go:171","msg":"trace[1073005560] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14219; }","duration":"237.684759ms","start":"2026-10-14T18:10:26.924209Z","end":"2026-10-14T18:10:27.161894Z","steps":["trace[1073005560] 'agreement among raft nodes before linearized reading' (duration: 237.410961ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:27.161936Z","caller":"traceutil/trace.go:171","msg":"trace[1590514932] linearizableReadLoop","detail":"{readStateIndex:14706; appliedIndex:14705; }","duration":"237.139303ms","start":"2026-10-14T18:10:26.924255Z","end":"2026-10-14T18:10:27.161394Z","steps":["trace[1590514932] 'read index received' (duration: 236.721782ms)","trace[1590514932] 'applied index is now lower than readState.Index' (duration: 416.291µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:10:27.162257Z","caller":"traceutil/trace.go:171","msg":"trace[2056000722] transaction","detail":"{read_only:false; response_revision:14219; number_of_response:1; }","duration":"239.713734ms","start":"2026-10-14T18:10:26.922513Z","end":"2026-10-14T18:10:27.162227Z","steps":["trace[2056000722] 'process raft request' (duration: 238.593183ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:27.249653Z","caller":"traceutil/trace.go:171","msg":"trace[1894573227] transaction","detail":"{read_only:false; response_revision:14220; number_of_response:1; }","duration":"324.827479ms","start":"2026-10-14T18:10:26.924798Z","end":"2026-10-14T18:10:27.249626Z","steps":["trace[1894573227] 'process raft request' (duration: 324.553593ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:27.249655Z","caller":"traceutil/trace.go:171","msg":"trace[1901226958] transaction","detail":"{read_only:false; response_revision:14222; number_of_response:1; }","duration":"324.605543ms","start":"2026-10-14T18:10:26.925019Z","end":"2026-10-14T18:10:27.249625Z","steps":["trace[1901226958] 'process raft request' (duration: 324.536322ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:27.249674Z","caller":"traceutil/trace.go:171","msg":"trace[480233001] transaction","detail":"{read_only:false; response_revision:14221; number_of_response:1; }","duration":"324.692925ms","start":"2026-10-14T18:10:26.92495Z","end":"2026-10-14T18:10:27.249643Z","steps":["trace[480233001] 'process raft request' (duration: 324.565881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:27.249802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.924781Z","time spent":"324.941072ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:10:27.249821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.92501Z","time spent":"324.726646ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:10:27.249851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.92494Z","time spent":"324.809099ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:10:27.25005Z","caller":"traceutil/trace.go:171","msg":"trace[9356431] transaction","detail":"{read_only:false; response_revision:14223; number_of_response:1; }","duration":"324.913802ms","start":"2026-10-14T18:10:26.925125Z","end":"2026-10-14T18:10:27.250039Z","steps":["trace[9356431] 'process raft request' (duration: 324.463969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:27.250117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.925102Z","time spent":"324.979903ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:10:27.250148Z","caller":"traceutil/trace.go:171","msg":"trace[2015208946] transaction","detail":"{read_only:false; response_revision:14224; number_of_response:1; }","duration":"324.764747ms","start":"2026-10-14T18:10:26.92536Z","end":"2026-10-14T18:10:27.250125Z","steps":["trace[2015208946] 'process raft request' (duration: 324.579292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:27.250297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.210125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ce1299b-4533-4f25-aa6c-50dbe6bdfd40\" ","response":"range_response_count:1 size:3607"} {"level":"warn","ts":"2026-10-14T18:10:27.250299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.925345Z","time spent":"324.875ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:10:27.250356Z","caller":"traceutil/trace.go:171","msg":"trace[1871608624] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ce1299b-4533-4f25-aa6c-50dbe6bdfd40; range_end:; response_count:1; response_revision:14225; }","duration":"123.312218ms","start":"2026-10-14T18:10:27.127033Z","end":"2026-10-14T18:10:27.250345Z","steps":["trace[1871608624] 'agreement among raft nodes before linearized reading' (duration: 123.146033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:27.250353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.423182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:10:27.250429Z","caller":"traceutil/trace.go:171","msg":"trace[2062291420] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:14225; }","duration":"264.594807ms","start":"2026-10-14T18:10:26.985818Z","end":"2026-10-14T18:10:27.250413Z","steps":["trace[2062291420] 'agreement among raft nodes before linearized reading' (duration: 264.404932ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:27.250347Z","caller":"traceutil/trace.go:171","msg":"trace[1072885755] transaction","detail":"{read_only:false; response_revision:14225; number_of_response:1; }","duration":"324.894821ms","start":"2026-10-14T18:10:26.925389Z","end":"2026-10-14T18:10:27.250284Z","steps":["trace[1072885755] 'process raft request' (duration: 324.655305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:27.250541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:26.925376Z","time spent":"325.113797ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:11:54.091419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12675} {"level":"info","ts":"2026-10-14T18:11:54.178885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12675,"took":"84.799167ms","hash":1856061073,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":30330880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:11:54.178982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1856061073,"revision":12675,"compact-revision":10358} {"level":"warn","ts":"2026-10-14T18:12:02.24464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.922905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-14T18:12:02.244805Z","caller":"traceutil/trace.go:171","msg":"trace[218146533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15285; }","duration":"142.122421ms","start":"2026-10-14T18:12:02.102662Z","end":"2026-10-14T18:12:02.244784Z","steps":["trace[218146533] 'range keys from in-memory index tree' (duration: 141.73494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.245146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.812766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:12:02.245226Z","caller":"traceutil/trace.go:171","msg":"trace[1432126261] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15285; }","duration":"146.948ms","start":"2026-10-14T18:12:02.098265Z","end":"2026-10-14T18:12:02.245213Z","steps":["trace[1432126261] 'range keys from in-memory index tree' (duration: 146.631872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.245313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.652247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-14T18:12:02.245396Z","caller":"traceutil/trace.go:171","msg":"trace[440846029] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15285; }","duration":"157.76159ms","start":"2026-10-14T18:12:02.087615Z","end":"2026-10-14T18:12:02.245377Z","steps":["trace[440846029] 'range keys from in-memory index tree' (duration: 157.436121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.24512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.477188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:12:02.245693Z","caller":"traceutil/trace.go:171","msg":"trace[723787974] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15285; }","duration":"153.100445ms","start":"2026-10-14T18:12:02.092578Z","end":"2026-10-14T18:12:02.245679Z","steps":["trace[723787974] 'range keys from in-memory index tree' (duration: 152.355004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.245183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.527133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:12:02.246033Z","caller":"traceutil/trace.go:171","msg":"trace[280541381] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15285; }","duration":"158.404577ms","start":"2026-10-14T18:12:02.087614Z","end":"2026-10-14T18:12:02.246019Z","steps":["trace[280541381] 'range keys from in-memory index tree' (duration: 157.40635ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:02.601918Z","caller":"traceutil/trace.go:171","msg":"trace[1884591086] transaction","detail":"{read_only:false; response_revision:15286; number_of_response:1; }","duration":"350.813804ms","start":"2026-10-14T18:12:02.251077Z","end":"2026-10-14T18:12:02.601891Z","steps":["trace[1884591086] 'process raft request' (duration: 350.677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.602087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:12:02.251058Z","time spent":"350.934086ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:12:02.602829Z","caller":"traceutil/trace.go:171","msg":"trace[1664801141] linearizableReadLoop","detail":"{readStateIndex:15807; appliedIndex:15807; }","duration":"349.645452ms","start":"2026-10-14T18:12:02.253163Z","end":"2026-10-14T18:12:02.602809Z","steps":["trace[1664801141] 'read index received' (duration: 349.637141ms)","trace[1664801141] 'applied index is now lower than readState.Index' (duration: 6.691µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:12:02.603062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.755061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-14T18:12:02.603118Z","caller":"traceutil/trace.go:171","msg":"trace[2085934596] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15286; }","duration":"304.868235ms","start":"2026-10-14T18:12:02.298235Z","end":"2026-10-14T18:12:02.603103Z","steps":["trace[2085934596] 'agreement among raft nodes before linearized reading' (duration: 304.69578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.603158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:12:02.298207Z","time spent":"304.939566ms","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-14T18:12:02.60333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.774594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:12:02.603353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.771154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:12:02.603451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.488419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-14T18:12:02.603477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.936599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:12:02.603499Z","caller":"traceutil/trace.go:171","msg":"trace[1526582030] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15286; }","duration":"254.967929ms","start":"2026-10-14T18:12:02.348495Z","end":"2026-10-14T18:12:02.603463Z","steps":["trace[1526582030] 'agreement among raft nodes before linearized reading' (duration: 254.750233ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:02.603523Z","caller":"traceutil/trace.go:171","msg":"trace[145565788] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15286; }","duration":"255.023502ms","start":"2026-10-14T18:12:02.348491Z","end":"2026-10-14T18:12:02.603514Z","steps":["trace[145565788] 'agreement among raft nodes before linearized reading' (duration: 254.96409ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:02.603538Z","caller":"traceutil/trace.go:171","msg":"trace[169967807] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15286; }","duration":"253.634954ms","start":"2026-10-14T18:12:02.349877Z","end":"2026-10-14T18:12:02.603512Z","steps":["trace[169967807] 'agreement among raft nodes before linearized reading' (duration: 253.426088ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:02.603428Z","caller":"traceutil/trace.go:171","msg":"trace[1724601734] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15286; }","duration":"254.897408ms","start":"2026-10-14T18:12:02.348491Z","end":"2026-10-14T18:12:02.603388Z","steps":["trace[1724601734] 'agreement among raft nodes before linearized reading' (duration: 254.796706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.603826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.636728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:759625"} {"level":"info","ts":"2026-10-14T18:12:02.60393Z","caller":"traceutil/trace.go:171","msg":"trace[1483679966] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15286; }","duration":"350.776102ms","start":"2026-10-14T18:12:02.25313Z","end":"2026-10-14T18:12:02.603906Z","steps":["trace[1483679966] 'agreement among raft nodes before linearized reading' (duration: 349.788686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:02.603993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:12:02.253109Z","time spent":"350.866445ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":759648,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-14T18:16:54.098097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15245} {"level":"info","ts":"2026-10-14T18:16:54.193441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15245,"took":"92.20564ms","hash":2104173327,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:16:54.19349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2104173327,"revision":15245,"compact-revision":12675} {"level":"info","ts":"2026-10-14T18:17:45.366505Z","caller":"traceutil/trace.go:171","msg":"trace[1299011051] transaction","detail":"{read_only:false; response_revision:17858; number_of_response:1; }","duration":"197.745575ms","start":"2026-10-14T18:17:45.168736Z","end":"2026-10-14T18:17:45.366482Z","steps":["trace[1299011051] 'process raft request' (duration: 197.618582ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:45.36723Z","caller":"traceutil/trace.go:171","msg":"trace[1356525720] linearizableReadLoop","detail":"{readStateIndex:18466; appliedIndex:18464; }","duration":"141.042874ms","start":"2026-10-14T18:17:45.226172Z","end":"2026-10-14T18:17:45.367214Z","steps":["trace[1356525720] 'read index received' (duration: 140.209173ms)","trace[1356525720] 'applied index is now lower than readState.Index' (duration: 832.861µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:17:45.367356Z","caller":"traceutil/trace.go:171","msg":"trace[1713340849] transaction","detail":"{read_only:false; response_revision:17859; number_of_response:1; }","duration":"159.272041ms","start":"2026-10-14T18:17:45.208058Z","end":"2026-10-14T18:17:45.36733Z","steps":["trace[1713340849] 'process raft request' (duration: 159.077616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:45.367433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.25621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4k9b8\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-14T18:17:45.367465Z","caller":"traceutil/trace.go:171","msg":"trace[2074991106] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4k9b8; range_end:; response_count:1; response_revision:17859; }","duration":"141.333423ms","start":"2026-10-14T18:17:45.226123Z","end":"2026-10-14T18:17:45.367456Z","steps":["trace[2074991106] 'agreement among raft nodes before linearized reading' (duration: 141.151398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:51.356205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997605499759006955,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T18:17:51.424482Z","caller":"traceutil/trace.go:171","msg":"trace[2129684478] linearizableReadLoop","detail":"{readStateIndex:18498; appliedIndex:18497; }","duration":"569.186003ms","start":"2026-10-14T18:17:50.855272Z","end":"2026-10-14T18:17:51.424458Z","steps":["trace[2129684478] 'read index received' (duration: 569.018568ms)","trace[2129684478] 'applied index is now lower than readState.Index' (duration: 166.485µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:17:51.424645Z","caller":"traceutil/trace.go:171","msg":"trace[1472448537] transaction","detail":"{read_only:false; response_revision:17890; number_of_response:1; }","duration":"615.348679ms","start":"2026-10-14T18:17:50.809286Z","end":"2026-10-14T18:17:51.424634Z","steps":["trace[1472448537] 'process raft request' (duration: 615.00967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:51.424737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:17:50.809268Z","time spent":"615.399801ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10655,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:17:51.424886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.624263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-14T18:17:51.424925Z","caller":"traceutil/trace.go:171","msg":"trace[1382282277] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0; range_end:; response_count:1; response_revision:17890; }","duration":"569.659274ms","start":"2026-10-14T18:17:50.855252Z","end":"2026-10-14T18:17:51.424912Z","steps":["trace[1382282277] 'agreement among raft nodes before linearized reading' (duration: 569.534471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:51.42495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:17:50.855179Z","time spent":"569.765307ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-senlin-server-0\" "} {"level":"warn","ts":"2026-10-14T18:17:51.456868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.239226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-vchmc\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-14T18:17:51.45696Z","caller":"traceutil/trace.go:171","msg":"trace[1698707273] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-vchmc; range_end:; response_count:1; response_revision:17892; }","duration":"334.326678ms","start":"2026-10-14T18:17:51.122603Z","end":"2026-10-14T18:17:51.45693Z","steps":["trace[1698707273] 'agreement among raft nodes before linearized reading' (duration: 334.140923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:51.456998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:17:51.122512Z","time spent":"334.476992ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22149,"request content":"key:\"/registry/pods/openstack/nova-compute-default-vchmc\" "} {"level":"info","ts":"2026-10-14T18:17:58.707801Z","caller":"traceutil/trace.go:171","msg":"trace[1760519078] linearizableReadLoop","detail":"{readStateIndex:18603; appliedIndex:18602; }","duration":"175.827085ms","start":"2026-10-14T18:17:58.531949Z","end":"2026-10-14T18:17:58.707776Z","steps":["trace[1760519078] 'read index received' (duration: 170.850691ms)","trace[1760519078] 'applied index is now lower than readState.Index' (duration: 4.975154ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:17:58.707885Z","caller":"traceutil/trace.go:171","msg":"trace[445846483] transaction","detail":"{read_only:false; response_revision:17991; number_of_response:1; }","duration":"178.200386ms","start":"2026-10-14T18:17:58.529671Z","end":"2026-10-14T18:17:58.707871Z","steps":["trace[445846483] 'process raft request' (duration: 173.114329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:58.708466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.491892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-10-14T18:17:58.708874Z","caller":"traceutil/trace.go:171","msg":"trace[57152049] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:17991; }","duration":"176.604304ms","start":"2026-10-14T18:17:58.531909Z","end":"2026-10-14T18:17:58.708514Z","steps":["trace[57152049] 'agreement among raft nodes before linearized reading' (duration: 175.963389ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.749977Z","caller":"traceutil/trace.go:171","msg":"trace[933307795] transaction","detail":"{read_only:false; response_revision:17992; number_of_response:1; }","duration":"217.850198ms","start":"2026-10-14T18:17:58.532097Z","end":"2026-10-14T18:17:58.749948Z","steps":["trace[933307795] 'process raft request' (duration: 216.794591ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.75018Z","caller":"traceutil/trace.go:171","msg":"trace[1474606020] transaction","detail":"{read_only:false; response_revision:17998; number_of_response:1; }","duration":"217.815349ms","start":"2026-10-14T18:17:58.532327Z","end":"2026-10-14T18:17:58.750143Z","steps":["trace[1474606020] 'process raft request' (duration: 216.895506ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750268Z","caller":"traceutil/trace.go:171","msg":"trace[764597252] transaction","detail":"{read_only:false; response_revision:18002; number_of_response:1; }","duration":"217.85161ms","start":"2026-10-14T18:17:58.532389Z","end":"2026-10-14T18:17:58.75024Z","steps":["trace[764597252] 'process raft request' (duration: 217.016818ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750469Z","caller":"traceutil/trace.go:171","msg":"trace[539348957] transaction","detail":"{read_only:false; response_revision:17993; number_of_response:1; }","duration":"218.291681ms","start":"2026-10-14T18:17:58.532165Z","end":"2026-10-14T18:17:58.750456Z","steps":["trace[539348957] 'process raft request' (duration: 216.852015ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750583Z","caller":"traceutil/trace.go:171","msg":"trace[1400322881] transaction","detail":"{read_only:false; response_revision:17996; number_of_response:1; }","duration":"218.29726ms","start":"2026-10-14T18:17:58.532269Z","end":"2026-10-14T18:17:58.750566Z","steps":["trace[1400322881] 'process raft request' (duration: 216.878485ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750638Z","caller":"traceutil/trace.go:171","msg":"trace[1741376259] transaction","detail":"{read_only:false; response_revision:18000; number_of_response:1; }","duration":"218.252759ms","start":"2026-10-14T18:17:58.532361Z","end":"2026-10-14T18:17:58.750614Z","steps":["trace[1741376259] 'process raft request' (duration: 216.936947ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750604Z","caller":"traceutil/trace.go:171","msg":"trace[1744127747] transaction","detail":"{read_only:false; response_revision:17997; number_of_response:1; }","duration":"218.303971ms","start":"2026-10-14T18:17:58.532289Z","end":"2026-10-14T18:17:58.750593Z","steps":["trace[1744127747] 'process raft request' (duration: 216.899016ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750721Z","caller":"traceutil/trace.go:171","msg":"trace[1850575842] transaction","detail":"{read_only:false; response_revision:17994; number_of_response:1; }","duration":"218.523226ms","start":"2026-10-14T18:17:58.532184Z","end":"2026-10-14T18:17:58.750707Z","steps":["trace[1850575842] 'process raft request' (duration: 216.872315ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750754Z","caller":"traceutil/trace.go:171","msg":"trace[2120056562] transaction","detail":"{read_only:false; response_revision:18001; number_of_response:1; }","duration":"218.362191ms","start":"2026-10-14T18:17:58.532381Z","end":"2026-10-14T18:17:58.750743Z","steps":["trace[2120056562] 'process raft request' (duration: 216.988028ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750744Z","caller":"traceutil/trace.go:171","msg":"trace[890053167] transaction","detail":"{read_only:false; response_revision:17995; number_of_response:1; }","duration":"218.520606ms","start":"2026-10-14T18:17:58.532197Z","end":"2026-10-14T18:17:58.750717Z","steps":["trace[890053167] 'process raft request' (duration: 216.907186ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:17:58.750219Z","caller":"traceutil/trace.go:171","msg":"trace[1186955374] transaction","detail":"{read_only:false; response_revision:17999; number_of_response:1; }","duration":"217.85116ms","start":"2026-10-14T18:17:58.532347Z","end":"2026-10-14T18:17:58.750198Z","steps":["trace[1186955374] 'process raft request' (duration: 216.916826ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:09.086358Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:20:09.092695Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T18:20:09.092815Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T18:21:54.105526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17436} {"level":"info","ts":"2026-10-14T18:21:54.207623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17436,"took":"98.829764ms","hash":3905773023,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":34680832,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:21:54.207698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3905773023,"revision":17436,"compact-revision":15245} {"level":"warn","ts":"2026-10-14T18:24:43.263439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.593584ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-10-14T18:24:43.263592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.191606ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:24:43.264023Z","caller":"traceutil/trace.go:171","msg":"trace[895327286] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21291; }","duration":"117.645676ms","start":"2026-10-14T18:24:43.146354Z","end":"2026-10-14T18:24:43.264Z","steps":["trace[895327286] 'range keys from in-memory index tree' (duration: 117.181336ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:24:43.27667Z","caller":"traceutil/trace.go:171","msg":"trace[777679858] transaction","detail":"{read_only:false; response_revision:21292; number_of_response:1; }","duration":"194.320726ms","start":"2026-10-14T18:24:43.082324Z","end":"2026-10-14T18:24:43.276645Z","steps":["trace[777679858] 'process raft request' (duration: 194.117171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:43.735719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.27585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:24:43.735857Z","caller":"traceutil/trace.go:171","msg":"trace[1495573126] transaction","detail":"{read_only:false; response_revision:21293; number_of_response:1; }","duration":"631.462255ms","start":"2026-10-14T18:24:43.104364Z","end":"2026-10-14T18:24:43.735826Z","steps":["trace[1495573126] 'process raft request' (duration: 346.99741ms)","trace[1495573126] 'compare' (duration: 284.003573ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:24:43.735952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:43.104342Z","time spent":"631.562097ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:24:43.977752Z","caller":"traceutil/trace.go:171","msg":"trace[526999896] linearizableReadLoop","detail":"{readStateIndex:22010; appliedIndex:22009; }","duration":"116.107477ms","start":"2026-10-14T18:24:43.861618Z","end":"2026-10-14T18:24:43.977725Z","steps":["trace[526999896] 'read index received' (duration: 75.924911ms)","trace[526999896] 'applied index is now lower than readState.Index' (duration: 40.181066ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:24:43.977827Z","caller":"traceutil/trace.go:171","msg":"trace[1120711314] transaction","detail":"{read_only:false; response_revision:21294; number_of_response:1; }","duration":"151.050573ms","start":"2026-10-14T18:24:43.826719Z","end":"2026-10-14T18:24:43.97777Z","steps":["trace[1120711314] 'process raft request' (duration: 110.838897ms)","trace[1120711314] 'compare' (duration: 39.97664ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:24:43.978135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.463006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:24:43.978222Z","caller":"traceutil/trace.go:171","msg":"trace[905639739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21294; }","duration":"116.60786ms","start":"2026-10-14T18:24:43.861591Z","end":"2026-10-14T18:24:43.978199Z","steps":["trace[905639739] 'agreement among raft nodes before linearized reading' (duration: 116.272512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:44.31435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.70223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:24:44.314667Z","caller":"traceutil/trace.go:171","msg":"trace[33269577] transaction","detail":"{read_only:false; response_revision:21295; number_of_response:1; }","duration":"332.326283ms","start":"2026-10-14T18:24:43.982311Z","end":"2026-10-14T18:24:44.314637Z","steps":["trace[33269577] 'process raft request' (duration: 164.253063ms)","trace[33269577] 'compare' (duration: 167.162997ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:24:44.31476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:43.982286Z","time spent":"332.426215ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:24:44.32171Z","caller":"traceutil/trace.go:171","msg":"trace[1239459826] linearizableReadLoop","detail":"{readStateIndex:22014; appliedIndex:22010; }","duration":"239.122289ms","start":"2026-10-14T18:24:44.082568Z","end":"2026-10-14T18:24:44.32169Z","steps":["trace[1239459826] 'read index received' (duration: 63.953351ms)","trace[1239459826] 'applied index is now lower than readState.Index' (duration: 175.167948ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:24:44.321788Z","caller":"traceutil/trace.go:171","msg":"trace[40431394] transaction","detail":"{read_only:false; response_revision:21297; number_of_response:1; }","duration":"338.726983ms","start":"2026-10-14T18:24:43.983024Z","end":"2026-10-14T18:24:44.321751Z","steps":["trace[40431394] 'process raft request' (duration: 338.602219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:44.321866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.284312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-14T18:24:44.321899Z","caller":"traceutil/trace.go:171","msg":"trace[307101018] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21298; }","duration":"239.362624ms","start":"2026-10-14T18:24:44.082526Z","end":"2026-10-14T18:24:44.321889Z","steps":["trace[307101018] 'agreement among raft nodes before linearized reading' (duration: 239.238562ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:24:44.321905Z","caller":"traceutil/trace.go:171","msg":"trace[1548717413] transaction","detail":"{read_only:false; response_revision:21296; number_of_response:1; }","duration":"338.904406ms","start":"2026-10-14T18:24:43.982983Z","end":"2026-10-14T18:24:44.321888Z","steps":["trace[1548717413] 'process raft request' (duration: 338.521827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:44.321968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:43.983003Z","time spent":"338.866886ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:24:44.321988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:43.982969Z","time spent":"338.966248ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:24:44.322068Z","caller":"traceutil/trace.go:171","msg":"trace[211472327] transaction","detail":"{read_only:false; response_revision:21298; number_of_response:1; }","duration":"337.83645ms","start":"2026-10-14T18:24:43.984209Z","end":"2026-10-14T18:24:44.322046Z","steps":["trace[211472327] 'process raft request' (duration: 337.447281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:44.322203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:43.984195Z","time spent":"337.955864ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:24:44.692872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.985139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:24:44.692979Z","caller":"traceutil/trace.go:171","msg":"trace[963064051] linearizableReadLoop","detail":"{readStateIndex:22015; appliedIndex:22014; }","duration":"176.672924ms","start":"2026-10-14T18:24:44.51629Z","end":"2026-10-14T18:24:44.692963Z","steps":["trace[963064051] 'read index received' (duration: 42.881µs)","trace[963064051] 'applied index is now lower than readState.Index' (duration: 176.628683ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:24:44.693245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.940562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-14T18:24:44.693295Z","caller":"traceutil/trace.go:171","msg":"trace[1111577622] transaction","detail":"{read_only:false; response_revision:21299; number_of_response:1; }","duration":"365.997115ms","start":"2026-10-14T18:24:44.327038Z","end":"2026-10-14T18:24:44.693035Z","steps":["trace[1111577622] 'process raft request' (duration: 171.693189ms)","trace[1111577622] 'compare' (duration: 193.859785ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:24:44.693323Z","caller":"traceutil/trace.go:171","msg":"trace[4436425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21299; }","duration":"177.028194ms","start":"2026-10-14T18:24:44.516276Z","end":"2026-10-14T18:24:44.693304Z","steps":["trace[4436425] 'agreement among raft nodes before linearized reading' (duration: 176.778278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:44.693367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:44.32702Z","time spent":"366.307974ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:24:44.698327Z","caller":"traceutil/trace.go:171","msg":"trace[344343519] transaction","detail":"{read_only:false; response_revision:21300; number_of_response:1; }","duration":"129.37218ms","start":"2026-10-14T18:24:44.56893Z","end":"2026-10-14T18:24:44.698303Z","steps":["trace[344343519] 'process raft request' (duration: 128.99976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:45.002361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.353671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:24:45.002513Z","caller":"traceutil/trace.go:171","msg":"trace[973981021] transaction","detail":"{read_only:false; response_revision:21301; number_of_response:1; }","duration":"305.172632ms","start":"2026-10-14T18:24:44.697305Z","end":"2026-10-14T18:24:45.002477Z","steps":["trace[973981021] 'process raft request' (duration: 135.625037ms)","trace[973981021] 'compare' (duration: 169.165645ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:24:45.002667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:44.69728Z","time spent":"305.303066ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:24:45.644858Z","caller":"traceutil/trace.go:171","msg":"trace[131073968] linearizableReadLoop","detail":"{readStateIndex:22018; appliedIndex:22017; }","duration":"150.645183ms","start":"2026-10-14T18:24:45.494184Z","end":"2026-10-14T18:24:45.644829Z","steps":["trace[131073968] 'read index received' (duration: 150.322935ms)","trace[131073968] 'applied index is now lower than readState.Index' (duration: 320.928µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:24:45.644934Z","caller":"traceutil/trace.go:171","msg":"trace[474832183] transaction","detail":"{read_only:false; response_revision:21302; number_of_response:1; }","duration":"363.012241ms","start":"2026-10-14T18:24:45.281888Z","end":"2026-10-14T18:24:45.6449Z","steps":["trace[474832183] 'process raft request' (duration: 362.748794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:45.645058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.857878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-14T18:24:45.645092Z","caller":"traceutil/trace.go:171","msg":"trace[998977317] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:21302; }","duration":"150.903219ms","start":"2026-10-14T18:24:45.494176Z","end":"2026-10-14T18:24:45.645079Z","steps":["trace[998977317] 'agreement among raft nodes before linearized reading' (duration: 150.756985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:24:45.645081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:24:45.281859Z","time spent":"363.134034ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:26:54.114484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20128} {"level":"info","ts":"2026-10-14T18:26:54.245691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20128,"took":"127.30127ms","hash":1682261928,"current-db-size-bytes":55472128,"current-db-size":"56 MB","current-db-size-in-use-bytes":31244288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:26:54.245764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1682261928,"revision":20128,"compact-revision":17436} {"level":"warn","ts":"2026-10-14T18:28:33.123313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.187871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:28:33.123408Z","caller":"traceutil/trace.go:171","msg":"trace[1553314124] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:23757; }","duration":"110.325294ms","start":"2026-10-14T18:28:33.01306Z","end":"2026-10-14T18:28:33.123386Z","steps":["trace[1553314124] 'count revisions from in-memory index tree' (duration: 110.102598ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:33.559699Z","caller":"traceutil/trace.go:171","msg":"trace[108094832] transaction","detail":"{read_only:false; response_revision:23758; number_of_response:1; }","duration":"307.146094ms","start":"2026-10-14T18:28:33.252531Z","end":"2026-10-14T18:28:33.559677Z","steps":["trace[108094832] 'process raft request' (duration: 306.835646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:33.559812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:33.252515Z","time spent":"307.238417ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:30:03.444434Z","caller":"traceutil/trace.go:171","msg":"trace[726792831] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"143.450623ms","start":"2026-10-14T18:30:03.300958Z","end":"2026-10-14T18:30:03.444409Z","steps":["trace[726792831] 'process raft request' (duration: 143.290879ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:30:03.450118Z","caller":"traceutil/trace.go:171","msg":"trace[59344687] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"123.274688ms","start":"2026-10-14T18:30:03.326822Z","end":"2026-10-14T18:30:03.450096Z","steps":["trace[59344687] 'process raft request' (duration: 122.749625ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:31:51.236351Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067a9.wal"} {"level":"info","ts":"2026-10-14T18:31:54.125484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22402} {"level":"info","ts":"2026-10-14T18:31:54.251666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22402,"took":"121.981066ms","hash":3232752007,"current-db-size-bytes":56324096,"current-db-size":"56 MB","current-db-size-in-use-bytes":45801472,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-14T18:31:54.251763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3232752007,"revision":22402,"compact-revision":20128} {"level":"info","ts":"2026-10-14T18:32:04.897809Z","caller":"traceutil/trace.go:171","msg":"trace[287200322] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"186.479802ms","start":"2026-10-14T18:32:04.711306Z","end":"2026-10-14T18:32:04.897786Z","steps":["trace[287200322] 'process raft request' (duration: 186.154244ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:04.897614Z","caller":"traceutil/trace.go:171","msg":"trace[2049793005] linearizableReadLoop","detail":"{readStateIndex:26700; appliedIndex:26699; }","duration":"169.134497ms","start":"2026-10-14T18:32:04.728457Z","end":"2026-10-14T18:32:04.897591Z","steps":["trace[2049793005] 'read index received' (duration: 168.989463ms)","trace[2049793005] 'applied index is now lower than readState.Index' (duration: 144.164µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:32:04.898593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.106042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:32:04.898646Z","caller":"traceutil/trace.go:171","msg":"trace[422736226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25856; }","duration":"170.211114ms","start":"2026-10-14T18:32:04.728422Z","end":"2026-10-14T18:32:04.898633Z","steps":["trace[422736226] 'agreement among raft nodes before linearized reading' (duration: 169.65091ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:05.232164Z","caller":"traceutil/trace.go:171","msg":"trace[1135063134] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"328.681463ms","start":"2026-10-14T18:32:04.903452Z","end":"2026-10-14T18:32:05.232134Z","steps":["trace[1135063134] 'process raft request' (duration: 328.472297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:05.23236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:04.90343Z","time spent":"328.854117ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:32:05.233234Z","caller":"traceutil/trace.go:171","msg":"trace[613149428] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"329.678548ms","start":"2026-10-14T18:32:04.903527Z","end":"2026-10-14T18:32:05.233206Z","steps":["trace[613149428] 'process raft request' (duration: 329.475743ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:05.233522Z","caller":"traceutil/trace.go:171","msg":"trace[2094907597] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"329.785841ms","start":"2026-10-14T18:32:04.903711Z","end":"2026-10-14T18:32:05.233497Z","steps":["trace[2094907597] 'process raft request' (duration: 329.389021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:05.233386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:04.903498Z","time spent":"329.805171ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:32:05.23372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:04.903697Z","time spent":"329.930855ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:32:05.233968Z","caller":"traceutil/trace.go:171","msg":"trace[424886723] linearizableReadLoop","detail":"{readStateIndex:26704; appliedIndex:26700; }","duration":"326.829547ms","start":"2026-10-14T18:32:04.907097Z","end":"2026-10-14T18:32:05.233927Z","steps":["trace[424886723] 'read index received' (duration: 324.931759ms)","trace[424886723] 'applied index is now lower than readState.Index' (duration: 1.894928ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:05.234013Z","caller":"traceutil/trace.go:171","msg":"trace[2003595784] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"330.003096ms","start":"2026-10-14T18:32:04.903996Z","end":"2026-10-14T18:32:05.233999Z","steps":["trace[2003595784] 'process raft request' (duration: 329.165115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:05.234084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.988621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:32:05.234118Z","caller":"traceutil/trace.go:171","msg":"trace[1526785852] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"272.863346ms","start":"2026-10-14T18:32:04.961205Z","end":"2026-10-14T18:32:05.234068Z","steps":["trace[1526785852] 'process raft request' (duration: 271.986993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:05.23421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:04.903982Z","time spent":"330.062628ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:32:05.234233Z","caller":"traceutil/trace.go:171","msg":"trace[469925786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25861; }","duration":"327.055753ms","start":"2026-10-14T18:32:04.907055Z","end":"2026-10-14T18:32:05.23411Z","steps":["trace[469925786] 'agreement among raft nodes before linearized reading' (duration: 326.991041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:05.234273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:04.907038Z","time spent":"327.224497ms","remote":"127.0.0.1:57830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:33:09.07004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.305772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:09.070128Z","caller":"traceutil/trace.go:171","msg":"trace[516780520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26430; }","duration":"167.436664ms","start":"2026-10-14T18:33:08.902674Z","end":"2026-10-14T18:33:09.070111Z","steps":["trace[516780520] 'range keys from in-memory index tree' (duration: 167.216019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:09.070156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.838079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:09.070211Z","caller":"traceutil/trace.go:171","msg":"trace[1858523544] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:26430; }","duration":"160.940671ms","start":"2026-10-14T18:33:08.909255Z","end":"2026-10-14T18:33:09.070196Z","steps":["trace[1858523544] 'count revisions from in-memory index tree' (duration: 160.742885ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:09.295312Z","caller":"traceutil/trace.go:171","msg":"trace[179328628] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"101.840222ms","start":"2026-10-14T18:33:09.193432Z","end":"2026-10-14T18:33:09.295272Z","steps":["trace[179328628] 'process raft request' (duration: 101.567585ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:09.812345Z","caller":"traceutil/trace.go:171","msg":"trace[1914571689] linearizableReadLoop","detail":"{readStateIndex:27292; appliedIndex:27291; }","duration":"268.144276ms","start":"2026-10-14T18:33:09.544178Z","end":"2026-10-14T18:33:09.812322Z","steps":["trace[1914571689] 'read index received' (duration: 267.90078ms)","trace[1914571689] 'applied index is now lower than readState.Index' (duration: 242.576µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:33:09.812449Z","caller":"traceutil/trace.go:171","msg":"trace[283050029] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"314.58724ms","start":"2026-10-14T18:33:09.497851Z","end":"2026-10-14T18:33:09.812438Z","steps":["trace[283050029] 'process raft request' (duration: 314.329904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:09.812722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.497822Z","time spent":"314.817275ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:33:09.812712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.525377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T18:33:09.812878Z","caller":"traceutil/trace.go:171","msg":"trace[930030690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26433; }","duration":"268.735562ms","start":"2026-10-14T18:33:09.544124Z","end":"2026-10-14T18:33:09.81286Z","steps":["trace[930030690] 'agreement among raft nodes before linearized reading' (duration: 268.470525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.135822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.219061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:33:10.135999Z","caller":"traceutil/trace.go:171","msg":"trace[1176370533] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"455.209013ms","start":"2026-10-14T18:33:09.680771Z","end":"2026-10-14T18:33:10.13598Z","steps":["trace[1176370533] 'process raft request' (duration: 251.750916ms)","trace[1176370533] 'compare' (duration: 202.754419ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:10.136064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.680751Z","time spent":"455.284935ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:33:10.136761Z","caller":"traceutil/trace.go:171","msg":"trace[1725629302] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"453.628922ms","start":"2026-10-14T18:33:09.683105Z","end":"2026-10-14T18:33:10.136734Z","steps":["trace[1725629302] 'process raft request' (duration: 452.832823ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:10.136821Z","caller":"traceutil/trace.go:171","msg":"trace[391951289] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"375.425533ms","start":"2026-10-14T18:33:09.761359Z","end":"2026-10-14T18:33:10.136784Z","steps":["trace[391951289] 'process raft request' (duration: 375.276841ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:10.136872Z","caller":"traceutil/trace.go:171","msg":"trace[1420056924] linearizableReadLoop","detail":"{readStateIndex:27295; appliedIndex:27292; }","duration":"324.428186ms","start":"2026-10-14T18:33:09.812413Z","end":"2026-10-14T18:33:10.136841Z","steps":["trace[1420056924] 'read index received' (duration: 120.109058ms)","trace[1420056924] 'applied index is now lower than readState.Index' (duration: 204.317348ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:33:10.136929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.683092Z","time spent":"453.742665ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:33:10.13699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.76134Z","time spent":"375.545746ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:33:10.137098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.357238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:33:10.137109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.324404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-14T18:33:10.137097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.908857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-14T18:33:10.137134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.993051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-10-14T18:33:10.137145Z","caller":"traceutil/trace.go:171","msg":"trace[63706911] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26436; }","duration":"487.43953ms","start":"2026-10-14T18:33:09.649694Z","end":"2026-10-14T18:33:10.137133Z","steps":["trace[63706911] 'agreement among raft nodes before linearized reading' (duration: 487.341648ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:10.137153Z","caller":"traceutil/trace.go:171","msg":"trace[913872307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26436; }","duration":"546.422606ms","start":"2026-10-14T18:33:09.590714Z","end":"2026-10-14T18:33:10.137137Z","steps":["trace[913872307] 'agreement among raft nodes before linearized reading' (duration: 546.268863ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:10.137175Z","caller":"traceutil/trace.go:171","msg":"trace[938276475] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26436; }","duration":"547.068923ms","start":"2026-10-14T18:33:09.590094Z","end":"2026-10-14T18:33:10.137163Z","steps":["trace[938276475] 'agreement among raft nodes before linearized reading' (duration: 546.94819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.137181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.649683Z","time spent":"487.489171ms","remote":"127.0.0.1:60574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:33:10.137177Z","caller":"traceutil/trace.go:171","msg":"trace[912204572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26436; }","duration":"491.002559ms","start":"2026-10-14T18:33:09.646156Z","end":"2026-10-14T18:33:10.137158Z","steps":["trace[912204572] 'agreement among raft nodes before linearized reading' (duration: 490.746352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.137188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.590696Z","time spent":"546.484099ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T18:33:10.137206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.590072Z","time spent":"547.125745ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T18:33:10.137188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.453887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/senlin-api-549c54456f-gn6ch.18de784e56421fff\" ","response":"range_response_count:1 size:1170"} {"level":"warn","ts":"2026-10-14T18:33:10.137224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.646096Z","time spent":"491.119991ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T18:33:10.137252Z","caller":"traceutil/trace.go:171","msg":"trace[2136197520] range","detail":"{range_begin:/registry/events/openstack/senlin-api-549c54456f-gn6ch.18de784e56421fff; range_end:; response_count:1; response_revision:26436; }","duration":"546.56633ms","start":"2026-10-14T18:33:09.590669Z","end":"2026-10-14T18:33:10.137235Z","steps":["trace[2136197520] 'agreement among raft nodes before linearized reading' (duration: 546.388015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.137286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.590645Z","time spent":"546.632832ms","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1193,"request content":"key:\"/registry/events/openstack/senlin-api-549c54456f-gn6ch.18de784e56421fff\" "} {"level":"warn","ts":"2026-10-14T18:33:10.137287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.702005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:33:10.137362Z","caller":"traceutil/trace.go:171","msg":"trace[528513886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26436; }","duration":"564.828869ms","start":"2026-10-14T18:33:09.57251Z","end":"2026-10-14T18:33:10.137339Z","steps":["trace[528513886] 'agreement among raft nodes before linearized reading' (duration: 564.607723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.137436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.572493Z","time spent":"564.927171ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T18:33:10.137585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.877096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-14T18:33:10.137625Z","caller":"traceutil/trace.go:171","msg":"trace[1835828477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26436; }","duration":"480.937867ms","start":"2026-10-14T18:33:09.656677Z","end":"2026-10-14T18:33:10.137615Z","steps":["trace[1835828477] 'agreement among raft nodes before linearized reading' (duration: 480.310251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.137692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.656667Z","time spent":"481.016029ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T18:33:10.146958Z","caller":"traceutil/trace.go:171","msg":"trace[1779306465] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"329.462433ms","start":"2026-10-14T18:33:09.817475Z","end":"2026-10-14T18:33:10.146937Z","steps":["trace[1779306465] 'process raft request' (duration: 329.198126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.147077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:33:09.817456Z","time spent":"329.559795ms","remote":"127.0.0.1:58090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:33:10.147216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.819167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:10.14728Z","caller":"traceutil/trace.go:171","msg":"trace[1479115321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26438; }","duration":"243.92817ms","start":"2026-10-14T18:33:09.903336Z","end":"2026-10-14T18:33:10.147264Z","steps":["trace[1479115321] 'agreement among raft nodes before linearized reading' (duration: 243.818397ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:10.14738Z","caller":"traceutil/trace.go:171","msg":"trace[1132558135] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"252.478455ms","start":"2026-10-14T18:33:09.89489Z","end":"2026-10-14T18:33:10.147368Z","steps":["trace[1132558135] 'process raft request' (duration: 251.938051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:10.147547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.339469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-14T18:33:10.147606Z","caller":"traceutil/trace.go:171","msg":"trace[1114404774] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26438; }","duration":"202.450962ms","start":"2026-10-14T18:33:09.945146Z","end":"2026-10-14T18:33:10.147597Z","steps":["trace[1114404774] 'agreement among raft nodes before linearized reading' (duration: 202.305188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:03.579164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.078703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:35:03.579302Z","caller":"traceutil/trace.go:171","msg":"trace[233767952] transaction","detail":"{read_only:false; response_revision:27552; number_of_response:1; }","duration":"158.985552ms","start":"2026-10-14T18:35:03.420298Z","end":"2026-10-14T18:35:03.579283Z","steps":["trace[233767952] 'process raft request' (duration: 35.703154ms)","trace[233767952] 'compare' (duration: 122.906479ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:03.773916Z","caller":"traceutil/trace.go:171","msg":"trace[903638043] transaction","detail":"{read_only:false; response_revision:27554; number_of_response:1; }","duration":"145.921806ms","start":"2026-10-14T18:35:03.627939Z","end":"2026-10-14T18:35:03.773861Z","steps":["trace[903638043] 'process raft request' (duration: 137.729541ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:03.941519Z","caller":"traceutil/trace.go:171","msg":"trace[1220889098] linearizableReadLoop","detail":"{readStateIndex:28440; appliedIndex:28439; }","duration":"120.13339ms","start":"2026-10-14T18:35:03.82136Z","end":"2026-10-14T18:35:03.941494Z","steps":["trace[1220889098] 'read index received' (duration: 116.588722ms)","trace[1220889098] 'applied index is now lower than readState.Index' (duration: 3.543758ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:03.941596Z","caller":"traceutil/trace.go:171","msg":"trace[1742808965] transaction","detail":"{read_only:false; response_revision:27555; number_of_response:1; }","duration":"140.394285ms","start":"2026-10-14T18:35:03.801164Z","end":"2026-10-14T18:35:03.941558Z","steps":["trace[1742808965] 'process raft request' (duration: 136.835707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:03.941841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.310644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:35:03.941913Z","caller":"traceutil/trace.go:171","msg":"trace[365187938] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27555; }","duration":"120.582071ms","start":"2026-10-14T18:35:03.821318Z","end":"2026-10-14T18:35:03.9419Z","steps":["trace[365187938] 'agreement among raft nodes before linearized reading' (duration: 120.298504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:03.941934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.827961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21431"} {"level":"info","ts":"2026-10-14T18:35:03.942176Z","caller":"traceutil/trace.go:171","msg":"trace[2077743302] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:27555; }","duration":"116.090027ms","start":"2026-10-14T18:35:03.826047Z","end":"2026-10-14T18:35:03.942137Z","steps":["trace[2077743302] 'agreement among raft nodes before linearized reading' (duration: 115.660016ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.137403Z","caller":"traceutil/trace.go:171","msg":"trace[1968139123] transaction","detail":"{read_only:false; response_revision:27556; number_of_response:1; }","duration":"179.361352ms","start":"2026-10-14T18:35:03.958015Z","end":"2026-10-14T18:35:04.137376Z","steps":["trace[1968139123] 'process raft request' (duration: 160.083649ms)","trace[1968139123] 'compare' (duration: 19.016747ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:04.166167Z","caller":"traceutil/trace.go:171","msg":"trace[1659268066] linearizableReadLoop","detail":"{readStateIndex:28443; appliedIndex:28440; }","duration":"176.680316ms","start":"2026-10-14T18:35:03.989471Z","end":"2026-10-14T18:35:04.166151Z","steps":["trace[1659268066] 'read index received' (duration: 128.806426ms)","trace[1659268066] 'applied index is now lower than readState.Index' (duration: 47.87302ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:04.166175Z","caller":"traceutil/trace.go:171","msg":"trace[72994606] transaction","detail":"{read_only:false; response_revision:27557; number_of_response:1; }","duration":"201.737763ms","start":"2026-10-14T18:35:03.9644Z","end":"2026-10-14T18:35:04.166138Z","steps":["trace[72994606] 'process raft request' (duration: 201.415125ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.166354Z","caller":"traceutil/trace.go:171","msg":"trace[801171354] transaction","detail":"{read_only:false; response_revision:27558; number_of_response:1; }","duration":"184.846771ms","start":"2026-10-14T18:35:03.981496Z","end":"2026-10-14T18:35:04.166343Z","steps":["trace[801171354] 'process raft request' (duration: 184.44679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.166549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.068466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:35:04.16659Z","caller":"traceutil/trace.go:171","msg":"trace[1189280057] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27558; }","duration":"177.166919ms","start":"2026-10-14T18:35:03.989414Z","end":"2026-10-14T18:35:04.166581Z","steps":["trace[1189280057] 'agreement among raft nodes before linearized reading' (duration: 177.089967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.16673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.071776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21431"} {"level":"info","ts":"2026-10-14T18:35:04.166813Z","caller":"traceutil/trace.go:171","msg":"trace[1903803773] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:27558; }","duration":"171.195419ms","start":"2026-10-14T18:35:03.995599Z","end":"2026-10-14T18:35:04.166795Z","steps":["trace[1903803773] 'agreement among raft nodes before linearized reading' (duration: 170.948893ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:31.114467Z","caller":"traceutil/trace.go:171","msg":"trace[1635043626] transaction","detail":"{read_only:false; response_revision:28482; number_of_response:1; }","duration":"130.005209ms","start":"2026-10-14T18:36:30.984429Z","end":"2026-10-14T18:36:31.114434Z","steps":["trace[1635043626] 'process raft request' (duration: 74.284562ms)","trace[1635043626] 'compare' (duration: 55.44597ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:36:31.172031Z","caller":"traceutil/trace.go:171","msg":"trace[1937717706] transaction","detail":"{read_only:false; response_revision:28484; number_of_response:1; }","duration":"151.958801ms","start":"2026-10-14T18:36:31.020042Z","end":"2026-10-14T18:36:31.172Z","steps":["trace[1937717706] 'process raft request' (duration: 151.831267ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:31.172047Z","caller":"traceutil/trace.go:171","msg":"trace[253868393] transaction","detail":"{read_only:false; response_revision:28483; number_of_response:1; }","duration":"164.200069ms","start":"2026-10-14T18:36:31.0078Z","end":"2026-10-14T18:36:31.172Z","steps":["trace[253868393] 'process raft request' (duration: 163.885231ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:31.172091Z","caller":"traceutil/trace.go:171","msg":"trace[9308176] linearizableReadLoop","detail":"{readStateIndex:29392; appliedIndex:29389; }","duration":"104.583913ms","start":"2026-10-14T18:36:31.067481Z","end":"2026-10-14T18:36:31.172065Z","steps":["trace[9308176] 'read index received' (duration: 104.204284ms)","trace[9308176] 'applied index is now lower than readState.Index' (duration: 378.659µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:36:31.172123Z","caller":"traceutil/trace.go:171","msg":"trace[513038370] transaction","detail":"{read_only:false; response_revision:28485; number_of_response:1; }","duration":"139.13426ms","start":"2026-10-14T18:36:31.032959Z","end":"2026-10-14T18:36:31.172093Z","steps":["trace[513038370] 'process raft request' (duration: 139.003607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:31.17237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.83953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4843"} {"level":"info","ts":"2026-10-14T18:36:31.172416Z","caller":"traceutil/trace.go:171","msg":"trace[1540913878] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:28485; }","duration":"104.959203ms","start":"2026-10-14T18:36:31.067444Z","end":"2026-10-14T18:36:31.172403Z","steps":["trace[1540913878] 'agreement among raft nodes before linearized reading' (duration: 104.712017ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:54.136046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25773} {"level":"info","ts":"2026-10-14T18:36:54.344603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25773,"took":"204.519638ms","hash":422907722,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":36208640,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T18:36:54.344678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":422907722,"revision":25773,"compact-revision":22402} {"level":"info","ts":"2026-10-14T18:37:35.351178Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:37:35.357396Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T18:37:35.357513Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T18:41:54.143273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28756} {"level":"info","ts":"2026-10-14T18:41:54.294486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28756,"took":"147.033237ms","hash":665681998,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":32636928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T18:41:54.29456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":665681998,"revision":28756,"compact-revision":25773} {"level":"info","ts":"2026-10-14T18:46:54.154302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31042} {"level":"info","ts":"2026-10-14T18:46:54.299399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31042,"took":"140.575437ms","hash":2779456925,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":30998528,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:46:54.29948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2779456925,"revision":31042,"compact-revision":28756} {"level":"info","ts":"2026-10-14T18:51:54.1636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33338} {"level":"info","ts":"2026-10-14T18:51:54.331713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33338,"took":"163.71552ms","hash":860927093,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":31035392,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:51:54.331781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":860927093,"revision":33338,"compact-revision":31042} {"level":"info","ts":"2026-10-14T18:56:54.169718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35639} {"level":"info","ts":"2026-10-14T18:56:54.320325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35639,"took":"145.650805ms","hash":2734696928,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":32346112,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:56:54.320411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2734696928,"revision":35639,"compact-revision":33338} {"level":"info","ts":"2026-10-14T18:58:46.106105Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:58:46.112232Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T18:58:46.112335Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T19:01:54.179609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37938} {"level":"info","ts":"2026-10-14T19:01:54.330327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37938,"took":"145.01887ms","hash":2716912154,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":32550912,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:01:54.330399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2716912154,"revision":37938,"compact-revision":35639}