{"level":"warn","ts":"2026-10-15T14:05:33.239179Z","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-15T14:05:33.239315Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T14:05:33.239417Z","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-15T14:05:33.239434Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-15T14:05:33.239465Z","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-15T14:05:33.24023Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-10-15T14:05:33.24037Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T14:05:33.24786Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.208785ms"} {"level":"info","ts":"2026-10-15T14:05:33.265567Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-10-15T14:05:33.265706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:05:33.265741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-10-15T14:05:33.265761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:05:33.265774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-10-15T14:05:33.265846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-10-15T14:05:33.279241Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:05:33.281291Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:05:33.282521Z","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-15T14:05:33.284664Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:05:33.288075Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:05:33.288313Z","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-15T14:05:33.288391Z","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-15T14:05:33.288405Z","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-15T14:05:33.291442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-10-15T14:05:33.291629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-10-15T14:05:33.291897Z","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-15T14:05:33.292052Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-15T14:05:33.292122Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-10-15T14:05:33.292261Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:05:33.292313Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:05:34.06636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:05:34.066414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:05:34.066444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-10-15T14:05:34.066458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:05:34.066463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-15T14:05:34.066472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-10-15T14:05:34.066481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-10-15T14:05:34.068715Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:05:34.068722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:05:34.068715Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:05:34.068859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:05:34.068828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:05:34.068881Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:05:34.069639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:05:34.069717Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:05:34.06974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:05:34.070447Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-10-15T14:05:34.072023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:05:37.497589Z","caller":"traceutil/trace.go:171","msg":"trace[1303409805] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"120.132589ms","start":"2026-10-15T14:05:37.377398Z","end":"2026-10-15T14:05:37.497531Z","steps":["trace[1303409805] 'process raft request' (duration: 62.988923ms)","trace[1303409805] 'compare' (duration: 56.618623ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:05:37.67213Z","caller":"traceutil/trace.go:171","msg":"trace[28763816] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:244; }","duration":"110.688506ms","start":"2026-10-15T14:05:37.561414Z","end":"2026-10-15T14:05:37.672103Z","steps":["trace[28763816] 'read index received' (duration: 66.61745ms)","trace[28763816] 'applied index is now lower than readState.Index' (duration: 44.070136ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:05:37.672494Z","caller":"traceutil/trace.go:171","msg":"trace[294581135] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"120.476168ms","start":"2026-10-15T14:05:37.551952Z","end":"2026-10-15T14:05:37.672428Z","steps":["trace[294581135] 'process raft request' (duration: 76.130236ms)","trace[294581135] 'compare' (duration: 43.830199ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:05:37.672501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.87216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:05:37.672694Z","caller":"traceutil/trace.go:171","msg":"trace[979996307] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:240; }","duration":"111.29974ms","start":"2026-10-15T14:05:37.561372Z","end":"2026-10-15T14:05:37.672672Z","steps":["trace[979996307] 'agreement among raft nodes before linearized reading' (duration: 110.85101ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:05:37.672869Z","caller":"traceutil/trace.go:171","msg":"trace[1750807677] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"113.362099ms","start":"2026-10-15T14:05:37.559486Z","end":"2026-10-15T14:05:37.672848Z","steps":["trace[1750807677] 'process raft request' (duration: 112.571431ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:05:37.835234Z","caller":"traceutil/trace.go:171","msg":"trace[757260711] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"157.025765ms","start":"2026-10-15T14:05:37.678182Z","end":"2026-10-15T14:05:37.835208Z","steps":["trace[757260711] 'process raft request' (duration: 101.428366ms)","trace[757260711] 'compare' (duration: 55.456466ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:06:00.286976Z","caller":"traceutil/trace.go:171","msg":"trace[311197853] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"183.816738ms","start":"2026-10-15T14:06:00.103126Z","end":"2026-10-15T14:06:00.286943Z","steps":["trace[311197853] 'read index received' (duration: 183.722606ms)","trace[311197853] 'applied index is now lower than readState.Index' (duration: 92.622µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:06:00.287073Z","caller":"traceutil/trace.go:171","msg":"trace[1418201158] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"187.728791ms","start":"2026-10-15T14:06:00.099295Z","end":"2026-10-15T14:06:00.287024Z","steps":["trace[1418201158] 'process raft request' (duration: 187.516147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:00.287254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.115944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T14:06:00.287333Z","caller":"traceutil/trace.go:171","msg":"trace[1110986004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"184.261267ms","start":"2026-10-15T14:06:00.103054Z","end":"2026-10-15T14:06:00.287315Z","steps":["trace[1110986004] 'agreement among raft nodes before linearized reading' (duration: 183.997381ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:00.402182Z","caller":"traceutil/trace.go:171","msg":"trace[1550083431] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"110.471062ms","start":"2026-10-15T14:06:00.291694Z","end":"2026-10-15T14:06:00.402165Z","steps":["trace[1550083431] 'process raft request' (duration: 80.832426ms)","trace[1550083431] 'compare' (duration: 29.373ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:06:21.331032Z","caller":"traceutil/trace.go:171","msg":"trace[775826932] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"182.73714ms","start":"2026-10-15T14:06:21.148256Z","end":"2026-10-15T14:06:21.330993Z","steps":["trace[775826932] 'process raft request' (duration: 182.474644ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.345258Z","caller":"traceutil/trace.go:171","msg":"trace[2091827177] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"184.835421ms","start":"2026-10-15T14:06:21.159681Z","end":"2026-10-15T14:06:21.344516Z","steps":["trace[2091827177] 'process raft request' (duration: 184.380322ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.346118Z","caller":"traceutil/trace.go:171","msg":"trace[1253617328] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"196.295101ms","start":"2026-10-15T14:06:21.148587Z","end":"2026-10-15T14:06:21.344882Z","steps":["trace[1253617328] 'process raft request' (duration: 195.315871ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.346422Z","caller":"traceutil/trace.go:171","msg":"trace[1780301917] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"157.29084ms","start":"2026-10-15T14:06:21.187184Z","end":"2026-10-15T14:06:21.344475Z","steps":["trace[1780301917] 'process raft request' (duration: 157.042105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.34678Z","caller":"traceutil/trace.go:171","msg":"trace[609045715] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"159.435573ms","start":"2026-10-15T14:06:21.187326Z","end":"2026-10-15T14:06:21.346762Z","steps":["trace[609045715] 'process raft request' (duration: 157.115937ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.347531Z","caller":"traceutil/trace.go:171","msg":"trace[1710030660] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"170.27726ms","start":"2026-10-15T14:06:21.177167Z","end":"2026-10-15T14:06:21.347444Z","steps":["trace[1710030660] 'process raft request' (duration: 166.981314ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.349649Z","caller":"traceutil/trace.go:171","msg":"trace[388644194] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"154.867721ms","start":"2026-10-15T14:06:21.194579Z","end":"2026-10-15T14:06:21.349446Z","steps":["trace[388644194] 'process raft request' (duration: 153.31023ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.347289Z","caller":"traceutil/trace.go:171","msg":"trace[2094852619] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"154.068536ms","start":"2026-10-15T14:06:21.193121Z","end":"2026-10-15T14:06:21.347189Z","steps":["trace[2094852619] 'process raft request' (duration: 152.724989ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.351837Z","caller":"traceutil/trace.go:171","msg":"trace[165006558] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"119.166027ms","start":"2026-10-15T14:06:21.232651Z","end":"2026-10-15T14:06:21.351817Z","steps":["trace[165006558] 'process raft request' (duration: 119.080445ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.351885Z","caller":"traceutil/trace.go:171","msg":"trace[1285890336] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"140.837901ms","start":"2026-10-15T14:06:21.211031Z","end":"2026-10-15T14:06:21.351869Z","steps":["trace[1285890336] 'process raft request' (duration: 140.608506ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:21.351829Z","caller":"traceutil/trace.go:171","msg":"trace[1426098334] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"153.800341ms","start":"2026-10-15T14:06:21.197966Z","end":"2026-10-15T14:06:21.351766Z","steps":["trace[1426098334] 'process raft request' (duration: 153.462454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:28.3815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906287335629444987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T14:06:28.501259Z","caller":"traceutil/trace.go:171","msg":"trace[2042632112] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"808.111887ms","start":"2026-10-15T14:06:27.693109Z","end":"2026-10-15T14:06:28.501221Z","steps":["trace[2042632112] 'process raft request' (duration: 807.901183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:28.501906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:27.693083Z","time spent":"808.2601ms","remote":"127.0.0.1:51552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:06:29.046026Z","caller":"traceutil/trace.go:171","msg":"trace[1428408394] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:682; }","duration":"1.16480128s","start":"2026-10-15T14:06:27.881163Z","end":"2026-10-15T14:06:29.045964Z","steps":["trace[1428408394] 'read index received' (duration: 619.978062ms)","trace[1428408394] 'applied index is now lower than readState.Index' (duration: 544.822278ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:06:29.046236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165052305s","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-15T14:06:29.04694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.72419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:06:29.046924Z","caller":"traceutil/trace.go:171","msg":"trace[1217561064] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.296872781s","start":"2026-10-15T14:06:27.749999Z","end":"2026-10-15T14:06:29.046872Z","steps":["trace[1217561064] 'process raft request' (duration: 1.293422633s)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:29.047035Z","caller":"traceutil/trace.go:171","msg":"trace[1328369698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"478.858992ms","start":"2026-10-15T14:06:28.568147Z","end":"2026-10-15T14:06:29.047006Z","steps":["trace[1328369698] 'agreement among raft nodes before linearized reading' (duration: 478.74101ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:29.046998Z","caller":"traceutil/trace.go:171","msg":"trace[1578582599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.16582763s","start":"2026-10-15T14:06:27.881098Z","end":"2026-10-15T14:06:29.046926Z","steps":["trace[1578582599] 'agreement among raft nodes before linearized reading' (duration: 1.164980483s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:29.047046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.511252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-15T14:06:29.04709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:28.568115Z","time spent":"478.963785ms","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T14:06:29.047106Z","caller":"traceutil/trace.go:171","msg":"trace[535807953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"406.575923ms","start":"2026-10-15T14:06:28.640514Z","end":"2026-10-15T14:06:29.04709Z","steps":["trace[535807953] 'agreement among raft nodes before linearized reading' (duration: 406.39797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:29.047167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:28.64043Z","time spent":"406.700915ms","remote":"127.0.0.1:51626","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-15T14:06:29.047175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:27.881069Z","time spent":"1.166025104s","remote":"127.0.0.1:51626","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-15T14:06:29.04722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:27.749957Z","time spent":"1.297123106s","remote":"127.0.0.1:51454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:06:29.047272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.729071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:06:29.047356Z","caller":"traceutil/trace.go:171","msg":"trace[1516084829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"420.812523ms","start":"2026-10-15T14:06:28.626513Z","end":"2026-10-15T14:06:29.047325Z","steps":["trace[1516084829] 'agreement among raft nodes before linearized reading' (duration: 420.493646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:29.047399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:28.626444Z","time spent":"420.944815ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:06:29.047532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.265483ms","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-15T14:06:29.047577Z","caller":"traceutil/trace.go:171","msg":"trace[453921676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"444.370155ms","start":"2026-10-15T14:06:28.603189Z","end":"2026-10-15T14:06:29.047559Z","steps":["trace[453921676] 'agreement among raft nodes before linearized reading' (duration: 444.182482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:29.047604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:28.603112Z","time spent":"444.485907ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T14:06:29.223772Z","caller":"traceutil/trace.go:171","msg":"trace[1097243110] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"171.508029ms","start":"2026-10-15T14:06:29.052234Z","end":"2026-10-15T14:06:29.223742Z","steps":["trace[1097243110] 'process raft request' (duration: 96.422879ms)","trace[1097243110] 'compare' (duration: 74.834745ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:06:29.224157Z","caller":"traceutil/trace.go:171","msg":"trace[453346031] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"167.926609ms","start":"2026-10-15T14:06:29.056215Z","end":"2026-10-15T14:06:29.224142Z","steps":["trace[453346031] 'process raft request' (duration: 167.46082ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:29.224297Z","caller":"traceutil/trace.go:171","msg":"trace[556672953] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"171.070641ms","start":"2026-10-15T14:06:29.0532Z","end":"2026-10-15T14:06:29.224271Z","steps":["trace[556672953] 'process raft request' (duration: 170.742704ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:29.224366Z","caller":"traceutil/trace.go:171","msg":"trace[801649498] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"155.18165ms","start":"2026-10-15T14:06:29.069153Z","end":"2026-10-15T14:06:29.224335Z","steps":["trace[801649498] 'process raft request' (duration: 155.015427ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:06:29.974626Z","caller":"traceutil/trace.go:171","msg":"trace[238421527] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"306.700318ms","start":"2026-10-15T14:06:29.667902Z","end":"2026-10-15T14:06:29.974602Z","steps":["trace[238421527] 'process raft request' (duration: 296.532379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:06:29.974808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:06:29.667873Z","time spent":"306.818619ms","remote":"127.0.0.1:51454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:06:45.829019Z","caller":"traceutil/trace.go:171","msg":"trace[403142293] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"196.122919ms","start":"2026-10-15T14:06:45.632818Z","end":"2026-10-15T14:06:45.828941Z","steps":["trace[403142293] 'process raft request' (duration: 195.911915ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:07:29.560689Z","caller":"traceutil/trace.go:171","msg":"trace[1868084844] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"106.168708ms","start":"2026-10-15T14:07:29.454488Z","end":"2026-10-15T14:07:29.560657Z","steps":["trace[1868084844] 'process raft request' (duration: 27.910071ms)","trace[1868084844] 'compare' (duration: 78.064203ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:07:29.562647Z","caller":"traceutil/trace.go:171","msg":"trace[1560292896] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"107.069734ms","start":"2026-10-15T14:07:29.455528Z","end":"2026-10-15T14:07:29.562598Z","steps":["trace[1560292896] 'process raft request' (duration: 106.659447ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:07:29.562733Z","caller":"traceutil/trace.go:171","msg":"trace[655980117] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"106.432413ms","start":"2026-10-15T14:07:29.456157Z","end":"2026-10-15T14:07:29.562589Z","steps":["trace[655980117] 'process raft request' (duration: 106.155068ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:07:58.961775Z","caller":"traceutil/trace.go:171","msg":"trace[1914844696] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"102.330453ms","start":"2026-10-15T14:07:58.859416Z","end":"2026-10-15T14:07:58.961746Z","steps":["trace[1914844696] 'process raft request' (duration: 102.1497ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:07:59.013197Z","caller":"traceutil/trace.go:171","msg":"trace[1262277997] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"150.02234ms","start":"2026-10-15T14:07:58.863134Z","end":"2026-10-15T14:07:59.013156Z","steps":["trace[1262277997] 'process raft request' (duration: 131.297019ms)","trace[1262277997] 'compare' (duration: 18.588538ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:10.127396Z","caller":"traceutil/trace.go:171","msg":"trace[1333955168] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1990; }","duration":"215.463612ms","start":"2026-10-15T14:08:09.911905Z","end":"2026-10-15T14:08:10.127368Z","steps":["trace[1333955168] 'read index received' (duration: 215.105056ms)","trace[1333955168] 'applied index is now lower than readState.Index' (duration: 357.256µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:10.12752Z","caller":"traceutil/trace.go:171","msg":"trace[1655069382] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"236.774485ms","start":"2026-10-15T14:08:09.890704Z","end":"2026-10-15T14:08:10.127478Z","steps":["trace[1655069382] 'process raft request' (duration: 236.400318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:10.127676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.751756ms","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-15T14:08:10.127734Z","caller":"traceutil/trace.go:171","msg":"trace[651777520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1911; }","duration":"215.872849ms","start":"2026-10-15T14:08:09.911846Z","end":"2026-10-15T14:08:10.127719Z","steps":["trace[651777520] 'agreement among raft nodes before linearized reading' (duration: 215.677856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:10.127935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.137721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:08:10.127997Z","caller":"traceutil/trace.go:171","msg":"trace[464497319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1911; }","duration":"160.255773ms","start":"2026-10-15T14:08:09.967725Z","end":"2026-10-15T14:08:10.127981Z","steps":["trace[464497319] 'agreement among raft nodes before linearized reading' (duration: 160.04475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:10.128014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.771734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T14:08:10.12806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.227977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15f39174-15c3-438c-aed1-39a0e2d1f3d8\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T14:08:10.128132Z","caller":"traceutil/trace.go:171","msg":"trace[58188643] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15f39174-15c3-438c-aed1-39a0e2d1f3d8; range_end:; response_count:1; response_revision:1911; }","duration":"132.337749ms","start":"2026-10-15T14:08:09.995776Z","end":"2026-10-15T14:08:10.128113Z","steps":["trace[58188643] 'agreement among raft nodes before linearized reading' (duration: 132.152105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:10.128063Z","caller":"traceutil/trace.go:171","msg":"trace[148662354] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1911; }","duration":"116.924536ms","start":"2026-10-15T14:08:10.011122Z","end":"2026-10-15T14:08:10.128047Z","steps":["trace[148662354] 'agreement among raft nodes before linearized reading' (duration: 116.797384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:10.128438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.277889ms","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-15T14:08:10.128501Z","caller":"traceutil/trace.go:171","msg":"trace[1165824083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"155.396691ms","start":"2026-10-15T14:08:09.973089Z","end":"2026-10-15T14:08:10.128485Z","steps":["trace[1165824083] 'agreement among raft nodes before linearized reading' (duration: 155.241118ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:10.256755Z","caller":"traceutil/trace.go:171","msg":"trace[742457865] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"121.401143ms","start":"2026-10-15T14:08:10.135307Z","end":"2026-10-15T14:08:10.256708Z","steps":["trace[742457865] 'process raft request' (duration: 84.75307ms)","trace[742457865] 'compare' (duration: 36.096283ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:10.256713Z","caller":"traceutil/trace.go:171","msg":"trace[1452355005] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"121.296142ms","start":"2026-10-15T14:08:10.135394Z","end":"2026-10-15T14:08:10.25669Z","steps":["trace[1452355005] 'process raft request' (duration: 120.942546ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:10.256926Z","caller":"traceutil/trace.go:171","msg":"trace[1657496102] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"121.392133ms","start":"2026-10-15T14:08:10.13546Z","end":"2026-10-15T14:08:10.256852Z","steps":["trace[1657496102] 'process raft request' (duration: 120.941366ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:10.614946Z","caller":"traceutil/trace.go:171","msg":"trace[784479488] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"159.270416ms","start":"2026-10-15T14:08:10.455627Z","end":"2026-10-15T14:08:10.614897Z","steps":["trace[784479488] 'process raft request' (duration: 159.038622ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:24.515756Z","caller":"traceutil/trace.go:171","msg":"trace[960441006] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2123; }","duration":"162.688545ms","start":"2026-10-15T14:08:24.35303Z","end":"2026-10-15T14:08:24.515718Z","steps":["trace[960441006] 'read index received' (duration: 162.328131ms)","trace[960441006] 'applied index is now lower than readState.Index' (duration: 358.964µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:24.51584Z","caller":"traceutil/trace.go:171","msg":"trace[493423724] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"187.726134ms","start":"2026-10-15T14:08:24.328071Z","end":"2026-10-15T14:08:24.515797Z","steps":["trace[493423724] 'process raft request' (duration: 187.418622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:24.516186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.954706ms","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-15T14:08:24.516247Z","caller":"traceutil/trace.go:171","msg":"trace[1601637817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2040; }","duration":"163.253657ms","start":"2026-10-15T14:08:24.352976Z","end":"2026-10-15T14:08:24.51623Z","steps":["trace[1601637817] 'agreement among raft nodes before linearized reading' (duration: 162.858531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:24.51635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.949877ms","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-15T14:08:24.516416Z","caller":"traceutil/trace.go:171","msg":"trace[644148543] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2040; }","duration":"162.132853ms","start":"2026-10-15T14:08:24.354267Z","end":"2026-10-15T14:08:24.5164Z","steps":["trace[644148543] 'agreement among raft nodes before linearized reading' (duration: 161.688076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:24.516551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.147506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-15T14:08:24.516621Z","caller":"traceutil/trace.go:171","msg":"trace[225654274] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2040; }","duration":"114.263101ms","start":"2026-10-15T14:08:24.402338Z","end":"2026-10-15T14:08:24.516601Z","steps":["trace[225654274] 'agreement among raft nodes before linearized reading' (duration: 113.925098ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:44.980339Z","caller":"traceutil/trace.go:171","msg":"trace[675748679] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"128.023176ms","start":"2026-10-15T14:08:44.85228Z","end":"2026-10-15T14:08:44.980304Z","steps":["trace[675748679] 'read index received' (duration: 127.715125ms)","trace[675748679] 'applied index is now lower than readState.Index' (duration: 306.611µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:44.980519Z","caller":"traceutil/trace.go:171","msg":"trace[2119682414] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"153.166646ms","start":"2026-10-15T14:08:44.827336Z","end":"2026-10-15T14:08:44.980503Z","steps":["trace[2119682414] 'process raft request' (duration: 152.74142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:44.980753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.457141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:08:44.980847Z","caller":"traceutil/trace.go:171","msg":"trace[125671966] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2162; }","duration":"128.624677ms","start":"2026-10-15T14:08:44.852202Z","end":"2026-10-15T14:08:44.980826Z","steps":["trace[125671966] 'agreement among raft nodes before linearized reading' (duration: 128.467732ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:08:45.895827Z","caller":"traceutil/trace.go:171","msg":"trace[1597427096] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"329.213349ms","start":"2026-10-15T14:08:45.566586Z","end":"2026-10-15T14:08:45.8958Z","steps":["trace[1597427096] 'read index received' (duration: 329.004812ms)","trace[1597427096] 'applied index is now lower than readState.Index' (duration: 207.597µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:45.895975Z","caller":"traceutil/trace.go:171","msg":"trace[1337103278] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"374.478295ms","start":"2026-10-15T14:08:45.521472Z","end":"2026-10-15T14:08:45.89595Z","steps":["trace[1337103278] 'process raft request' (duration: 374.118012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:45.896016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.397205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:08:45.896065Z","caller":"traceutil/trace.go:171","msg":"trace[1972919124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2165; }","duration":"329.52078ms","start":"2026-10-15T14:08:45.566529Z","end":"2026-10-15T14:08:45.89605Z","steps":["trace[1972919124] 'agreement among raft nodes before linearized reading' (duration: 329.377175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:45.896108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:08:45.566499Z","time spent":"329.594873ms","remote":"127.0.0.1:51358","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-15T14:08:45.896113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:08:45.521446Z","time spent":"374.589679ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:08:46.032812Z","caller":"traceutil/trace.go:171","msg":"trace[882436822] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"135.763171ms","start":"2026-10-15T14:08:45.897026Z","end":"2026-10-15T14:08:46.032789Z","steps":["trace[882436822] 'read index received' (duration: 114.983323ms)","trace[882436822] 'applied index is now lower than readState.Index' (duration: 20.779178ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:46.032863Z","caller":"traceutil/trace.go:171","msg":"trace[214648004] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"452.966301ms","start":"2026-10-15T14:08:45.579876Z","end":"2026-10-15T14:08:46.032843Z","steps":["trace[214648004] 'process raft request' (duration: 432.192193ms)","trace[214648004] 'compare' (duration: 20.570341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:08:46.032922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.882275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:08:46.032953Z","caller":"traceutil/trace.go:171","msg":"trace[767512084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"135.959448ms","start":"2026-10-15T14:08:45.896985Z","end":"2026-10-15T14:08:46.032944Z","steps":["trace[767512084] 'agreement among raft nodes before linearized reading' (duration: 135.904216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:46.032969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:08:45.579855Z","time spent":"453.051575ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:08:46.545292Z","caller":"traceutil/trace.go:171","msg":"trace[913370813] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2255; }","duration":"168.120968ms","start":"2026-10-15T14:08:46.377144Z","end":"2026-10-15T14:08:46.545265Z","steps":["trace[913370813] 'read index received' (duration: 167.90575ms)","trace[913370813] 'applied index is now lower than readState.Index' (duration: 214.367µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:08:46.545371Z","caller":"traceutil/trace.go:171","msg":"trace[2109112015] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"197.354117ms","start":"2026-10-15T14:08:46.34799Z","end":"2026-10-15T14:08:46.545344Z","steps":["trace[2109112015] 'process raft request' (duration: 197.130659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:08:46.545521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.361187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T14:08:46.545579Z","caller":"traceutil/trace.go:171","msg":"trace[709558378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2167; }","duration":"168.4744ms","start":"2026-10-15T14:08:46.377089Z","end":"2026-10-15T14:08:46.545564Z","steps":["trace[709558378] 'agreement among raft nodes before linearized reading' (duration: 168.287864ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:49.872203Z","caller":"traceutil/trace.go:171","msg":"trace[358218723] linearizableReadLoop","detail":"{readStateIndex:2712; appliedIndex:2711; }","duration":"171.448093ms","start":"2026-10-15T14:09:49.700734Z","end":"2026-10-15T14:09:49.872182Z","steps":["trace[358218723] 'read index received' (duration: 171.275068ms)","trace[358218723] 'applied index is now lower than readState.Index' (duration: 172.265µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:09:49.872295Z","caller":"traceutil/trace.go:171","msg":"trace[89601160] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"229.399317ms","start":"2026-10-15T14:09:49.642873Z","end":"2026-10-15T14:09:49.872273Z","steps":["trace[89601160] 'process raft request' (duration: 229.197761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:49.87249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.69274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:09:49.872548Z","caller":"traceutil/trace.go:171","msg":"trace[1812525826] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2608; }","duration":"171.822843ms","start":"2026-10-15T14:09:49.700696Z","end":"2026-10-15T14:09:49.872519Z","steps":["trace[1812525826] 'agreement among raft nodes before linearized reading' (duration: 171.634438ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:10:22.232571Z","caller":"traceutil/trace.go:171","msg":"trace[1686546552] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"200.367189ms","start":"2026-10-15T14:10:22.03217Z","end":"2026-10-15T14:10:22.232537Z","steps":["trace[1686546552] 'process raft request' (duration: 200.198754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:22.628974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.928287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:10:22.629046Z","caller":"traceutil/trace.go:171","msg":"trace[371489255] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2787; }","duration":"280.05004ms","start":"2026-10-15T14:10:22.348978Z","end":"2026-10-15T14:10:22.629028Z","steps":["trace[371489255] 'count revisions from in-memory index tree' (duration: 279.846265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:22.629896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.834927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:10:22.630037Z","caller":"traceutil/trace.go:171","msg":"trace[1144541426] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"229.645002ms","start":"2026-10-15T14:10:22.400371Z","end":"2026-10-15T14:10:22.630016Z","steps":["trace[1144541426] 'process raft request' (duration: 51.474866ms)","trace[1144541426] 'compare' (duration: 177.175209ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:10:22.629992Z","caller":"traceutil/trace.go:171","msg":"trace[2120300375] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2897; }","duration":"122.955699ms","start":"2026-10-15T14:10:22.507021Z","end":"2026-10-15T14:10:22.629977Z","steps":["trace[2120300375] 'read index received' (duration: 61.522µs)","trace[2120300375] 'applied index is now lower than readState.Index' (duration: 122.892987ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:10:22.630356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.329248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T14:10:22.630436Z","caller":"traceutil/trace.go:171","msg":"trace[418196076] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2788; }","duration":"123.451232ms","start":"2026-10-15T14:10:22.50697Z","end":"2026-10-15T14:10:22.630421Z","steps":["trace[418196076] 'agreement among raft nodes before linearized reading' (duration: 123.194395ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.408783Z","caller":"traceutil/trace.go:171","msg":"trace[265989352] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"136.722904ms","start":"2026-10-15T14:14:51.272024Z","end":"2026-10-15T14:14:51.408747Z","steps":["trace[265989352] 'process raft request' (duration: 124.073983ms)","trace[265989352] 'compare' (duration: 12.325555ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:51.420301Z","caller":"traceutil/trace.go:171","msg":"trace[252267424] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"129.011892ms","start":"2026-10-15T14:14:51.291259Z","end":"2026-10-15T14:14:51.420271Z","steps":["trace[252267424] 'process raft request' (duration: 128.200505ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:52.489995Z","caller":"traceutil/trace.go:171","msg":"trace[1026986218] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4954; }","duration":"138.375466ms","start":"2026-10-15T14:14:52.351603Z","end":"2026-10-15T14:14:52.489979Z","steps":["trace[1026986218] 'read index received' (duration: 80.767313ms)","trace[1026986218] 'applied index is now lower than readState.Index' (duration: 57.607383ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:52.490141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.517739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-10-15T14:14:52.490168Z","caller":"traceutil/trace.go:171","msg":"trace[1192693997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4766; }","duration":"138.57075ms","start":"2026-10-15T14:14:52.351589Z","end":"2026-10-15T14:14:52.49016Z","steps":["trace[1192693997] 'agreement among raft nodes before linearized reading' (duration: 138.462258ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:52.490576Z","caller":"traceutil/trace.go:171","msg":"trace[689111824] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"138.551159ms","start":"2026-10-15T14:14:52.351465Z","end":"2026-10-15T14:14:52.490016Z","steps":["trace[689111824] 'process raft request' (duration: 80.891205ms)","trace[689111824] 'compare' (duration: 57.511291ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:52.492025Z","caller":"traceutil/trace.go:171","msg":"trace[281215117] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"135.950717ms","start":"2026-10-15T14:14:52.356063Z","end":"2026-10-15T14:14:52.492014Z","steps":["trace[281215117] 'process raft request' (duration: 135.902937ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:52.492169Z","caller":"traceutil/trace.go:171","msg":"trace[334615501] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"139.456307ms","start":"2026-10-15T14:14:52.352702Z","end":"2026-10-15T14:14:52.492159Z","steps":["trace[334615501] 'process raft request' (duration: 139.196922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:52.500877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.1374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:52.500942Z","caller":"traceutil/trace.go:171","msg":"trace[767216509] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4770; }","duration":"143.230462ms","start":"2026-10-15T14:14:52.357696Z","end":"2026-10-15T14:14:52.500927Z","steps":["trace[767216509] 'agreement among raft nodes before linearized reading' (duration: 143.134761ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:00.730147Z","caller":"traceutil/trace.go:171","msg":"trace[374550186] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"124.456273ms","start":"2026-10-15T14:15:00.605663Z","end":"2026-10-15T14:15:00.73012Z","steps":["trace[374550186] 'process raft request' (duration: 99.236474ms)","trace[374550186] 'compare' (duration: 24.958953ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:15:00.730376Z","caller":"traceutil/trace.go:171","msg":"trace[382145991] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"124.397461ms","start":"2026-10-15T14:15:00.60595Z","end":"2026-10-15T14:15:00.730347Z","steps":["trace[382145991] 'process raft request' (duration: 124.063324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:07.156144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.062966ms","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-15T14:15:07.156243Z","caller":"traceutil/trace.go:171","msg":"trace[120919747] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5039; }","duration":"137.203878ms","start":"2026-10-15T14:15:07.01902Z","end":"2026-10-15T14:15:07.156224Z","steps":["trace[120919747] 'range keys from in-memory index tree' (duration: 136.919193ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:07.274536Z","caller":"traceutil/trace.go:171","msg":"trace[1636977669] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"189.458879ms","start":"2026-10-15T14:15:07.085058Z","end":"2026-10-15T14:15:07.274517Z","steps":["trace[1636977669] 'process raft request' (duration: 189.341357ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:07.442786Z","caller":"traceutil/trace.go:171","msg":"trace[1478653121] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5236; }","duration":"267.368208ms","start":"2026-10-15T14:15:07.175393Z","end":"2026-10-15T14:15:07.442761Z","steps":["trace[1478653121] 'read index received' (duration: 99.046595ms)","trace[1478653121] 'applied index is now lower than readState.Index' (duration: 168.320682ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:15:07.443102Z","caller":"traceutil/trace.go:171","msg":"trace[1851852587] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"281.470206ms","start":"2026-10-15T14:15:07.161618Z","end":"2026-10-15T14:15:07.443088Z","steps":["trace[1851852587] 'process raft request' (duration: 214.072196ms)","trace[1851852587] 'compare' (duration: 66.963081ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:15:07.444342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.894238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da4570fb-c1e4-4bd7-b04f-6b1479279be1\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-15T14:15:07.444403Z","caller":"traceutil/trace.go:171","msg":"trace[1699958971] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da4570fb-c1e4-4bd7-b04f-6b1479279be1; range_end:; response_count:1; response_revision:5041; }","duration":"269.005001ms","start":"2026-10-15T14:15:07.17538Z","end":"2026-10-15T14:15:07.444385Z","steps":["trace[1699958971] 'agreement among raft nodes before linearized reading' (duration: 268.785666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:07.444634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.850371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:15:07.444681Z","caller":"traceutil/trace.go:171","msg":"trace[1635487446] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5041; }","duration":"252.943033ms","start":"2026-10-15T14:15:07.191724Z","end":"2026-10-15T14:15:07.444667Z","steps":["trace[1635487446] 'agreement among raft nodes before linearized reading' (duration: 252.870622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:07.445645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.195126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da4570fb-c1e4-4bd7-b04f-6b1479279be1\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-10-15T14:15:07.445674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.046193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-76664cfd89-gvhbz.18deb98a7e1e552a\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-10-15T14:15:07.445753Z","caller":"traceutil/trace.go:171","msg":"trace[1847281002] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-76664cfd89-gvhbz.18deb98a7e1e552a; range_end:; response_count:1; response_revision:5041; }","duration":"221.160656ms","start":"2026-10-15T14:15:07.224574Z","end":"2026-10-15T14:15:07.445735Z","steps":["trace[1847281002] 'agreement among raft nodes before linearized reading' (duration: 220.916042ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:07.445703Z","caller":"traceutil/trace.go:171","msg":"trace[825800434] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da4570fb-c1e4-4bd7-b04f-6b1479279be1; range_end:; response_count:1; response_revision:5041; }","duration":"222.287628ms","start":"2026-10-15T14:15:07.223401Z","end":"2026-10-15T14:15:07.445688Z","steps":["trace[825800434] 'agreement among raft nodes before linearized reading' (duration: 222.157016ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:07.74809Z","caller":"traceutil/trace.go:171","msg":"trace[689415808] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"117.200033ms","start":"2026-10-15T14:15:07.630867Z","end":"2026-10-15T14:15:07.748067Z","steps":["trace[689415808] 'process raft request' (duration: 116.793015ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:08.102474Z","caller":"traceutil/trace.go:171","msg":"trace[554182887] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"112.771616ms","start":"2026-10-15T14:15:07.989675Z","end":"2026-10-15T14:15:08.102446Z","steps":["trace[554182887] 'process raft request' (duration: 112.566362ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:11.352255Z","caller":"traceutil/trace.go:171","msg":"trace[1702321387] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"106.187054ms","start":"2026-10-15T14:15:11.246035Z","end":"2026-10-15T14:15:11.352222Z","steps":["trace[1702321387] 'process raft request' (duration: 62.124025ms)","trace[1702321387] 'compare' (duration: 43.849195ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:15:12.061819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.981834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:15:12.061976Z","caller":"traceutil/trace.go:171","msg":"trace[2001574941] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5113; }","duration":"159.184688ms","start":"2026-10-15T14:15:11.902774Z","end":"2026-10-15T14:15:12.061959Z","steps":["trace[2001574941] 'count revisions from in-memory index tree' (duration: 158.912072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.425486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906287335629455989,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T14:15:22.565016Z","caller":"traceutil/trace.go:171","msg":"trace[769679317] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"663.873724ms","start":"2026-10-15T14:15:21.901122Z","end":"2026-10-15T14:15:22.564996Z","steps":["trace[769679317] 'process raft request' (duration: 663.773652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.565134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:15:21.901103Z","time spent":"663.979766ms","remote":"127.0.0.1:51438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:15:22.570778Z","caller":"traceutil/trace.go:171","msg":"trace[1238720885] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"630.936056ms","start":"2026-10-15T14:15:21.939818Z","end":"2026-10-15T14:15:22.570754Z","steps":["trace[1238720885] 'process raft request' (duration: 630.690591ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.570848Z","caller":"traceutil/trace.go:171","msg":"trace[1724679798] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"618.811218ms","start":"2026-10-15T14:15:21.952023Z","end":"2026-10-15T14:15:22.570834Z","steps":["trace[1724679798] 'process raft request' (duration: 618.663855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.570917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:15:21.939801Z","time spent":"631.034578ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:15:22.570936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:15:21.951998Z","time spent":"618.90804ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:15:22.571037Z","caller":"traceutil/trace.go:171","msg":"trace[1879047282] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"280.077433ms","start":"2026-10-15T14:15:22.290925Z","end":"2026-10-15T14:15:22.571003Z","steps":["trace[1879047282] 'process raft request' (duration: 279.873609ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.576924Z","caller":"traceutil/trace.go:171","msg":"trace[2040366444] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5422; }","duration":"652.275796ms","start":"2026-10-15T14:15:21.924615Z","end":"2026-10-15T14:15:22.576891Z","steps":["trace[2040366444] 'read index received' (duration: 640.354252ms)","trace[2040366444] 'applied index is now lower than readState.Index' (duration: 11.919964ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:15:22.577185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.116305ms","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":"warn","ts":"2026-10-15T14:15:22.577199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.261918ms","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":"info","ts":"2026-10-15T14:15:22.577284Z","caller":"traceutil/trace.go:171","msg":"trace[1098672309] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5223; }","duration":"390.256587ms","start":"2026-10-15T14:15:22.187006Z","end":"2026-10-15T14:15:22.577263Z","steps":["trace[1098672309] 'agreement among raft nodes before linearized reading' (duration: 390.057543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.577278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.807176ms","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-15T14:15:22.577315Z","caller":"traceutil/trace.go:171","msg":"trace[1963322729] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:5223; }","duration":"106.454742ms","start":"2026-10-15T14:15:22.470835Z","end":"2026-10-15T14:15:22.57729Z","steps":["trace[1963322729] 'agreement among raft nodes before linearized reading' (duration: 106.275328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.577325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.697304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:15:22.57737Z","caller":"traceutil/trace.go:171","msg":"trace[1774808405] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5223; }","duration":"140.97583ms","start":"2026-10-15T14:15:22.436375Z","end":"2026-10-15T14:15:22.577351Z","steps":["trace[1774808405] 'agreement among raft nodes before linearized reading' (duration: 140.809377ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.577392Z","caller":"traceutil/trace.go:171","msg":"trace[896811636] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:5223; }","duration":"652.798176ms","start":"2026-10-15T14:15:21.924573Z","end":"2026-10-15T14:15:22.577372Z","steps":["trace[896811636] 'agreement among raft nodes before linearized reading' (duration: 652.684314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.577325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:15:22.186987Z","time spent":"390.327819ms","remote":"127.0.0.1:51626","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-15T14:15:22.577411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.705994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-10-15T14:15:22.577431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:15:21.924562Z","time spent":"652.856717ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-10-15T14:15:22.577442Z","caller":"traceutil/trace.go:171","msg":"trace[604677893] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5223; }","duration":"228.776444ms","start":"2026-10-15T14:15:22.348656Z","end":"2026-10-15T14:15:22.577433Z","steps":["trace[604677893] 'agreement among raft nodes before linearized reading' (duration: 228.689213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:22.577321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.33091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T14:15:22.577648Z","caller":"traceutil/trace.go:171","msg":"trace[1013554499] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5223; }","duration":"189.691626ms","start":"2026-10-15T14:15:22.387942Z","end":"2026-10-15T14:15:22.577633Z","steps":["trace[1013554499] 'agreement among raft nodes before linearized reading' (duration: 189.244488ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.707622Z","caller":"traceutil/trace.go:171","msg":"trace[588134393] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"111.563762ms","start":"2026-10-15T14:15:22.596026Z","end":"2026-10-15T14:15:22.70759Z","steps":["trace[588134393] 'process raft request' (duration: 106.587244ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.842782Z","caller":"traceutil/trace.go:171","msg":"trace[2018388868] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"125.906504ms","start":"2026-10-15T14:15:22.716832Z","end":"2026-10-15T14:15:22.842738Z","steps":["trace[2018388868] 'process raft request' (duration: 91.028858ms)","trace[2018388868] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/services/endpoints/openstack/rabbitmq-keystone; req_size:889; } (duration: 34.715372ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:15:22.843459Z","caller":"traceutil/trace.go:171","msg":"trace[700727459] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"126.606827ms","start":"2026-10-15T14:15:22.716835Z","end":"2026-10-15T14:15:22.843441Z","steps":["trace[700727459] 'process raft request' (duration: 125.850933ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.843703Z","caller":"traceutil/trace.go:171","msg":"trace[1156808255] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"125.628508ms","start":"2026-10-15T14:15:22.717996Z","end":"2026-10-15T14:15:22.843624Z","steps":["trace[1156808255] 'process raft request' (duration: 124.922674ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:34.097288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-10-15T14:15:34.338845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"240.179163ms","hash":2726359889,"current-db-size-bytes":52342784,"current-db-size":"52 MB","current-db-size-in-use-bytes":28196864,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:15:34.338994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2726359889,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:16:07.070454Z","caller":"traceutil/trace.go:171","msg":"trace[1268211] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"306.071027ms","start":"2026-10-15T14:16:06.764351Z","end":"2026-10-15T14:16:07.070422Z","steps":["trace[1268211] 'process raft request' (duration: 305.882913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:07.070822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:06.764336Z","time spent":"306.208119ms","remote":"127.0.0.1:45674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:16:07.217642Z","caller":"traceutil/trace.go:171","msg":"trace[2002122396] linearizableReadLoop","detail":"{readStateIndex:5816; appliedIndex:5814; }","duration":"380.006793ms","start":"2026-10-15T14:16:06.837603Z","end":"2026-10-15T14:16:07.21761Z","steps":["trace[2002122396] 'read index received' (duration: 232.6276ms)","trace[2002122396] 'applied index is now lower than readState.Index' (duration: 147.377993ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:07.217731Z","caller":"traceutil/trace.go:171","msg":"trace[872151100] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"448.86851ms","start":"2026-10-15T14:16:06.768826Z","end":"2026-10-15T14:16:07.217695Z","steps":["trace[872151100] 'process raft request' (duration: 386.393067ms)","trace[872151100] 'compare' (duration: 62.261799ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:16:07.217953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:06.768812Z","time spent":"449.040693ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:16:07.218148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.438391ms","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-15T14:16:07.218291Z","caller":"traceutil/trace.go:171","msg":"trace[89474252] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5602; }","duration":"380.705486ms","start":"2026-10-15T14:16:06.837558Z","end":"2026-10-15T14:16:07.218264Z","steps":["trace[89474252] 'agreement among raft nodes before linearized reading' (duration: 380.223777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:07.218336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.109724ms","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-15T14:16:07.21841Z","caller":"traceutil/trace.go:171","msg":"trace[429411615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5602; }","duration":"153.196155ms","start":"2026-10-15T14:16:07.065174Z","end":"2026-10-15T14:16:07.21837Z","steps":["trace[429411615] 'agreement among raft nodes before linearized reading' (duration: 153.003022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:07.218348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:06.837543Z","time spent":"380.787398ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T14:20:34.103616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5317} {"level":"info","ts":"2026-10-15T14:20:34.184605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5317,"took":"79.469459ms","hash":1020695343,"current-db-size-bytes":52342784,"current-db-size":"52 MB","current-db-size-in-use-bytes":19038208,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-15T14:20:34.18467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1020695343,"revision":5317,"compact-revision":2849}