{"level":"warn","ts":"2026-09-29T03:51:57.749006Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-29T03:51:57.749503Z","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-09-29T03:51:57.749671Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-29T03:51:57.749715Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-09-29T03:51:57.749986Z","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-09-29T03:51:57.752103Z","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-09-29T03:51:57.752266Z","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-09-29T03:51:57.755349Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.701257ms"} {"level":"info","ts":"2026-09-29T03:51:57.761916Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-09-29T03:51:57.762018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T03:51:57.762043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-09-29T03:51:57.762062Z","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-09-29T03:51:57.762084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-09-29T03:51:57.762135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-09-29T03:51:57.769787Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T03:51:57.771766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T03:51:57.773259Z","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-09-29T03:51:57.77562Z","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-09-29T03:51:57.775754Z","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-09-29T03:51:57.775824Z","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-09-29T03:51:57.775848Z","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-09-29T03:51:57.775856Z","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-09-29T03:51:57.778072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-09-29T03:51:57.778304Z","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-09-29T03:51:57.780298Z","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-09-29T03:51:57.780432Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-09-29T03:51:57.780512Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-09-29T03:51:57.780628Z","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-09-29T03:51:57.780678Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T03:51:58.462945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T03:51:58.463002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T03:51:58.463022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-09-29T03:51:58.463039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-09-29T03:51:58.463049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-09-29T03:51:58.463063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-09-29T03:51:58.463076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-09-29T03:51:58.464518Z","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-09-29T03:51:58.464584Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:51:58.464747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:51:58.464779Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:51:58.464843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T03:51:58.464861Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-29T03:51:58.466146Z","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-09-29T03:51:58.466265Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:51:58.466305Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:51:58.466819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-29T03:51:58.468152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"warn","ts":"2026-09-29T03:52:14.526966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.373851ms","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-09-29T03:52:14.527117Z","caller":"traceutil/trace.go:171","msg":"trace[280904826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"143.575156ms","start":"2026-09-29T03:52:14.383519Z","end":"2026-09-29T03:52:14.527095Z","steps":["trace[280904826] 'range keys from in-memory index tree' (duration: 143.248187ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:52:14.637877Z","caller":"traceutil/trace.go:171","msg":"trace[715755149] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"106.765396ms","start":"2026-09-29T03:52:14.531092Z","end":"2026-09-29T03:52:14.637857Z","steps":["trace[715755149] 'read index received' (duration: 106.744555ms)","trace[715755149] 'applied index is now lower than readState.Index' (duration: 20.151µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:52:14.638024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.909887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:52:14.637993Z","caller":"traceutil/trace.go:171","msg":"trace[135772525] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"106.9131ms","start":"2026-09-29T03:52:14.531043Z","end":"2026-09-29T03:52:14.637956Z","steps":["trace[135772525] 'process raft request' (duration: 106.710754ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:52:14.638059Z","caller":"traceutil/trace.go:171","msg":"trace[196762537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"101.971599ms","start":"2026-09-29T03:52:14.536074Z","end":"2026-09-29T03:52:14.638045Z","steps":["trace[196762537] 'agreement among raft nodes before linearized reading' (duration: 101.916198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:52:14.638046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.946511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-09-29T03:52:14.6381Z","caller":"traceutil/trace.go:171","msg":"trace[969300116] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:409; }","duration":"107.029213ms","start":"2026-09-29T03:52:14.531056Z","end":"2026-09-29T03:52:14.638086Z","steps":["trace[969300116] 'agreement among raft nodes before linearized reading' (duration: 106.92821ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:52:15.060539Z","caller":"traceutil/trace.go:171","msg":"trace[817880955] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"413.832255ms","start":"2026-09-29T03:52:14.646678Z","end":"2026-09-29T03:52:15.06051Z","steps":["trace[817880955] 'process raft request' (duration: 413.64569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:52:15.061241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:52:14.646656Z","time spent":"413.969639ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T03:52:46.153481Z","caller":"traceutil/trace.go:171","msg":"trace[1675812890] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"163.339568ms","start":"2026-09-29T03:52:45.990113Z","end":"2026-09-29T03:52:46.153452Z","steps":["trace[1675812890] 'process raft request' (duration: 162.436493ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:52:46.171869Z","caller":"traceutil/trace.go:171","msg":"trace[770243754] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"134.497661ms","start":"2026-09-29T03:52:46.037348Z","end":"2026-09-29T03:52:46.171845Z","steps":["trace[770243754] 'read index received' (duration: 114.978583ms)","trace[770243754] 'applied index is now lower than readState.Index' (duration: 19.518168ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:52:46.172071Z","caller":"traceutil/trace.go:171","msg":"trace[420286894] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"168.950466ms","start":"2026-09-29T03:52:46.003079Z","end":"2026-09-29T03:52:46.17203Z","steps":["trace[420286894] 'process raft request' (duration: 168.656598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:52:46.172115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.744257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:52:46.172342Z","caller":"traceutil/trace.go:171","msg":"trace[1280210557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"135.038375ms","start":"2026-09-29T03:52:46.037294Z","end":"2026-09-29T03:52:46.172332Z","steps":["trace[1280210557] 'agreement among raft nodes before linearized reading' (duration: 134.771358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:52:46.611872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.055011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:52:46.612022Z","caller":"traceutil/trace.go:171","msg":"trace[1860158507] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"384.332253ms","start":"2026-09-29T03:52:46.227648Z","end":"2026-09-29T03:52:46.61198Z","steps":["trace[1860158507] 'read index received' (duration: 271.747977ms)","trace[1860158507] 'applied index is now lower than readState.Index' (duration: 112.582776ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:52:46.612067Z","caller":"traceutil/trace.go:171","msg":"trace[1060820617] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"385.398653ms","start":"2026-09-29T03:52:46.22662Z","end":"2026-09-29T03:52:46.612019Z","steps":["trace[1060820617] 'process raft request' (duration: 272.858678ms)","trace[1060820617] 'compare' (duration: 111.836145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:52:46.612241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:52:46.226589Z","time spent":"385.571398ms","remote":"127.0.0.1:59572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T03:52:46.612236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.589621ms","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-09-29T03:52:46.61229Z","caller":"traceutil/trace.go:171","msg":"trace[100857958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"384.678823ms","start":"2026-09-29T03:52:46.227601Z","end":"2026-09-29T03:52:46.61228Z","steps":["trace[100857958] 'agreement among raft nodes before linearized reading' (duration: 384.447017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:52:46.612322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:52:46.227585Z","time spent":"384.728334ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-29T03:53:32.039806Z","caller":"traceutil/trace.go:171","msg":"trace[1807874241] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"187.140396ms","start":"2026-09-29T03:53:31.852642Z","end":"2026-09-29T03:53:32.039782Z","steps":["trace[1807874241] 'process raft request' (duration: 185.323645ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:54:21.458598Z","caller":"traceutil/trace.go:171","msg":"trace[378754063] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"191.074546ms","start":"2026-09-29T03:54:21.267502Z","end":"2026-09-29T03:54:21.458577Z","steps":["trace[378754063] 'read index received' (duration: 190.85792ms)","trace[378754063] 'applied index is now lower than readState.Index' (duration: 215.676µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:54:21.458665Z","caller":"traceutil/trace.go:171","msg":"trace[901447105] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"259.143204ms","start":"2026-09-29T03:54:21.199472Z","end":"2026-09-29T03:54:21.458616Z","steps":["trace[901447105] 'process raft request' (duration: 258.970259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:54:21.458858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.48879ms","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-09-29T03:54:21.458903Z","caller":"traceutil/trace.go:171","msg":"trace[1772766037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1895; }","duration":"149.602074ms","start":"2026-09-29T03:54:21.309288Z","end":"2026-09-29T03:54:21.45889Z","steps":["trace[1772766037] 'agreement among raft nodes before linearized reading' (duration: 149.456489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:54:21.458918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.395084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-29T03:54:21.459026Z","caller":"traceutil/trace.go:171","msg":"trace[1565569604] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1895; }","duration":"191.550819ms","start":"2026-09-29T03:54:21.267449Z","end":"2026-09-29T03:54:21.459Z","steps":["trace[1565569604] 'agreement among raft nodes before linearized reading' (duration: 191.299142ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:54:21.773724Z","caller":"traceutil/trace.go:171","msg":"trace[1231182097] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"272.773386ms","start":"2026-09-29T03:54:21.5009Z","end":"2026-09-29T03:54:21.773674Z","steps":["trace[1231182097] 'process raft request' (duration: 272.621582ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:55:10.819583Z","caller":"traceutil/trace.go:171","msg":"trace[1916427924] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"127.36327ms","start":"2026-09-29T03:55:10.691545Z","end":"2026-09-29T03:55:10.818908Z","steps":["trace[1916427924] 'process raft request' (duration: 126.523157ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:55:10.825833Z","caller":"traceutil/trace.go:171","msg":"trace[1708277465] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"132.767241ms","start":"2026-09-29T03:55:10.693031Z","end":"2026-09-29T03:55:10.825799Z","steps":["trace[1708277465] 'process raft request' (duration: 132.476933ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:40.445611Z","caller":"traceutil/trace.go:171","msg":"trace[1168543085] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"127.995237ms","start":"2026-09-29T03:56:40.317303Z","end":"2026-09-29T03:56:40.445299Z","steps":["trace[1168543085] 'process raft request' (duration: 35.741521ms)","trace[1168543085] 'compare' (duration: 91.992049ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:56:40.497367Z","caller":"traceutil/trace.go:171","msg":"trace[741332074] transaction","detail":"{read_only:false; response_revision:2898; number_of_response:1; }","duration":"176.514397ms","start":"2026-09-29T03:56:40.320799Z","end":"2026-09-29T03:56:40.497313Z","steps":["trace[741332074] 'process raft request' (duration: 176.25455ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:40.497405Z","caller":"traceutil/trace.go:171","msg":"trace[34128362] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"172.812594ms","start":"2026-09-29T03:56:40.324573Z","end":"2026-09-29T03:56:40.497386Z","steps":["trace[34128362] 'process raft request' (duration: 172.623709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:59:42.009218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.79582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-29T03:59:42.009242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.865165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p8jpj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-29T03:59:42.009457Z","caller":"traceutil/trace.go:171","msg":"trace[1488661252] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p8jpj; range_end:; response_count:1; response_revision:4055; }","duration":"102.117073ms","start":"2026-09-29T03:59:41.907309Z","end":"2026-09-29T03:59:42.009426Z","steps":["trace[1488661252] 'range keys from in-memory index tree' (duration: 101.660379ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:59:42.009368Z","caller":"traceutil/trace.go:171","msg":"trace[23419860] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4055; }","duration":"118.989156ms","start":"2026-09-29T03:59:41.890327Z","end":"2026-09-29T03:59:42.009316Z","steps":["trace[23419860] 'range keys from in-memory index tree' (duration: 118.673506ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:14.150094Z","caller":"traceutil/trace.go:171","msg":"trace[714317394] linearizableReadLoop","detail":"{readStateIndex:5344; appliedIndex:5343; }","duration":"127.851544ms","start":"2026-09-29T04:01:14.022211Z","end":"2026-09-29T04:01:14.150063Z","steps":["trace[714317394] 'read index received' (duration: 41.404681ms)","trace[714317394] 'applied index is now lower than readState.Index' (duration: 86.445493ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:01:14.150255Z","caller":"traceutil/trace.go:171","msg":"trace[175990812] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"136.861376ms","start":"2026-09-29T04:01:14.013379Z","end":"2026-09-29T04:01:14.15024Z","steps":["trace[175990812] 'process raft request' (duration: 50.31639ms)","trace[175990812] 'compare' (duration: 86.199387ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:01:14.150619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.382239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-29T04:01:14.150726Z","caller":"traceutil/trace.go:171","msg":"trace[768865053] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5152; }","duration":"128.540703ms","start":"2026-09-29T04:01:14.022165Z","end":"2026-09-29T04:01:14.150706Z","steps":["trace[768865053] 'agreement among raft nodes before linearized reading' (duration: 128.251505ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:16.813828Z","caller":"traceutil/trace.go:171","msg":"trace[707599504] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"164.347436ms","start":"2026-09-29T04:01:16.649447Z","end":"2026-09-29T04:01:16.813794Z","steps":["trace[707599504] 'process raft request' (duration: 164.040617ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:18.996262Z","caller":"traceutil/trace.go:171","msg":"trace[780415877] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"155.53526ms","start":"2026-09-29T04:01:18.840697Z","end":"2026-09-29T04:01:18.996232Z","steps":["trace[780415877] 'process raft request' (duration: 155.352615ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:19.196282Z","caller":"traceutil/trace.go:171","msg":"trace[1449824260] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"354.564308ms","start":"2026-09-29T04:01:18.841691Z","end":"2026-09-29T04:01:19.196255Z","steps":["trace[1449824260] 'process raft request' (duration: 354.373212ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:19.196506Z","caller":"traceutil/trace.go:171","msg":"trace[942015173] linearizableReadLoop","detail":"{readStateIndex:5500; appliedIndex:5498; }","duration":"159.516481ms","start":"2026-09-29T04:01:19.036951Z","end":"2026-09-29T04:01:19.196467Z","steps":["trace[942015173] 'read index received' (duration: 159.098359ms)","trace[942015173] 'applied index is now lower than readState.Index' (duration: 415.422µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:01:19.196569Z","caller":"traceutil/trace.go:171","msg":"trace[1798891261] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"222.884577ms","start":"2026-09-29T04:01:18.973653Z","end":"2026-09-29T04:01:19.196537Z","steps":["trace[1798891261] 'process raft request' (duration: 222.541937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:01:19.196546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:01:18.841658Z","time spent":"354.798014ms","remote":"127.0.0.1:59472","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-09-29T04:01:19.196756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.776658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T04:01:19.196801Z","caller":"traceutil/trace.go:171","msg":"trace[597092769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5303; }","duration":"159.85264ms","start":"2026-09-29T04:01:19.036936Z","end":"2026-09-29T04:01:19.196788Z","steps":["trace[597092769] 'agreement among raft nodes before linearized reading' (duration: 159.666965ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:32.44683Z","caller":"traceutil/trace.go:171","msg":"trace[1957887487] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"110.580979ms","start":"2026-09-29T04:01:32.336216Z","end":"2026-09-29T04:01:32.446797Z","steps":["trace[1957887487] 'process raft request' (duration: 84.55572ms)","trace[1957887487] 'compare' (duration: 25.829594ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:01:32.475183Z","caller":"traceutil/trace.go:171","msg":"trace[1314736182] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"138.906064ms","start":"2026-09-29T04:01:32.336248Z","end":"2026-09-29T04:01:32.475154Z","steps":["trace[1314736182] 'process raft request' (duration: 138.735689ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:45.271973Z","caller":"traceutil/trace.go:171","msg":"trace[2011016570] transaction","detail":"{read_only:false; response_revision:5797; number_of_response:1; }","duration":"105.020734ms","start":"2026-09-29T04:01:45.166917Z","end":"2026-09-29T04:01:45.271938Z","steps":["trace[2011016570] 'process raft request' (duration: 104.670664ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:45.46109Z","caller":"traceutil/trace.go:171","msg":"trace[2078945126] linearizableReadLoop","detail":"{readStateIndex:6007; appliedIndex:6006; }","duration":"119.757497ms","start":"2026-09-29T04:01:45.341292Z","end":"2026-09-29T04:01:45.461049Z","steps":["trace[2078945126] 'read index received' (duration: 98.158011ms)","trace[2078945126] 'applied index is now lower than readState.Index' (duration: 21.597605ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:01:45.461133Z","caller":"traceutil/trace.go:171","msg":"trace[1687658034] transaction","detail":"{read_only:false; response_revision:5798; number_of_response:1; }","duration":"168.458552ms","start":"2026-09-29T04:01:45.292638Z","end":"2026-09-29T04:01:45.461096Z","steps":["trace[1687658034] 'process raft request' (duration: 146.936039ms)","trace[1687658034] 'compare' (duration: 21.289506ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:01:45.461408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.094737ms","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-09-29T04:01:45.46146Z","caller":"traceutil/trace.go:171","msg":"trace[646941480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5798; }","duration":"120.21783ms","start":"2026-09-29T04:01:45.341231Z","end":"2026-09-29T04:01:45.461449Z","steps":["trace[646941480] 'agreement among raft nodes before linearized reading' (duration: 119.959502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:01:57.940421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.411643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T04:01:57.940633Z","caller":"traceutil/trace.go:171","msg":"trace[663526769] linearizableReadLoop","detail":"{readStateIndex:6115; appliedIndex:6112; }","duration":"159.001167ms","start":"2026-09-29T04:01:57.781611Z","end":"2026-09-29T04:01:57.940612Z","steps":["trace[663526769] 'read index received' (duration: 9.678141ms)","trace[663526769] 'applied index is now lower than readState.Index' (duration: 149.321786ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:01:57.940859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.258794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"info","ts":"2026-09-29T04:01:57.940888Z","caller":"traceutil/trace.go:171","msg":"trace[1083578740] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5902; }","duration":"159.342106ms","start":"2026-09-29T04:01:57.781535Z","end":"2026-09-29T04:01:57.940877Z","steps":["trace[1083578740] 'agreement among raft nodes before linearized reading' (duration: 159.13008ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:57.941385Z","caller":"traceutil/trace.go:171","msg":"trace[528991337] transaction","detail":"{read_only:false; response_revision:5901; number_of_response:1; }","duration":"208.527585ms","start":"2026-09-29T04:01:57.732789Z","end":"2026-09-29T04:01:57.941316Z","steps":["trace[528991337] 'process raft request' (duration: 101.099804ms)","trace[528991337] 'compare' (duration: 105.989251ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:01:57.941598Z","caller":"traceutil/trace.go:171","msg":"trace[1681336962] transaction","detail":"{read_only:false; response_revision:5902; number_of_response:1; }","duration":"205.338476ms","start":"2026-09-29T04:01:57.736243Z","end":"2026-09-29T04:01:57.941581Z","steps":["trace[1681336962] 'process raft request' (duration: 204.282426ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:01:58.505361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2996} {"level":"info","ts":"2026-09-29T04:01:58.584602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2996,"took":"77.41596ms","hash":1545973031,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T04:01:58.58473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1545973031,"revision":2996,"compact-revision":-1} {"level":"info","ts":"2026-09-29T04:06:58.515843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5924} {"level":"info","ts":"2026-09-29T04:06:58.615016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5924,"took":"96.746402ms","hash":3565648405,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":26546176,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-29T04:06:58.6151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3565648405,"revision":5924,"compact-revision":2996} {"level":"info","ts":"2026-09-29T04:08:14.298577Z","caller":"traceutil/trace.go:171","msg":"trace[584924489] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"151.951975ms","start":"2026-09-29T04:08:14.146587Z","end":"2026-09-29T04:08:14.298539Z","steps":["trace[584924489] 'process raft request' (duration: 151.662527ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:14.298754Z","caller":"traceutil/trace.go:171","msg":"trace[1784939195] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"151.232925ms","start":"2026-09-29T04:08:14.147505Z","end":"2026-09-29T04:08:14.298738Z","steps":["trace[1784939195] 'read index received' (duration: 150.311899ms)","trace[1784939195] 'applied index is now lower than readState.Index' (duration: 919.696µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:08:14.298957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.44051ms","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-09-29T04:08:14.299006Z","caller":"traceutil/trace.go:171","msg":"trace[1527531028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8689; }","duration":"151.526583ms","start":"2026-09-29T04:08:14.147465Z","end":"2026-09-29T04:08:14.298992Z","steps":["trace[1527531028] 'agreement among raft nodes before linearized reading' (duration: 151.348148ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:14.421053Z","caller":"traceutil/trace.go:171","msg":"trace[682805104] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"116.13032ms","start":"2026-09-29T04:08:14.30489Z","end":"2026-09-29T04:08:14.42102Z","steps":["trace[682805104] 'process raft request' (duration: 106.474839ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:43.749193Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023fd.wal"} {"level":"warn","ts":"2026-09-29T04:08:45.821113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.291457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p8jpj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-29T04:08:45.821249Z","caller":"traceutil/trace.go:171","msg":"trace[787313903] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p8jpj; range_end:; response_count:1; response_revision:8907; }","duration":"266.495992ms","start":"2026-09-29T04:08:45.55473Z","end":"2026-09-29T04:08:45.821226Z","steps":["trace[787313903] 'range keys from in-memory index tree' (duration: 266.078791ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:45.821715Z","caller":"traceutil/trace.go:171","msg":"trace[1197885440] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"240.04765ms","start":"2026-09-29T04:08:45.581622Z","end":"2026-09-29T04:08:45.82167Z","steps":["trace[1197885440] 'process raft request' (duration: 230.054189ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:45.876531Z","caller":"traceutil/trace.go:171","msg":"trace[1694631030] linearizableReadLoop","detail":"{readStateIndex:9222; appliedIndex:9220; }","duration":"115.949025ms","start":"2026-09-29T04:08:45.760545Z","end":"2026-09-29T04:08:45.876494Z","steps":["trace[1694631030] 'read index received' (duration: 51.212108ms)","trace[1694631030] 'applied index is now lower than readState.Index' (duration: 64.734847ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:08:45.877206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.631895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-29T04:08:45.877184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.291348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T04:08:45.87726Z","caller":"traceutil/trace.go:171","msg":"trace[1669031670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8909; }","duration":"116.748918ms","start":"2026-09-29T04:08:45.760494Z","end":"2026-09-29T04:08:45.877243Z","steps":["trace[1669031670] 'agreement among raft nodes before linearized reading' (duration: 116.551003ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:45.877317Z","caller":"traceutil/trace.go:171","msg":"trace[1047898405] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8909; }","duration":"115.474212ms","start":"2026-09-29T04:08:45.761805Z","end":"2026-09-29T04:08:45.877279Z","steps":["trace[1047898405] 'agreement among raft nodes before linearized reading' (duration: 115.207745ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:45.877531Z","caller":"traceutil/trace.go:171","msg":"trace[1854966129] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"121.042399ms","start":"2026-09-29T04:08:45.756476Z","end":"2026-09-29T04:08:45.877518Z","steps":["trace[1854966129] 'process raft request' (duration: 119.832435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:08:48.835926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.11075ms","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-09-29T04:08:48.836029Z","caller":"traceutil/trace.go:171","msg":"trace[1287486264] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8925; }","duration":"194.264424ms","start":"2026-09-29T04:08:48.641743Z","end":"2026-09-29T04:08:48.836007Z","steps":["trace[1287486264] 'count revisions from in-memory index tree' (duration: 194.047448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:08:48.836339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.730539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-29T04:08:48.836381Z","caller":"traceutil/trace.go:171","msg":"trace[2122512023] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8925; }","duration":"157.77618ms","start":"2026-09-29T04:08:48.678594Z","end":"2026-09-29T04:08:48.836371Z","steps":["trace[2122512023] 'count revisions from in-memory index tree' (duration: 157.679077ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:56.727132Z","caller":"traceutil/trace.go:171","msg":"trace[1254208451] transaction","detail":"{read_only:false; response_revision:8968; number_of_response:1; }","duration":"115.519813ms","start":"2026-09-29T04:08:56.611578Z","end":"2026-09-29T04:08:56.727098Z","steps":["trace[1254208451] 'process raft request' (duration: 95.551552ms)","trace[1254208451] 'compare' (duration: 19.817587ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:08:56.741519Z","caller":"traceutil/trace.go:171","msg":"trace[1294067501] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"127.112919ms","start":"2026-09-29T04:08:56.614383Z","end":"2026-09-29T04:08:56.741495Z","steps":["trace[1294067501] 'process raft request' (duration: 126.988146ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:08:59.065088Z","caller":"traceutil/trace.go:171","msg":"trace[1605805197] transaction","detail":"{read_only:false; response_revision:8991; number_of_response:1; }","duration":"106.974783ms","start":"2026-09-29T04:08:58.958077Z","end":"2026-09-29T04:08:59.065052Z","steps":["trace[1605805197] 'process raft request' (duration: 106.762307ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:10:02.996265Z","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-09-29T04:10:03.003464Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-29T04:10:03.003588Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-29T04:11:58.52288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8077} {"level":"info","ts":"2026-09-29T04:11:58.599869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8077,"took":"74.587878ms","hash":3460321531,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":28065792,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-29T04:11:58.599962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3460321531,"revision":8077,"compact-revision":5924} {"level":"info","ts":"2026-09-29T04:14:43.448005Z","caller":"traceutil/trace.go:171","msg":"trace[1478438562] transaction","detail":"{read_only:false; response_revision:11477; number_of_response:1; }","duration":"103.329115ms","start":"2026-09-29T04:14:43.344642Z","end":"2026-09-29T04:14:43.447971Z","steps":["trace[1478438562] 'process raft request' (duration: 103.14963ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:16:15.43501Z","caller":"traceutil/trace.go:171","msg":"trace[1854536869] transaction","detail":"{read_only:false; response_revision:12315; number_of_response:1; }","duration":"126.650368ms","start":"2026-09-29T04:16:15.308334Z","end":"2026-09-29T04:16:15.434984Z","steps":["trace[1854536869] 'process raft request' (duration: 126.546395ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:16:58.529825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10448} {"level":"info","ts":"2026-09-29T04:16:58.611797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10448,"took":"79.132227ms","hash":763235425,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":25808896,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-29T04:16:58.61187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":763235425,"revision":10448,"compact-revision":8077} {"level":"info","ts":"2026-09-29T04:21:58.538136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12622} {"level":"info","ts":"2026-09-29T04:21:58.643043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12622,"took":"102.387952ms","hash":4034403986,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":27824128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-29T04:21:58.64311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4034403986,"revision":12622,"compact-revision":10448} {"level":"info","ts":"2026-09-29T04:22:55.187616Z","caller":"traceutil/trace.go:171","msg":"trace[1010623831] transaction","detail":"{read_only:false; response_revision:14978; number_of_response:1; }","duration":"120.460419ms","start":"2026-09-29T04:22:55.067115Z","end":"2026-09-29T04:22:55.187575Z","steps":["trace[1010623831] 'process raft request' (duration: 120.15595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:23:07.325599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.795092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T04:23:07.325939Z","caller":"traceutil/trace.go:171","msg":"trace[176166347] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"340.777682ms","start":"2026-09-29T04:23:06.985131Z","end":"2026-09-29T04:23:07.325908Z","steps":["trace[176166347] 'process raft request' (duration: 83.572578ms)","trace[176166347] 'compare' (duration: 256.477273ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:23:07.326107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:23:06.985103Z","time spent":"340.912875ms","remote":"127.0.0.1:59472","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-09-29T04:23:07.369265Z","caller":"traceutil/trace.go:171","msg":"trace[994147984] linearizableReadLoop","detail":"{readStateIndex:15677; appliedIndex:15675; }","duration":"249.96224ms","start":"2026-09-29T04:23:07.119275Z","end":"2026-09-29T04:23:07.369238Z","steps":["trace[994147984] 'read index received' (duration: 147.556896ms)","trace[994147984] 'applied index is now lower than readState.Index' (duration: 102.403384ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:23:07.369596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.321351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-lw4hk.18d9aff31ec203d4\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-29T04:23:07.369648Z","caller":"traceutil/trace.go:171","msg":"trace[1493016607] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-lw4hk.18d9aff31ec203d4; range_end:; response_count:1; response_revision:15158; }","duration":"250.419743ms","start":"2026-09-29T04:23:07.119212Z","end":"2026-09-29T04:23:07.369632Z","steps":["trace[1493016607] 'agreement among raft nodes before linearized reading' (duration: 250.218088ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:23:07.371736Z","caller":"traceutil/trace.go:171","msg":"trace[268742507] transaction","detail":"{read_only:false; response_revision:15160; number_of_response:1; }","duration":"137.99789ms","start":"2026-09-29T04:23:07.23371Z","end":"2026-09-29T04:23:07.371708Z","steps":["trace[268742507] 'process raft request' (duration: 137.903467ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:23:07.371912Z","caller":"traceutil/trace.go:171","msg":"trace[125633696] transaction","detail":"{read_only:false; response_revision:15159; number_of_response:1; }","duration":"138.305418ms","start":"2026-09-29T04:23:07.233589Z","end":"2026-09-29T04:23:07.371895Z","steps":["trace[125633696] 'process raft request' (duration: 137.750922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:23:07.37188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.48932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57e70958-21d8-4aed-a6c0-f6ff7940f840\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-09-29T04:23:07.371986Z","caller":"traceutil/trace.go:171","msg":"trace[237384851] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57e70958-21d8-4aed-a6c0-f6ff7940f840; range_end:; response_count:1; response_revision:15160; }","duration":"243.656155ms","start":"2026-09-29T04:23:07.128305Z","end":"2026-09-29T04:23:07.371961Z","steps":["trace[237384851] 'agreement among raft nodes before linearized reading' (duration: 243.373157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:23:07.371972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.111885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-29T04:23:07.372081Z","caller":"traceutil/trace.go:171","msg":"trace[1217156444] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:15160; }","duration":"162.296979ms","start":"2026-09-29T04:23:07.209759Z","end":"2026-09-29T04:23:07.372056Z","steps":["trace[1217156444] 'agreement among raft nodes before linearized reading' (duration: 161.992351ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:24:15.74899Z","caller":"traceutil/trace.go:171","msg":"trace[1377889373] transaction","detail":"{read_only:false; response_revision:16085; number_of_response:1; }","duration":"106.106158ms","start":"2026-09-29T04:24:15.642854Z","end":"2026-09-29T04:24:15.74896Z","steps":["trace[1377889373] 'process raft request' (duration: 105.794929ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:24:15.749784Z","caller":"traceutil/trace.go:171","msg":"trace[908455860] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16085; }","duration":"100.055999ms","start":"2026-09-29T04:24:15.649686Z","end":"2026-09-29T04:24:15.749742Z","steps":["trace[908455860] 'agreement among raft nodes before linearized reading' (duration: 99.838893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:24:40.289067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.943146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T04:24:40.289188Z","caller":"traceutil/trace.go:171","msg":"trace[2005992695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16215; }","duration":"252.129221ms","start":"2026-09-29T04:24:40.037036Z","end":"2026-09-29T04:24:40.289165Z","steps":["trace[2005992695] 'range keys from in-memory index tree' (duration: 251.851694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:24:40.289421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.358203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T04:24:40.289558Z","caller":"traceutil/trace.go:171","msg":"trace[1644885116] linearizableReadLoop","detail":"{readStateIndex:16767; appliedIndex:16766; }","duration":"114.292347ms","start":"2026-09-29T04:24:40.175246Z","end":"2026-09-29T04:24:40.289538Z","steps":["trace[1644885116] 'read index received' (duration: 58.852µs)","trace[1644885116] 'applied index is now lower than readState.Index' (duration: 114.230624ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:24:40.289635Z","caller":"traceutil/trace.go:171","msg":"trace[982177691] transaction","detail":"{read_only:false; response_revision:16216; number_of_response:1; }","duration":"204.808659ms","start":"2026-09-29T04:24:40.084808Z","end":"2026-09-29T04:24:40.289616Z","steps":["trace[982177691] 'process raft request' (duration: 33.074945ms)","trace[982177691] 'compare' (duration: 170.856279ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:24:40.289822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.572034ms","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-09-29T04:24:40.289863Z","caller":"traceutil/trace.go:171","msg":"trace[1345976126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16216; }","duration":"114.856522ms","start":"2026-09-29T04:24:40.174996Z","end":"2026-09-29T04:24:40.289852Z","steps":["trace[1345976126] 'agreement among raft nodes before linearized reading' (duration: 114.646506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:24:40.526011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.216523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T04:24:40.526189Z","caller":"traceutil/trace.go:171","msg":"trace[507240568] transaction","detail":"{read_only:false; response_revision:16217; number_of_response:1; }","duration":"229.847779ms","start":"2026-09-29T04:24:40.29631Z","end":"2026-09-29T04:24:40.526157Z","steps":["trace[507240568] 'process raft request' (duration: 119.366679ms)","trace[507240568] 'compare' (duration: 110.049777ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:24:40.528939Z","caller":"traceutil/trace.go:171","msg":"trace[786867217] linearizableReadLoop","detail":"{readStateIndex:16770; appliedIndex:16767; }","duration":"229.939231ms","start":"2026-09-29T04:24:40.298957Z","end":"2026-09-29T04:24:40.528896Z","steps":["trace[786867217] 'read index received' (duration: 116.731625ms)","trace[786867217] 'applied index is now lower than readState.Index' (duration: 113.205876ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:24:40.529999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.014551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769953"} {"level":"info","ts":"2026-09-29T04:24:40.530044Z","caller":"traceutil/trace.go:171","msg":"trace[1348016307] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16219; }","duration":"231.118064ms","start":"2026-09-29T04:24:40.298915Z","end":"2026-09-29T04:24:40.530033Z","steps":["trace[1348016307] 'agreement among raft nodes before linearized reading' (duration: 230.142237ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:24:40.530565Z","caller":"traceutil/trace.go:171","msg":"trace[1431152601] transaction","detail":"{read_only:false; response_revision:16218; number_of_response:1; }","duration":"233.959224ms","start":"2026-09-29T04:24:40.296594Z","end":"2026-09-29T04:24:40.530553Z","steps":["trace[1431152601] 'process raft request' (duration: 232.096151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:24:40.530721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.499054ms","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-09-29T04:24:40.530752Z","caller":"traceutil/trace.go:171","msg":"trace[267620995] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16219; }","duration":"187.531865ms","start":"2026-09-29T04:24:40.343212Z","end":"2026-09-29T04:24:40.530744Z","steps":["trace[267620995] 'agreement among raft nodes before linearized reading' (duration: 187.438942ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:24:40.530744Z","caller":"traceutil/trace.go:171","msg":"trace[542450130] transaction","detail":"{read_only:false; response_revision:16219; number_of_response:1; }","duration":"233.596994ms","start":"2026-09-29T04:24:40.297117Z","end":"2026-09-29T04:24:40.530714Z","steps":["trace[542450130] 'process raft request' (duration: 231.729472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:24:40.530859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.774136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-29T04:24:40.531002Z","caller":"traceutil/trace.go:171","msg":"trace[1168471350] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16219; }","duration":"195.95363ms","start":"2026-09-29T04:24:40.335019Z","end":"2026-09-29T04:24:40.530973Z","steps":["trace[1168471350] 'agreement among raft nodes before linearized reading' (duration: 195.608881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:24:40.530952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.682913ms","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-09-29T04:24:40.531165Z","caller":"traceutil/trace.go:171","msg":"trace[985037343] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16219; }","duration":"195.93476ms","start":"2026-09-29T04:24:40.3352Z","end":"2026-09-29T04:24:40.531135Z","steps":["trace[985037343] 'agreement among raft nodes before linearized reading' (duration: 195.524918ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:26:58.546663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14626} {"level":"info","ts":"2026-09-29T04:26:58.633593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14626,"took":"82.677342ms","hash":3989660439,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-29T04:26:58.633671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3989660439,"revision":14626,"compact-revision":12622} {"level":"info","ts":"2026-09-29T04:27:19.472759Z","caller":"traceutil/trace.go:171","msg":"trace[747289408] transaction","detail":"{read_only:false; response_revision:17362; number_of_response:1; }","duration":"189.080618ms","start":"2026-09-29T04:27:19.283653Z","end":"2026-09-29T04:27:19.472733Z","steps":["trace[747289408] 'process raft request' (duration: 189.012276ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:27:19.472913Z","caller":"traceutil/trace.go:171","msg":"trace[602693256] transaction","detail":"{read_only:false; response_revision:17361; number_of_response:1; }","duration":"189.366687ms","start":"2026-09-29T04:27:19.283525Z","end":"2026-09-29T04:27:19.472892Z","steps":["trace[602693256] 'process raft request' (duration: 188.491422ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:27:19.47328Z","caller":"traceutil/trace.go:171","msg":"trace[1304887369] linearizableReadLoop","detail":"{readStateIndex:17956; appliedIndex:17953; }","duration":"182.096272ms","start":"2026-09-29T04:27:19.291152Z","end":"2026-09-29T04:27:19.473248Z","steps":["trace[1304887369] 'read index received' (duration: 180.879209ms)","trace[1304887369] 'applied index is now lower than readState.Index' (duration: 1.214913ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:27:19.473466Z","caller":"traceutil/trace.go:171","msg":"trace[92758708] transaction","detail":"{read_only:false; response_revision:17363; number_of_response:1; }","duration":"188.105711ms","start":"2026-09-29T04:27:19.285348Z","end":"2026-09-29T04:27:19.473454Z","steps":["trace[92758708] 'process raft request' (duration: 187.745811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:27:19.473866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.684699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-29T04:27:19.47393Z","caller":"traceutil/trace.go:171","msg":"trace[1710280948] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:17363; }","duration":"182.801432ms","start":"2026-09-29T04:27:19.291111Z","end":"2026-09-29T04:27:19.473912Z","steps":["trace[1710280948] 'agreement among raft nodes before linearized reading' (duration: 182.658678ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:30:24.714053Z","caller":"traceutil/trace.go:171","msg":"trace[108524281] linearizableReadLoop","detail":"{readStateIndex:19361; appliedIndex:19360; }","duration":"170.92457ms","start":"2026-09-29T04:30:24.543093Z","end":"2026-09-29T04:30:24.714017Z","steps":["trace[108524281] 'read index received' (duration: 170.601001ms)","trace[108524281] 'applied index is now lower than readState.Index' (duration: 321.839µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:30:24.71416Z","caller":"traceutil/trace.go:171","msg":"trace[795674411] transaction","detail":"{read_only:false; response_revision:18726; number_of_response:1; }","duration":"172.476034ms","start":"2026-09-29T04:30:24.541637Z","end":"2026-09-29T04:30:24.714113Z","steps":["trace[795674411] 'process raft request' (duration: 172.114264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:30:24.714477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.775247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-29T04:30:24.714504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.382504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4xqxt\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-09-29T04:30:24.714541Z","caller":"traceutil/trace.go:171","msg":"trace[1371076950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18726; }","duration":"155.882869ms","start":"2026-09-29T04:30:24.558641Z","end":"2026-09-29T04:30:24.714523Z","steps":["trace[1371076950] 'agreement among raft nodes before linearized reading' (duration: 155.618582ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:30:24.714604Z","caller":"traceutil/trace.go:171","msg":"trace[541775601] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4xqxt; range_end:; response_count:1; response_revision:18726; }","duration":"171.547668ms","start":"2026-09-29T04:30:24.543034Z","end":"2026-09-29T04:30:24.714581Z","steps":["trace[541775601] 'agreement among raft nodes before linearized reading' (duration: 171.183068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:30:24.714605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.634313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-29T04:30:24.714709Z","caller":"traceutil/trace.go:171","msg":"trace[1377036333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18726; }","duration":"155.831568ms","start":"2026-09-29T04:30:24.558855Z","end":"2026-09-29T04:30:24.714687Z","steps":["trace[1377036333] 'agreement among raft nodes before linearized reading' (duration: 155.53427ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:30:31.548373Z","caller":"traceutil/trace.go:171","msg":"trace[1596840000] transaction","detail":"{read_only:false; response_revision:18773; number_of_response:1; }","duration":"102.126486ms","start":"2026-09-29T04:30:31.446215Z","end":"2026-09-29T04:30:31.548341Z","steps":["trace[1596840000] 'process raft request' (duration: 64.00942ms)","trace[1596840000] 'compare' (duration: 37.89357ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:30:32.208972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.145583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:30:32.209124Z","caller":"traceutil/trace.go:171","msg":"trace[793804346] linearizableReadLoop","detail":"{readStateIndex:19416; appliedIndex:19415; }","duration":"237.648526ms","start":"2026-09-29T04:30:31.971455Z","end":"2026-09-29T04:30:32.209104Z","steps":["trace[793804346] 'read index received' (duration: 28.81µs)","trace[793804346] 'applied index is now lower than readState.Index' (duration: 237.618286ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:30:32.209217Z","caller":"traceutil/trace.go:171","msg":"trace[1538233169] transaction","detail":"{read_only:false; response_revision:18779; number_of_response:1; }","duration":"280.697721ms","start":"2026-09-29T04:30:31.928502Z","end":"2026-09-29T04:30:32.2092Z","steps":["trace[1538233169] 'process raft request' (duration: 33.212989ms)","trace[1538233169] 'compare' (duration: 247.006449ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:30:32.209442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.161567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-29T04:30:32.209504Z","caller":"traceutil/trace.go:171","msg":"trace[1369078077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18779; }","duration":"191.270429ms","start":"2026-09-29T04:30:32.018216Z","end":"2026-09-29T04:30:32.209486Z","steps":["trace[1369078077] 'agreement among raft nodes before linearized reading' (duration: 191.000812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:30:32.209646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.001398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:30:32.209688Z","caller":"traceutil/trace.go:171","msg":"trace[1705705216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18779; }","duration":"173.0651ms","start":"2026-09-29T04:30:32.036612Z","end":"2026-09-29T04:30:32.209678Z","steps":["trace[1705705216] 'agreement among raft nodes before linearized reading' (duration: 172.996609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:30:32.209773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.273365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-09-29T04:30:32.210138Z","caller":"traceutil/trace.go:171","msg":"trace[180308200] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18779; }","duration":"238.480801ms","start":"2026-09-29T04:30:31.971414Z","end":"2026-09-29T04:30:32.209895Z","steps":["trace[180308200] 'agreement among raft nodes before linearized reading' (duration: 238.010587ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:31:17.810299Z","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-09-29T04:31:17.816801Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-29T04:31:17.816897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-29T04:31:58.553893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17143} {"level":"info","ts":"2026-09-29T04:31:58.659748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17143,"took":"102.150457ms","hash":1729756162,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":34230272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-29T04:31:58.659965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1729756162,"revision":17143,"compact-revision":14626} {"level":"warn","ts":"2026-09-29T04:36:46.190246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.515654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:36:46.190413Z","caller":"traceutil/trace.go:171","msg":"trace[328741808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21622; }","duration":"153.713949ms","start":"2026-09-29T04:36:46.036656Z","end":"2026-09-29T04:36:46.19037Z","steps":["trace[328741808] 'range keys from in-memory index tree' (duration: 153.433991ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:36:46.666197Z","caller":"traceutil/trace.go:171","msg":"trace[1573395749] transaction","detail":"{read_only:false; response_revision:21624; number_of_response:1; }","duration":"137.816705ms","start":"2026-09-29T04:36:46.528343Z","end":"2026-09-29T04:36:46.666159Z","steps":["trace[1573395749] 'process raft request' (duration: 136.671792ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:36:47.210881Z","caller":"traceutil/trace.go:171","msg":"trace[452490005] linearizableReadLoop","detail":"{readStateIndex:22357; appliedIndex:22356; }","duration":"139.101211ms","start":"2026-09-29T04:36:47.071742Z","end":"2026-09-29T04:36:47.210843Z","steps":["trace[452490005] 'read index received' (duration: 138.956276ms)","trace[452490005] 'applied index is now lower than readState.Index' (duration: 143.704µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:36:47.210954Z","caller":"traceutil/trace.go:171","msg":"trace[882929150] transaction","detail":"{read_only:false; response_revision:21626; number_of_response:1; }","duration":"151.326482ms","start":"2026-09-29T04:36:47.059584Z","end":"2026-09-29T04:36:47.21091Z","steps":["trace[882929150] 'process raft request' (duration: 151.081546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.211386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.616495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-29T04:36:47.211489Z","caller":"traceutil/trace.go:171","msg":"trace[1670156564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21626; }","duration":"139.753679ms","start":"2026-09-29T04:36:47.071712Z","end":"2026-09-29T04:36:47.211465Z","steps":["trace[1670156564] 'agreement among raft nodes before linearized reading' (duration: 139.309326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.484278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.043008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:36:47.48454Z","caller":"traceutil/trace.go:171","msg":"trace[1982741069] transaction","detail":"{read_only:false; response_revision:21627; number_of_response:1; }","duration":"364.383771ms","start":"2026-09-29T04:36:47.120112Z","end":"2026-09-29T04:36:47.484495Z","steps":["trace[1982741069] 'process raft request' (duration: 233.974234ms)","trace[1982741069] 'compare' (duration: 129.712048ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:36:47.484822Z","caller":"traceutil/trace.go:171","msg":"trace[845073546] transaction","detail":"{read_only:false; response_revision:21628; number_of_response:1; }","duration":"360.701188ms","start":"2026-09-29T04:36:47.124085Z","end":"2026-09-29T04:36:47.484787Z","steps":["trace[845073546] 'process raft request' (duration: 360.305797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.485034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:36:47.124031Z","time spent":"360.845142ms","remote":"127.0.0.1:59472","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-09-29T04:36:47.485432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:36:47.12009Z","time spent":"365.271466ms","remote":"127.0.0.1:59472","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-09-29T04:36:47.496724Z","caller":"traceutil/trace.go:171","msg":"trace[1095099663] transaction","detail":"{read_only:false; response_revision:21630; number_of_response:1; }","duration":"280.65833ms","start":"2026-09-29T04:36:47.216044Z","end":"2026-09-29T04:36:47.496702Z","steps":["trace[1095099663] 'process raft request' (duration: 280.585798ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:36:47.496777Z","caller":"traceutil/trace.go:171","msg":"trace[1252939788] linearizableReadLoop","detail":"{readStateIndex:22361; appliedIndex:22357; }","duration":"285.767632ms","start":"2026-09-29T04:36:47.210992Z","end":"2026-09-29T04:36:47.496759Z","steps":["trace[1252939788] 'read index received' (duration: 143.113482ms)","trace[1252939788] 'applied index is now lower than readState.Index' (duration: 142.65267ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:36:47.496864Z","caller":"traceutil/trace.go:171","msg":"trace[191609339] transaction","detail":"{read_only:false; response_revision:21629; number_of_response:1; }","duration":"335.824683ms","start":"2026-09-29T04:36:47.161028Z","end":"2026-09-29T04:36:47.496853Z","steps":["trace[191609339] 'process raft request' (duration: 335.300108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.496933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:36:47.161005Z","time spent":"335.877824ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:36:47.497051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.683237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:36:47.4971Z","caller":"traceutil/trace.go:171","msg":"trace[1753208528] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21630; }","duration":"375.786161ms","start":"2026-09-29T04:36:47.121304Z","end":"2026-09-29T04:36:47.49709Z","steps":["trace[1753208528] 'agreement among raft nodes before linearized reading' (duration: 375.706218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.497129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:36:47.121295Z","time spent":"375.828031ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-29T04:36:47.49759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.257152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-29T04:36:47.497636Z","caller":"traceutil/trace.go:171","msg":"trace[1361434371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21630; }","duration":"421.356594ms","start":"2026-09-29T04:36:47.076267Z","end":"2026-09-29T04:36:47.497624Z","steps":["trace[1361434371] 'agreement among raft nodes before linearized reading' (duration: 421.214691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.497665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:36:47.076243Z","time spent":"421.414526ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-29T04:36:47.703466Z","caller":"traceutil/trace.go:171","msg":"trace[421766990] linearizableReadLoop","detail":"{readStateIndex:22363; appliedIndex:22362; }","duration":"199.526721ms","start":"2026-09-29T04:36:47.503911Z","end":"2026-09-29T04:36:47.703438Z","steps":["trace[421766990] 'read index received' (duration: 199.263723ms)","trace[421766990] 'applied index is now lower than readState.Index' (duration: 261.678µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:36:47.703563Z","caller":"traceutil/trace.go:171","msg":"trace[1858804369] transaction","detail":"{read_only:false; response_revision:21631; number_of_response:1; }","duration":"202.01764ms","start":"2026-09-29T04:36:47.501501Z","end":"2026-09-29T04:36:47.703518Z","steps":["trace[1858804369] 'process raft request' (duration: 201.701121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:36:47.703753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.806178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-29T04:36:47.703807Z","caller":"traceutil/trace.go:171","msg":"trace[628632231] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21631; }","duration":"199.901791ms","start":"2026-09-29T04:36:47.503888Z","end":"2026-09-29T04:36:47.70379Z","steps":["trace[628632231] 'agreement among raft nodes before linearized reading' (duration: 199.663374ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:36:58.560952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19699} {"level":"info","ts":"2026-09-29T04:36:58.687931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19699,"took":"122.354812ms","hash":1753207547,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":29724672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-29T04:36:58.688014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1753207547,"revision":19699,"compact-revision":17143} {"level":"warn","ts":"2026-09-29T04:41:06.333429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.033246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager\" ","response":"range_response_count:1 size:5167"} {"level":"info","ts":"2026-09-29T04:41:06.333554Z","caller":"traceutil/trace.go:171","msg":"trace[65526752] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager; range_end:; response_count:1; response_revision:24215; }","duration":"229.249232ms","start":"2026-09-29T04:41:06.104285Z","end":"2026-09-29T04:41:06.333534Z","steps":["trace[65526752] 'range keys from in-memory index tree' (duration: 228.810009ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:41:58.567991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21685} {"level":"info","ts":"2026-09-29T04:41:58.690378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21685,"took":"117.706192ms","hash":1447780787,"current-db-size-bytes":57151488,"current-db-size":"57 MB","current-db-size-in-use-bytes":42704896,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-29T04:41:58.69048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1447780787,"revision":21685,"compact-revision":19699} {"level":"info","ts":"2026-09-29T04:44:29.538318Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069ae.wal"} {"level":"info","ts":"2026-09-29T04:44:44.803468Z","caller":"traceutil/trace.go:171","msg":"trace[802640295] transaction","detail":"{read_only:false; response_revision:26360; number_of_response:1; }","duration":"371.630803ms","start":"2026-09-29T04:44:44.431796Z","end":"2026-09-29T04:44:44.803427Z","steps":["trace[802640295] 'process raft request' (duration: 321.744678ms)","trace[802640295] 'compare' (duration: 49.559765ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:44.803592Z","caller":"traceutil/trace.go:171","msg":"trace[1790733217] linearizableReadLoop","detail":"{readStateIndex:27229; appliedIndex:27228; }","duration":"338.667491ms","start":"2026-09-29T04:44:44.464885Z","end":"2026-09-29T04:44:44.803552Z","steps":["trace[1790733217] 'read index received' (duration: 288.641713ms)","trace[1790733217] 'applied index is now lower than readState.Index' (duration: 50.022048ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:44.803703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:44.431757Z","time spent":"371.817209ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:44.803955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.801905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:44:44.803982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.077273ms","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-09-29T04:44:44.804003Z","caller":"traceutil/trace.go:171","msg":"trace[241116580] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26361; }","duration":"146.900807ms","start":"2026-09-29T04:44:44.657086Z","end":"2026-09-29T04:44:44.803987Z","steps":["trace[241116580] 'agreement among raft nodes before linearized reading' (duration: 146.823125ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:44.804032Z","caller":"traceutil/trace.go:171","msg":"trace[217194289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26361; }","duration":"339.156885ms","start":"2026-09-29T04:44:44.464863Z","end":"2026-09-29T04:44:44.80402Z","steps":["trace[217194289] 'agreement among raft nodes before linearized reading' (duration: 338.874357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:44.804075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:44.464809Z","time spent":"339.258027ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-29T04:44:44.804039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.948231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:44:44.804213Z","caller":"traceutil/trace.go:171","msg":"trace[1908745998] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26361; }","duration":"235.121266ms","start":"2026-09-29T04:44:44.569022Z","end":"2026-09-29T04:44:44.804144Z","steps":["trace[1908745998] 'agreement among raft nodes before linearized reading' (duration: 234.856098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.060763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.504928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:44:45.060896Z","caller":"traceutil/trace.go:171","msg":"trace[2091567321] transaction","detail":"{read_only:false; response_revision:26362; number_of_response:1; }","duration":"251.954447ms","start":"2026-09-29T04:44:44.808918Z","end":"2026-09-29T04:44:45.060873Z","steps":["trace[2091567321] 'process raft request' (duration: 145.230042ms)","trace[2091567321] 'compare' (duration: 106.343684ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:45.307147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.34828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:45.307287Z","caller":"traceutil/trace.go:171","msg":"trace[1655854689] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26362; }","duration":"143.510474ms","start":"2026-09-29T04:44:45.163748Z","end":"2026-09-29T04:44:45.307258Z","steps":["trace[1655854689] 'range keys from in-memory index tree' (duration: 143.230886ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:45.307693Z","caller":"traceutil/trace.go:171","msg":"trace[1790507774] transaction","detail":"{read_only:false; response_revision:26363; number_of_response:1; }","duration":"143.71091ms","start":"2026-09-29T04:44:45.163932Z","end":"2026-09-29T04:44:45.307643Z","steps":["trace[1790507774] 'process raft request' (duration: 123.648559ms)","trace[1790507774] 'compare' (duration: 19.259418ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:45.463734Z","caller":"traceutil/trace.go:171","msg":"trace[451691622] linearizableReadLoop","detail":"{readStateIndex:27233; appliedIndex:27231; }","duration":"179.415408ms","start":"2026-09-29T04:44:45.284281Z","end":"2026-09-29T04:44:45.463696Z","steps":["trace[451691622] 'read index received' (duration: 3.324553ms)","trace[451691622] 'applied index is now lower than readState.Index' (duration: 176.089785ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:45.464123Z","caller":"traceutil/trace.go:171","msg":"trace[2025491555] transaction","detail":"{read_only:false; response_revision:26364; number_of_response:1; }","duration":"199.093409ms","start":"2026-09-29T04:44:45.265011Z","end":"2026-09-29T04:44:45.464105Z","steps":["trace[2025491555] 'process raft request' (duration: 155.265003ms)","trace[2025491555] 'compare' (duration: 43.226059ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:45.464269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.98366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:45.464369Z","caller":"traceutil/trace.go:171","msg":"trace[964431703] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26364; }","duration":"152.133624ms","start":"2026-09-29T04:44:45.312211Z","end":"2026-09-29T04:44:45.464345Z","steps":["trace[964431703] 'agreement among raft nodes before linearized reading' (duration: 151.97098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.46451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.174939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:45.464633Z","caller":"traceutil/trace.go:171","msg":"trace[206180353] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:26364; }","duration":"180.399306ms","start":"2026-09-29T04:44:45.284204Z","end":"2026-09-29T04:44:45.464604Z","steps":["trace[206180353] 'agreement among raft nodes before linearized reading' (duration: 180.130668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.464641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.535768ms","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-09-29T04:44:45.464709Z","caller":"traceutil/trace.go:171","msg":"trace[259158664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26364; }","duration":"151.660581ms","start":"2026-09-29T04:44:45.313026Z","end":"2026-09-29T04:44:45.464687Z","steps":["trace[259158664] 'agreement among raft nodes before linearized reading' (duration: 151.403074ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:45.864831Z","caller":"traceutil/trace.go:171","msg":"trace[1476327298] linearizableReadLoop","detail":"{readStateIndex:27234; appliedIndex:27233; }","duration":"393.774974ms","start":"2026-09-29T04:44:45.471024Z","end":"2026-09-29T04:44:45.864799Z","steps":["trace[1476327298] 'read index received' (duration: 393.482536ms)","trace[1476327298] 'applied index is now lower than readState.Index' (duration: 291.078µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:45.864901Z","caller":"traceutil/trace.go:171","msg":"trace[136187328] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"394.782921ms","start":"2026-09-29T04:44:45.470075Z","end":"2026-09-29T04:44:45.864858Z","steps":["trace[136187328] 'process raft request' (duration: 394.418231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.865117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:45.470045Z","time spent":"394.956456ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:45.865142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.089132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-09-29T04:44:45.865211Z","caller":"traceutil/trace.go:171","msg":"trace[1428233895] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26365; }","duration":"394.209086ms","start":"2026-09-29T04:44:45.470989Z","end":"2026-09-29T04:44:45.865198Z","steps":["trace[1428233895] 'agreement among raft nodes before linearized reading' (duration: 393.964449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.865249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:45.470974Z","time spent":"394.265417ms","remote":"127.0.0.1:59388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19257,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-29T04:44:45.905517Z","caller":"traceutil/trace.go:171","msg":"trace[359225423] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"250.801725ms","start":"2026-09-29T04:44:45.654687Z","end":"2026-09-29T04:44:45.905489Z","steps":["trace[359225423] 'process raft request' (duration: 250.551388ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:45.905858Z","caller":"traceutil/trace.go:171","msg":"trace[1113678363] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"249.497058ms","start":"2026-09-29T04:44:45.656335Z","end":"2026-09-29T04:44:45.905832Z","steps":["trace[1113678363] 'process raft request' (duration: 249.108227ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:45.906029Z","caller":"traceutil/trace.go:171","msg":"trace[407149417] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"150.726155ms","start":"2026-09-29T04:44:45.755247Z","end":"2026-09-29T04:44:45.905973Z","steps":["trace[407149417] 'process raft request' (duration: 150.489789ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:45.906313Z","caller":"traceutil/trace.go:171","msg":"trace[389606194] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"122.337121ms","start":"2026-09-29T04:44:45.783961Z","end":"2026-09-29T04:44:45.906298Z","steps":["trace[389606194] 'process raft request' (duration: 121.961671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.906311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.495515ms","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-09-29T04:44:45.906397Z","caller":"traceutil/trace.go:171","msg":"trace[432027009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26369; }","duration":"221.68163ms","start":"2026-09-29T04:44:45.684695Z","end":"2026-09-29T04:44:45.906377Z","steps":["trace[432027009] 'agreement among raft nodes before linearized reading' (duration: 221.423912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:45.906683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.056626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-29T04:44:45.906708Z","caller":"traceutil/trace.go:171","msg":"trace[1446619848] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26369; }","duration":"200.128308ms","start":"2026-09-29T04:44:45.70657Z","end":"2026-09-29T04:44:45.906699Z","steps":["trace[1446619848] 'agreement among raft nodes before linearized reading' (duration: 199.947773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:46.187719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.847899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:44:46.187871Z","caller":"traceutil/trace.go:171","msg":"trace[1629527157] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"277.297755ms","start":"2026-09-29T04:44:45.910531Z","end":"2026-09-29T04:44:46.187829Z","steps":["trace[1629527157] 'process raft request' (duration: 136.205049ms)","trace[1629527157] 'compare' (duration: 140.532401ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:46.18852Z","caller":"traceutil/trace.go:171","msg":"trace[672356392] linearizableReadLoop","detail":"{readStateIndex:27240; appliedIndex:27238; }","duration":"151.840767ms","start":"2026-09-29T04:44:46.03665Z","end":"2026-09-29T04:44:46.188491Z","steps":["trace[672356392] 'read index received' (duration: 10.108673ms)","trace[672356392] 'applied index is now lower than readState.Index' (duration: 141.730424ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:46.188579Z","caller":"traceutil/trace.go:171","msg":"trace[1108401095] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"277.10268ms","start":"2026-09-29T04:44:45.911435Z","end":"2026-09-29T04:44:46.188538Z","steps":["trace[1108401095] 'process raft request' (duration: 276.908515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:46.188676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.032603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:46.188733Z","caller":"traceutil/trace.go:171","msg":"trace[475978581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26371; }","duration":"152.145585ms","start":"2026-09-29T04:44:46.036573Z","end":"2026-09-29T04:44:46.188718Z","steps":["trace[475978581] 'agreement among raft nodes before linearized reading' (duration: 152.033722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:46.577111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.179989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:44:46.577387Z","caller":"traceutil/trace.go:171","msg":"trace[1459184873] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"157.828715ms","start":"2026-09-29T04:44:46.419516Z","end":"2026-09-29T04:44:46.577345Z","steps":["trace[1459184873] 'process raft request' (duration: 35.961186ms)","trace[1459184873] 'compare' (duration: 120.878461ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:52.277092Z","caller":"traceutil/trace.go:171","msg":"trace[1132344192] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"350.213994ms","start":"2026-09-29T04:44:51.926845Z","end":"2026-09-29T04:44:52.277059Z","steps":["trace[1132344192] 'process raft request' (duration: 350.013299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.277312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.926819Z","time spent":"350.387218ms","remote":"127.0.0.1:59472","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-09-29T04:44:52.310057Z","caller":"traceutil/trace.go:171","msg":"trace[484608645] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"381.287993ms","start":"2026-09-29T04:44:51.92874Z","end":"2026-09-29T04:44:52.310028Z","steps":["trace[484608645] 'process raft request' (duration: 381.073317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.310266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.928725Z","time spent":"381.421437ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:52.310961Z","caller":"traceutil/trace.go:171","msg":"trace[737943006] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"379.797131ms","start":"2026-09-29T04:44:51.931146Z","end":"2026-09-29T04:44:52.310943Z","steps":["trace[737943006] 'process raft request' (duration: 379.669218ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:52.311021Z","caller":"traceutil/trace.go:171","msg":"trace[289146895] linearizableReadLoop","detail":"{readStateIndex:27282; appliedIndex:27277; }","duration":"373.238869ms","start":"2026-09-29T04:44:51.937766Z","end":"2026-09-29T04:44:52.311005Z","steps":["trace[289146895] 'read index received' (duration: 339.094834ms)","trace[289146895] 'applied index is now lower than readState.Index' (duration: 34.142485ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:52.310988Z","caller":"traceutil/trace.go:171","msg":"trace[1701532218] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"379.972106ms","start":"2026-09-29T04:44:51.930971Z","end":"2026-09-29T04:44:52.310943Z","steps":["trace[1701532218] 'process raft request' (duration: 379.003869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.311076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.93112Z","time spent":"379.899784ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:52.311142Z","caller":"traceutil/trace.go:171","msg":"trace[912431197] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"378.30543ms","start":"2026-09-29T04:44:51.932821Z","end":"2026-09-29T04:44:52.311127Z","steps":["trace[912431197] 'process raft request' (duration: 378.080454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.311236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.932812Z","time spent":"378.377292ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:52.311241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.930959Z","time spent":"380.141762ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:52.311314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.233814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-29T04:44:52.311354Z","caller":"traceutil/trace.go:171","msg":"trace[508001231] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:26412; }","duration":"346.285495ms","start":"2026-09-29T04:44:51.965058Z","end":"2026-09-29T04:44:52.311344Z","steps":["trace[508001231] 'agreement among raft nodes before linearized reading' (duration: 346.156172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.311385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.96505Z","time spent":"346.328686ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"warn","ts":"2026-09-29T04:44:52.311441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.664901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:52.311474Z","caller":"traceutil/trace.go:171","msg":"trace[1142530883] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26412; }","duration":"373.755184ms","start":"2026-09-29T04:44:51.937706Z","end":"2026-09-29T04:44:52.311461Z","steps":["trace[1142530883] 'agreement among raft nodes before linearized reading' (duration: 373.690702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.311503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:51.937679Z","time spent":"373.814836ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-09-29T04:44:52.311719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.92182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:52.311751Z","caller":"traceutil/trace.go:171","msg":"trace[1314006639] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26412; }","duration":"131.995721ms","start":"2026-09-29T04:44:52.179744Z","end":"2026-09-29T04:44:52.31174Z","steps":["trace[1314006639] 'agreement among raft nodes before linearized reading' (duration: 131.938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.78172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.519783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:44:52.781935Z","caller":"traceutil/trace.go:171","msg":"trace[495425217] transaction","detail":"{read_only:false; response_revision:26415; number_of_response:1; }","duration":"324.64597ms","start":"2026-09-29T04:44:52.457271Z","end":"2026-09-29T04:44:52.781917Z","steps":["trace[495425217] 'process raft request' (duration: 324.601988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.781985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:52.457222Z","time spent":"324.740003ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:52.782175Z","caller":"traceutil/trace.go:171","msg":"trace[1699860211] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"465.572722ms","start":"2026-09-29T04:44:52.316574Z","end":"2026-09-29T04:44:52.782146Z","steps":["trace[1699860211] 'process raft request' (duration: 291.532244ms)","trace[1699860211] 'compare' (duration: 173.359449ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:52.782206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:52.316554Z","time spent":"465.632563ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:52.782439Z","caller":"traceutil/trace.go:171","msg":"trace[1569465212] linearizableReadLoop","detail":"{readStateIndex:27285; appliedIndex:27283; }","duration":"339.953228ms","start":"2026-09-29T04:44:52.442476Z","end":"2026-09-29T04:44:52.78243Z","steps":["trace[1569465212] 'read index received' (duration: 165.815487ms)","trace[1569465212] 'applied index is now lower than readState.Index' (duration: 174.136751ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:52.782478Z","caller":"traceutil/trace.go:171","msg":"trace[24580977] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"465.566691ms","start":"2026-09-29T04:44:52.316906Z","end":"2026-09-29T04:44:52.782473Z","steps":["trace[24580977] 'process raft request' (duration: 464.906583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.782502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:52.316894Z","time spent":"465.592872ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:52.782592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.128923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:52.782608Z","caller":"traceutil/trace.go:171","msg":"trace[428561021] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26415; }","duration":"340.197545ms","start":"2026-09-29T04:44:52.442404Z","end":"2026-09-29T04:44:52.782601Z","steps":["trace[428561021] 'agreement among raft nodes before linearized reading' (duration: 340.153423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.782621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:52.442381Z","time spent":"340.236436ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"info","ts":"2026-09-29T04:44:52.783579Z","caller":"traceutil/trace.go:171","msg":"trace[1609648689] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"174.547042ms","start":"2026-09-29T04:44:52.609Z","end":"2026-09-29T04:44:52.783547Z","steps":["trace[1609648689] 'process raft request' (duration: 174.340886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:52.783801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.983348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-29T04:44:52.784126Z","caller":"traceutil/trace.go:171","msg":"trace[1842772469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26416; }","duration":"173.319268ms","start":"2026-09-29T04:44:52.610785Z","end":"2026-09-29T04:44:52.784104Z","steps":["trace[1842772469] 'agreement among raft nodes before linearized reading' (duration: 172.735641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:53.093202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.56201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:44:53.093534Z","caller":"traceutil/trace.go:171","msg":"trace[1150340507] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"188.642296ms","start":"2026-09-29T04:44:52.904868Z","end":"2026-09-29T04:44:53.09351Z","steps":["trace[1150340507] 'process raft request' (duration: 188.514353ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:53.093516Z","caller":"traceutil/trace.go:171","msg":"trace[475924433] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"303.431487ms","start":"2026-09-29T04:44:52.790022Z","end":"2026-09-29T04:44:53.093453Z","steps":["trace[475924433] 'process raft request' (duration: 107.482756ms)","trace[475924433] 'compare' (duration: 195.061636ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:53.093725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:52.789978Z","time spent":"303.635832ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:53.093745Z","caller":"traceutil/trace.go:171","msg":"trace[2088947343] linearizableReadLoop","detail":"{readStateIndex:27288; appliedIndex:27287; }","duration":"302.180511ms","start":"2026-09-29T04:44:52.791513Z","end":"2026-09-29T04:44:53.093694Z","steps":["trace[2088947343] 'read index received' (duration: 106.107168ms)","trace[2088947343] 'applied index is now lower than readState.Index' (duration: 196.069523ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:53.094047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.49898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-29T04:44:53.094138Z","caller":"traceutil/trace.go:171","msg":"trace[1402585935] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26418; }","duration":"302.633254ms","start":"2026-09-29T04:44:52.791485Z","end":"2026-09-29T04:44:53.094119Z","steps":["trace[1402585935] 'agreement among raft nodes before linearized reading' (duration: 302.348296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:53.094212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:52.791465Z","time spent":"302.731586ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-09-29T04:44:53.131194Z","caller":"traceutil/trace.go:171","msg":"trace[834662631] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"225.519838ms","start":"2026-09-29T04:44:52.905625Z","end":"2026-09-29T04:44:53.131145Z","steps":["trace[834662631] 'process raft request' (duration: 225.337573ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:53.266798Z","caller":"traceutil/trace.go:171","msg":"trace[19131831] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"130.709726ms","start":"2026-09-29T04:44:53.136064Z","end":"2026-09-29T04:44:53.266774Z","steps":["trace[19131831] 'process raft request' (duration: 129.867023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:53.907742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.004614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:53.907832Z","caller":"traceutil/trace.go:171","msg":"trace[998354345] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26421; }","duration":"131.153128ms","start":"2026-09-29T04:44:53.776657Z","end":"2026-09-29T04:44:53.90781Z","steps":["trace[998354345] 'count revisions from in-memory index tree' (duration: 130.872041ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:54.437341Z","caller":"traceutil/trace.go:171","msg":"trace[833143795] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"149.386098ms","start":"2026-09-29T04:44:54.287905Z","end":"2026-09-29T04:44:54.437291Z","steps":["trace[833143795] 'process raft request' (duration: 149.167802ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:54.619039Z","caller":"traceutil/trace.go:171","msg":"trace[234873976] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"301.615316ms","start":"2026-09-29T04:44:54.317392Z","end":"2026-09-29T04:44:54.619008Z","steps":["trace[234873976] 'process raft request' (duration: 300.574327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:54.619251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:54.317362Z","time spent":"301.76908ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:54.619498Z","caller":"traceutil/trace.go:171","msg":"trace[1756774382] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"302.046398ms","start":"2026-09-29T04:44:54.317416Z","end":"2026-09-29T04:44:54.619462Z","steps":["trace[1756774382] 'process raft request' (duration: 301.823332ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:54.6195Z","caller":"traceutil/trace.go:171","msg":"trace[1525589961] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"302.027897ms","start":"2026-09-29T04:44:54.317409Z","end":"2026-09-29T04:44:54.619437Z","steps":["trace[1525589961] 'process raft request' (duration: 301.506563ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:54.619538Z","caller":"traceutil/trace.go:171","msg":"trace[1505103823] linearizableReadLoop","detail":"{readStateIndex:27298; appliedIndex:27293; }","duration":"235.62156ms","start":"2026-09-29T04:44:54.383881Z","end":"2026-09-29T04:44:54.619503Z","steps":["trace[1505103823] 'read index received' (duration: 53.235949ms)","trace[1505103823] 'applied index is now lower than readState.Index' (duration: 182.383661ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:54.619714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:54.317381Z","time spent":"302.221143ms","remote":"127.0.0.1:59472","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-09-29T04:44:54.619739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:54.317371Z","time spent":"302.248144ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:54.619754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.846776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:54.619818Z","caller":"traceutil/trace.go:171","msg":"trace[1179844182] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:26427; }","duration":"235.97533ms","start":"2026-09-29T04:44:54.383819Z","end":"2026-09-29T04:44:54.619794Z","steps":["trace[1179844182] 'agreement among raft nodes before linearized reading' (duration: 235.805716ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:54.619793Z","caller":"traceutil/trace.go:171","msg":"trace[1678955421] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"300.954978ms","start":"2026-09-29T04:44:54.318558Z","end":"2026-09-29T04:44:54.619513Z","steps":["trace[1678955421] 'process raft request' (duration: 300.794023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:54.620046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:54.318525Z","time spent":"301.420641ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:55.239833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.73942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:44:55.240028Z","caller":"traceutil/trace.go:171","msg":"trace[1654458481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26430; }","duration":"202.983847ms","start":"2026-09-29T04:44:55.037021Z","end":"2026-09-29T04:44:55.240004Z","steps":["trace[1654458481] 'range keys from in-memory index tree' (duration: 202.639748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:55.240013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.606891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-29T04:44:55.240132Z","caller":"traceutil/trace.go:171","msg":"trace[60795228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26430; }","duration":"141.780226ms","start":"2026-09-29T04:44:55.098328Z","end":"2026-09-29T04:44:55.240108Z","steps":["trace[60795228] 'range keys from in-memory index tree' (duration: 141.397945ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:55.244105Z","caller":"traceutil/trace.go:171","msg":"trace[2121245518] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"143.804032ms","start":"2026-09-29T04:44:55.100266Z","end":"2026-09-29T04:44:55.24407Z","steps":["trace[2121245518] 'process raft request' (duration: 143.268157ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:55.265362Z","caller":"traceutil/trace.go:171","msg":"trace[424471870] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"127.198037ms","start":"2026-09-29T04:44:55.138105Z","end":"2026-09-29T04:44:55.265303Z","steps":["trace[424471870] 'process raft request' (duration: 126.698583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:55.547235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.204758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:44:55.547502Z","caller":"traceutil/trace.go:171","msg":"trace[779672300] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"166.706022ms","start":"2026-09-29T04:44:55.380775Z","end":"2026-09-29T04:44:55.547481Z","steps":["trace[779672300] 'process raft request' (duration: 166.595889ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:55.547594Z","caller":"traceutil/trace.go:171","msg":"trace[1192534900] linearizableReadLoop","detail":"{readStateIndex:27304; appliedIndex:27303; }","duration":"275.951137ms","start":"2026-09-29T04:44:55.271604Z","end":"2026-09-29T04:44:55.547555Z","steps":["trace[1192534900] 'read index received' (duration: 134.481091ms)","trace[1192534900] 'applied index is now lower than readState.Index' (duration: 141.460665ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:55.547797Z","caller":"traceutil/trace.go:171","msg":"trace[1745794086] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"302.305754ms","start":"2026-09-29T04:44:55.245452Z","end":"2026-09-29T04:44:55.547758Z","steps":["trace[1745794086] 'process raft request' (duration: 160.454498ms)","trace[1745794086] 'compare' (duration: 140.91207ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:55.54791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.276656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-29T04:44:55.547949Z","caller":"traceutil/trace.go:171","msg":"trace[795889701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26434; }","duration":"276.374188ms","start":"2026-09-29T04:44:55.271562Z","end":"2026-09-29T04:44:55.547936Z","steps":["trace[795889701] 'agreement among raft nodes before linearized reading' (duration: 276.117751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:55.548011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:55.245425Z","time spent":"302.461289ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:55.678132Z","caller":"traceutil/trace.go:171","msg":"trace[149473347] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"125.036537ms","start":"2026-09-29T04:44:55.553067Z","end":"2026-09-29T04:44:55.678104Z","steps":["trace[149473347] 'process raft request' (duration: 109.910674ms)","trace[149473347] 'compare' (duration: 14.997569ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:55.935437Z","caller":"traceutil/trace.go:171","msg":"trace[604774652] linearizableReadLoop","detail":"{readStateIndex:27307; appliedIndex:27305; }","duration":"286.960406ms","start":"2026-09-29T04:44:55.64844Z","end":"2026-09-29T04:44:55.935401Z","steps":["trace[604774652] 'read index received' (duration: 14.689141ms)","trace[604774652] 'applied index is now lower than readState.Index' (duration: 272.269905ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:55.935723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.269264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-09-29T04:44:55.93577Z","caller":"traceutil/trace.go:171","msg":"trace[477955204] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26436; }","duration":"287.388038ms","start":"2026-09-29T04:44:55.648367Z","end":"2026-09-29T04:44:55.935755Z","steps":["trace[477955204] 'agreement among raft nodes before linearized reading' (duration: 287.156112ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:55.936041Z","caller":"traceutil/trace.go:171","msg":"trace[2066853588] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"340.998057ms","start":"2026-09-29T04:44:55.594988Z","end":"2026-09-29T04:44:55.935986Z","steps":["trace[2066853588] 'process raft request' (duration: 305.366531ms)","trace[2066853588] 'compare' (duration: 34.906266ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:55.936472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:55.594953Z","time spent":"341.176102ms","remote":"127.0.0.1:59472","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-09-29T04:44:56.198376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.661906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-29T04:44:56.198556Z","caller":"traceutil/trace.go:171","msg":"trace[1518848673] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26437; }","duration":"144.912863ms","start":"2026-09-29T04:44:56.053567Z","end":"2026-09-29T04:44:56.19848Z","steps":["trace[1518848673] 'range keys from in-memory index tree' (duration: 144.313627ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:57.058231Z","caller":"traceutil/trace.go:171","msg":"trace[1072758085] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"431.759915ms","start":"2026-09-29T04:44:56.626447Z","end":"2026-09-29T04:44:57.058207Z","steps":["trace[1072758085] 'process raft request' (duration: 431.492518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:57.058418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:56.626395Z","time spent":"431.91743ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:57.058618Z","caller":"traceutil/trace.go:171","msg":"trace[1992527511] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"432.056274ms","start":"2026-09-29T04:44:56.626529Z","end":"2026-09-29T04:44:57.058585Z","steps":["trace[1992527511] 'process raft request' (duration: 431.591181ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:57.05872Z","caller":"traceutil/trace.go:171","msg":"trace[1833918075] linearizableReadLoop","detail":"{readStateIndex:27315; appliedIndex:27311; }","duration":"224.753996ms","start":"2026-09-29T04:44:56.833951Z","end":"2026-09-29T04:44:57.058705Z","steps":["trace[1833918075] 'read index received' (duration: 224.067057ms)","trace[1833918075] 'applied index is now lower than readState.Index' (duration: 685.519µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:57.058792Z","caller":"traceutil/trace.go:171","msg":"trace[655563125] transaction","detail":"{read_only:false; response_revision:26443; number_of_response:1; }","duration":"432.139437ms","start":"2026-09-29T04:44:56.626641Z","end":"2026-09-29T04:44:57.058781Z","steps":["trace[655563125] 'process raft request' (duration: 431.777676ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:57.058847Z","caller":"traceutil/trace.go:171","msg":"trace[2018046686] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"400.866452ms","start":"2026-09-29T04:44:56.657967Z","end":"2026-09-29T04:44:57.058834Z","steps":["trace[2018046686] 'process raft request' (duration: 400.680177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:57.058889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:56.65795Z","time spent":"400.913263ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:57.058891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:56.626501Z","time spent":"432.222299ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:57.059009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:56.626631Z","time spent":"432.178167ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:44:57.059047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.100385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-09-29T04:44:57.059114Z","caller":"traceutil/trace.go:171","msg":"trace[17858087] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26444; }","duration":"225.169717ms","start":"2026-09-29T04:44:56.833926Z","end":"2026-09-29T04:44:57.059096Z","steps":["trace[17858087] 'agreement among raft nodes before linearized reading' (duration: 224.978212ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:57.068585Z","caller":"traceutil/trace.go:171","msg":"trace[704508608] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"205.325863ms","start":"2026-09-29T04:44:56.863226Z","end":"2026-09-29T04:44:57.068552Z","steps":["trace[704508608] 'process raft request' (duration: 205.060345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:57.068822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.21854ms","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-09-29T04:44:57.068903Z","caller":"traceutil/trace.go:171","msg":"trace[1046273965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26445; }","duration":"185.339904ms","start":"2026-09-29T04:44:56.883548Z","end":"2026-09-29T04:44:57.068888Z","steps":["trace[1046273965] 'agreement among raft nodes before linearized reading' (duration: 185.17683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:57.069178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.628322ms","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-09-29T04:44:57.069238Z","caller":"traceutil/trace.go:171","msg":"trace[186236527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26445; }","duration":"185.746506ms","start":"2026-09-29T04:44:56.883475Z","end":"2026-09-29T04:44:57.069221Z","steps":["trace[186236527] 'agreement among raft nodes before linearized reading' (duration: 185.526699ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:44:57.279192Z","caller":"traceutil/trace.go:171","msg":"trace[1280579908] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"205.21605ms","start":"2026-09-29T04:44:57.073912Z","end":"2026-09-29T04:44:57.279128Z","steps":["trace[1280579908] 'process raft request' (duration: 184.180162ms)","trace[1280579908] 'compare' (duration: 20.781821ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:44:57.300171Z","caller":"traceutil/trace.go:171","msg":"trace[1443923822] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"224.112588ms","start":"2026-09-29T04:44:57.075994Z","end":"2026-09-29T04:44:57.300106Z","steps":["trace[1443923822] 'process raft request' (duration: 223.872511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:57.656029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.558933ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-29T04:44:57.656617Z","caller":"traceutil/trace.go:171","msg":"trace[330291119] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"382.522499ms","start":"2026-09-29T04:44:57.27407Z","end":"2026-09-29T04:44:57.656593Z","steps":["trace[330291119] 'process raft request' (duration: 382.109187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:44:57.656752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:44:57.274045Z","time spent":"382.622492ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:44:57.656603Z","caller":"traceutil/trace.go:171","msg":"trace[571022351] linearizableReadLoop","detail":"{readStateIndex:27321; appliedIndex:27319; }","duration":"104.121723ms","start":"2026-09-29T04:44:57.552441Z","end":"2026-09-29T04:44:57.656563Z","steps":["trace[571022351] 'read index received' (duration: 62.982µs)","trace[571022351] 'applied index is now lower than readState.Index' (duration: 104.055631ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:44:57.676276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.820863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-29T04:44:57.676357Z","caller":"traceutil/trace.go:171","msg":"trace[180335939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26449; }","duration":"123.923827ms","start":"2026-09-29T04:44:57.552411Z","end":"2026-09-29T04:44:57.676335Z","steps":["trace[180335939] 'agreement among raft nodes before linearized reading' (duration: 104.255387ms)","trace[180335939] 'range keys from in-memory index tree' (duration: 19.377562ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:03.545855Z","caller":"traceutil/trace.go:171","msg":"trace[527097234] linearizableReadLoop","detail":"{readStateIndex:27389; appliedIndex:27388; }","duration":"200.553299ms","start":"2026-09-29T04:45:03.345273Z","end":"2026-09-29T04:45:03.545826Z","steps":["trace[527097234] 'read index received' (duration: 179.926432ms)","trace[527097234] 'applied index is now lower than readState.Index' (duration: 20.625697ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:03.545913Z","caller":"traceutil/trace.go:171","msg":"trace[1463878830] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"221.446604ms","start":"2026-09-29T04:45:03.324407Z","end":"2026-09-29T04:45:03.545853Z","steps":["trace[1463878830] 'process raft request' (duration: 200.809756ms)","trace[1463878830] 'compare' (duration: 20.432162ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:03.546278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.219587ms","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-09-29T04:45:03.546341Z","caller":"traceutil/trace.go:171","msg":"trace[373289834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26516; }","duration":"221.32682ms","start":"2026-09-29T04:45:03.325003Z","end":"2026-09-29T04:45:03.546329Z","steps":["trace[373289834] 'agreement among raft nodes before linearized reading' (duration: 220.98961ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:03.565539Z","caller":"traceutil/trace.go:171","msg":"trace[1157229387] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"213.47522ms","start":"2026-09-29T04:45:03.352029Z","end":"2026-09-29T04:45:03.565505Z","steps":["trace[1157229387] 'process raft request' (duration: 213.175942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:03.895648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.352988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:45:03.895927Z","caller":"traceutil/trace.go:171","msg":"trace[960763800] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"347.776977ms","start":"2026-09-29T04:45:03.548108Z","end":"2026-09-29T04:45:03.895885Z","steps":["trace[960763800] 'process raft request' (duration: 164.089949ms)","trace[960763800] 'compare' (duration: 183.07325ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:03.895991Z","caller":"traceutil/trace.go:171","msg":"trace[927933955] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"217.239606ms","start":"2026-09-29T04:45:03.678725Z","end":"2026-09-29T04:45:03.895964Z","steps":["trace[927933955] 'process raft request' (duration: 217.190144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:03.896093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.548081Z","time spent":"347.928241ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T04:45:03.896083Z","caller":"traceutil/trace.go:171","msg":"trace[515911370] linearizableReadLoop","detail":"{readStateIndex:27392; appliedIndex:27390; }","duration":"280.357981ms","start":"2026-09-29T04:45:03.615677Z","end":"2026-09-29T04:45:03.896035Z","steps":["trace[515911370] 'read index received' (duration: 96.409346ms)","trace[515911370] 'applied index is now lower than readState.Index' (duration: 183.943065ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:03.896106Z","caller":"traceutil/trace.go:171","msg":"trace[28419260] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"344.731961ms","start":"2026-09-29T04:45:03.551316Z","end":"2026-09-29T04:45:03.896048Z","steps":["trace[28419260] 'process raft request' (duration: 344.462794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:03.896388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.551285Z","time spent":"344.945177ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:45:03.896343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.630639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:45:03.896479Z","caller":"traceutil/trace.go:171","msg":"trace[1574813068] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26520; }","duration":"280.833324ms","start":"2026-09-29T04:45:03.61562Z","end":"2026-09-29T04:45:03.896453Z","steps":["trace[1574813068] 'agreement among raft nodes before linearized reading' (duration: 280.611488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:03.896514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.147928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-29T04:45:03.896529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.756203ms","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-09-29T04:45:03.896567Z","caller":"traceutil/trace.go:171","msg":"trace[1748875789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26520; }","duration":"180.270892ms","start":"2026-09-29T04:45:03.716284Z","end":"2026-09-29T04:45:03.896555Z","steps":["trace[1748875789] 'agreement among raft nodes before linearized reading' (duration: 180.000095ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:03.896574Z","caller":"traceutil/trace.go:171","msg":"trace[44395522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26520; }","duration":"176.826516ms","start":"2026-09-29T04:45:03.719736Z","end":"2026-09-29T04:45:03.896562Z","steps":["trace[44395522] 'agreement among raft nodes before linearized reading' (duration: 176.655451ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:04.311166Z","caller":"traceutil/trace.go:171","msg":"trace[835957322] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"408.32897ms","start":"2026-09-29T04:45:03.90275Z","end":"2026-09-29T04:45:04.311079Z","steps":["trace[835957322] 'process raft request' (duration: 407.893158ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:04.311496Z","caller":"traceutil/trace.go:171","msg":"trace[1569585105] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"408.586947ms","start":"2026-09-29T04:45:03.902863Z","end":"2026-09-29T04:45:04.31145Z","steps":["trace[1569585105] 'process raft request' (duration: 407.996741ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:04.311601Z","caller":"traceutil/trace.go:171","msg":"trace[145598362] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"408.701311ms","start":"2026-09-29T04:45:03.902867Z","end":"2026-09-29T04:45:04.311569Z","steps":["trace[145598362] 'process raft request' (duration: 408.354761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.311711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.902848Z","time spent":"408.742812ms","remote":"127.0.0.1:59472","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-09-29T04:45:04.311715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.902728Z","time spent":"408.534036ms","remote":"127.0.0.1:59378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":658,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T04:45:04.311764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.902836Z","time spent":"408.848485ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T04:45:04.312355Z","caller":"traceutil/trace.go:171","msg":"trace[1768983928] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"409.439411ms","start":"2026-09-29T04:45:03.902895Z","end":"2026-09-29T04:45:04.312335Z","steps":["trace[1768983928] 'process raft request' (duration: 408.606578ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:04.312378Z","caller":"traceutil/trace.go:171","msg":"trace[959278636] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"408.110985ms","start":"2026-09-29T04:45:03.904251Z","end":"2026-09-29T04:45:04.312362Z","steps":["trace[959278636] 'process raft request' (duration: 408.014592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.312428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.902885Z","time spent":"409.512934ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T04:45:04.312474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.904237Z","time spent":"408.178446ms","remote":"127.0.0.1:59472","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-09-29T04:45:04.385957Z","caller":"traceutil/trace.go:171","msg":"trace[211212878] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"408.357921ms","start":"2026-09-29T04:45:03.977572Z","end":"2026-09-29T04:45:04.38593Z","steps":["trace[211212878] 'process raft request' (duration: 408.147445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.38608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:03.977543Z","time spent":"408.468584ms","remote":"127.0.0.1:59472","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-09-29T04:45:04.651281Z","caller":"traceutil/trace.go:171","msg":"trace[1922396887] linearizableReadLoop","detail":"{readStateIndex:27402; appliedIndex:27400; }","duration":"265.336671ms","start":"2026-09-29T04:45:04.385914Z","end":"2026-09-29T04:45:04.651251Z","steps":["trace[1922396887] 'read index received' (duration: 264.983951ms)","trace[1922396887] 'applied index is now lower than readState.Index' (duration: 351.99µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:04.651421Z","caller":"traceutil/trace.go:171","msg":"trace[1234265560] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"332.632294ms","start":"2026-09-29T04:45:04.318779Z","end":"2026-09-29T04:45:04.651411Z","steps":["trace[1234265560] 'process raft request' (duration: 332.403837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.651522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.318757Z","time spent":"332.691635ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1294,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T04:45:04.651475Z","caller":"traceutil/trace.go:171","msg":"trace[2121232411] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"332.86102ms","start":"2026-09-29T04:45:04.318562Z","end":"2026-09-29T04:45:04.651423Z","steps":["trace[2121232411] 'process raft request' (duration: 332.413508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.651811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.31852Z","time spent":"333.122367ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T04:45:04.652226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.161843ms","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-09-29T04:45:04.652326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.257469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-09-29T04:45:04.652425Z","caller":"traceutil/trace.go:171","msg":"trace[1884753461] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:26529; }","duration":"334.429253ms","start":"2026-09-29T04:45:04.317973Z","end":"2026-09-29T04:45:04.652402Z","steps":["trace[1884753461] 'agreement among raft nodes before linearized reading' (duration: 333.890328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.65248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.317952Z","time spent":"334.516216ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-09-29T04:45:04.652722Z","caller":"traceutil/trace.go:171","msg":"trace[836628727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26529; }","duration":"119.391529ms","start":"2026-09-29T04:45:04.53296Z","end":"2026-09-29T04:45:04.652352Z","steps":["trace[836628727] 'agreement among raft nodes before linearized reading' (duration: 118.830693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:04.87826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.430501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:45:04.878385Z","caller":"traceutil/trace.go:171","msg":"trace[1460659472] linearizableReadLoop","detail":"{readStateIndex:27403; appliedIndex:27402; }","duration":"220.132937ms","start":"2026-09-29T04:45:04.658229Z","end":"2026-09-29T04:45:04.878362Z","steps":["trace[1460659472] 'read index received' (duration: 100.510271ms)","trace[1460659472] 'applied index is now lower than readState.Index' (duration: 119.621176ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:04.878483Z","caller":"traceutil/trace.go:171","msg":"trace[1550520233] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"221.206176ms","start":"2026-09-29T04:45:04.657233Z","end":"2026-09-29T04:45:04.878439Z","steps":["trace[1550520233] 'process raft request' (duration: 101.482908ms)","trace[1550520233] 'compare' (duration: 118.987188ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:04.878641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.400894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-09-29T04:45:04.878694Z","caller":"traceutil/trace.go:171","msg":"trace[504481362] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:26530; }","duration":"220.504827ms","start":"2026-09-29T04:45:04.658173Z","end":"2026-09-29T04:45:04.878678Z","steps":["trace[504481362] 'agreement among raft nodes before linearized reading' (duration: 220.277771ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:05.06119Z","caller":"traceutil/trace.go:171","msg":"trace[1579253321] linearizableReadLoop","detail":"{readStateIndex:27406; appliedIndex:27403; }","duration":"182.72232ms","start":"2026-09-29T04:45:04.878419Z","end":"2026-09-29T04:45:05.061141Z","steps":["trace[1579253321] 'read index received' (duration: 171.519917ms)","trace[1579253321] 'applied index is now lower than readState.Index' (duration: 11.201293ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:05.061357Z","caller":"traceutil/trace.go:171","msg":"trace[142543266] transaction","detail":"{read_only:false; response_revision:26532; number_of_response:1; }","duration":"402.803826ms","start":"2026-09-29T04:45:04.658514Z","end":"2026-09-29T04:45:05.061318Z","steps":["trace[142543266] 'process raft request' (duration: 402.490207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:05.061505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.658506Z","time spent":"402.885458ms","remote":"127.0.0.1:59472","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-09-29T04:45:05.061567Z","caller":"traceutil/trace.go:171","msg":"trace[1574628170] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"337.170631ms","start":"2026-09-29T04:45:04.724347Z","end":"2026-09-29T04:45:05.061517Z","steps":["trace[1574628170] 'process raft request' (duration: 336.746489ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:05.061694Z","caller":"traceutil/trace.go:171","msg":"trace[374868479] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"403.29904ms","start":"2026-09-29T04:45:04.658358Z","end":"2026-09-29T04:45:05.061657Z","steps":["trace[374868479] 'process raft request' (duration: 391.593542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:05.061677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.897719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:45:05.061824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.724327Z","time spent":"337.348385ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:45:05.061871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.658341Z","time spent":"403.444624ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T04:45:05.061852Z","caller":"traceutil/trace.go:171","msg":"trace[205388142] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26533; }","duration":"403.121025ms","start":"2026-09-29T04:45:04.658663Z","end":"2026-09-29T04:45:05.061784Z","steps":["trace[205388142] 'agreement among raft nodes before linearized reading' (duration: 402.855508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:05.062544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.651144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-09-29T04:45:05.062623Z","caller":"traceutil/trace.go:171","msg":"trace[1552651774] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26533; }","duration":"342.797367ms","start":"2026-09-29T04:45:04.719807Z","end":"2026-09-29T04:45:05.062605Z","steps":["trace[1552651774] 'agreement among raft nodes before linearized reading' (duration: 341.549652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:05.062666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.719779Z","time spent":"342.87602ms","remote":"127.0.0.1:59342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225432,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-09-29T04:45:05.062687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:04.65864Z","time spent":"403.967588ms","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-09-29T04:45:05.082117Z","caller":"traceutil/trace.go:171","msg":"trace[2091841436] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"198.173613ms","start":"2026-09-29T04:45:04.883899Z","end":"2026-09-29T04:45:05.082072Z","steps":["trace[2091841436] 'process raft request' (duration: 197.982617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:05.08224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.890629ms","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-09-29T04:45:05.082329Z","caller":"traceutil/trace.go:171","msg":"trace[1206270052] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:26534; }","duration":"191.020502ms","start":"2026-09-29T04:45:04.891289Z","end":"2026-09-29T04:45:05.082309Z","steps":["trace[1206270052] 'agreement among raft nodes before linearized reading' (duration: 190.727684ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:06.595245Z","caller":"traceutil/trace.go:171","msg":"trace[1966245138] transaction","detail":"{read_only:false; response_revision:26591; number_of_response:1; }","duration":"113.318359ms","start":"2026-09-29T04:45:06.481896Z","end":"2026-09-29T04:45:06.595214Z","steps":["trace[1966245138] 'process raft request' (duration: 113.106843ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:14.083223Z","caller":"traceutil/trace.go:171","msg":"trace[1412573770] linearizableReadLoop","detail":"{readStateIndex:27559; appliedIndex:27558; }","duration":"133.984967ms","start":"2026-09-29T04:45:13.949207Z","end":"2026-09-29T04:45:14.083192Z","steps":["trace[1412573770] 'read index received' (duration: 133.697289ms)","trace[1412573770] 'applied index is now lower than readState.Index' (duration: 286.558µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:14.083312Z","caller":"traceutil/trace.go:171","msg":"trace[1159395630] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"140.85561ms","start":"2026-09-29T04:45:13.942422Z","end":"2026-09-29T04:45:14.083278Z","steps":["trace[1159395630] 'process raft request' (duration: 140.564241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:14.08353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.294516ms","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-09-29T04:45:14.083628Z","caller":"traceutil/trace.go:171","msg":"trace[2052535476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26683; }","duration":"134.42741ms","start":"2026-09-29T04:45:13.949177Z","end":"2026-09-29T04:45:14.083604Z","steps":["trace[2052535476] 'agreement among raft nodes before linearized reading' (duration: 134.141622ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:14.225002Z","caller":"traceutil/trace.go:171","msg":"trace[1851456734] transaction","detail":"{read_only:false; response_revision:26684; number_of_response:1; }","duration":"136.017804ms","start":"2026-09-29T04:45:14.088943Z","end":"2026-09-29T04:45:14.224961Z","steps":["trace[1851456734] 'process raft request' (duration: 134.819121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:22.471685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.996205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-29T04:45:22.471775Z","caller":"traceutil/trace.go:171","msg":"trace[1815904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26749; }","duration":"209.186421ms","start":"2026-09-29T04:45:22.26257Z","end":"2026-09-29T04:45:22.471756Z","steps":["trace[1815904] 'range keys from in-memory index tree' (duration: 208.748239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:22.781082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.547907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:45:22.781234Z","caller":"traceutil/trace.go:171","msg":"trace[1736138881] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"298.647023ms","start":"2026-09-29T04:45:22.482564Z","end":"2026-09-29T04:45:22.781211Z","steps":["trace[1736138881] 'process raft request' (duration: 145.857969ms)","trace[1736138881] 'compare' (duration: 152.347351ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:23.07552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.445185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:45:23.075627Z","caller":"traceutil/trace.go:171","msg":"trace[1751999263] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26753; }","duration":"127.625189ms","start":"2026-09-29T04:45:22.947974Z","end":"2026-09-29T04:45:23.0756Z","steps":["trace[1751999263] 'count revisions from in-memory index tree' (duration: 127.340331ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:23.243384Z","caller":"traceutil/trace.go:171","msg":"trace[57333730] linearizableReadLoop","detail":"{readStateIndex:27632; appliedIndex:27631; }","duration":"101.299643ms","start":"2026-09-29T04:45:23.142046Z","end":"2026-09-29T04:45:23.243346Z","steps":["trace[57333730] 'read index received' (duration: 101.099838ms)","trace[57333730] 'applied index is now lower than readState.Index' (duration: 198.305µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:23.243804Z","caller":"traceutil/trace.go:171","msg":"trace[1516303328] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"106.959901ms","start":"2026-09-29T04:45:23.136828Z","end":"2026-09-29T04:45:23.243788Z","steps":["trace[1516303328] 'process raft request' (duration: 106.291313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:23.244552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.484166ms","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:8"} {"level":"info","ts":"2026-09-29T04:45:23.244673Z","caller":"traceutil/trace.go:171","msg":"trace[434482489] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26754; }","duration":"102.680041ms","start":"2026-09-29T04:45:23.141963Z","end":"2026-09-29T04:45:23.244643Z","steps":["trace[434482489] 'agreement among raft nodes before linearized reading' (duration: 102.384303ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:23.391422Z","caller":"traceutil/trace.go:171","msg":"trace[1368718321] linearizableReadLoop","detail":"{readStateIndex:27633; appliedIndex:27632; }","duration":"147.820805ms","start":"2026-09-29T04:45:23.243566Z","end":"2026-09-29T04:45:23.391387Z","steps":["trace[1368718321] 'read index received' (duration: 147.423093ms)","trace[1368718321] 'applied index is now lower than readState.Index' (duration: 396.001µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:23.391524Z","caller":"traceutil/trace.go:171","msg":"trace[1999726192] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"237.718708ms","start":"2026-09-29T04:45:23.153762Z","end":"2026-09-29T04:45:23.391481Z","steps":["trace[1999726192] 'process raft request' (duration: 237.383779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:23.391744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.856267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-29T04:45:23.391823Z","caller":"traceutil/trace.go:171","msg":"trace[278447740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26755; }","duration":"225.98902ms","start":"2026-09-29T04:45:23.165809Z","end":"2026-09-29T04:45:23.391798Z","steps":["trace[278447740] 'agreement among raft nodes before linearized reading' (duration: 225.754383ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:23.392266Z","caller":"traceutil/trace.go:171","msg":"trace[1989518947] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"145.906211ms","start":"2026-09-29T04:45:23.246347Z","end":"2026-09-29T04:45:23.392253Z","steps":["trace[1989518947] 'process raft request' (duration: 145.652434ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:23.39269Z","caller":"traceutil/trace.go:171","msg":"trace[1402667262] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"133.441342ms","start":"2026-09-29T04:45:23.259227Z","end":"2026-09-29T04:45:23.392668Z","steps":["trace[1402667262] 'process raft request' (duration: 133.233767ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:23.392767Z","caller":"traceutil/trace.go:171","msg":"trace[526078879] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"133.719569ms","start":"2026-09-29T04:45:23.258989Z","end":"2026-09-29T04:45:23.392709Z","steps":["trace[526078879] 'process raft request' (duration: 133.211955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:23.392839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.228371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-29T04:45:23.393097Z","caller":"traceutil/trace.go:171","msg":"trace[227724104] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26758; }","duration":"121.56287ms","start":"2026-09-29T04:45:23.27152Z","end":"2026-09-29T04:45:23.393082Z","steps":["trace[227724104] 'agreement among raft nodes before linearized reading' (duration: 121.098157ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:31.684026Z","caller":"traceutil/trace.go:171","msg":"trace[1029792151] transaction","detail":"{read_only:false; response_revision:26814; number_of_response:1; }","duration":"256.427722ms","start":"2026-09-29T04:45:31.427564Z","end":"2026-09-29T04:45:31.683991Z","steps":["trace[1029792151] 'process raft request' (duration: 216.926437ms)","trace[1029792151] 'compare' (duration: 39.182626ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:31.684463Z","caller":"traceutil/trace.go:171","msg":"trace[992602700] transaction","detail":"{read_only:false; response_revision:26815; number_of_response:1; }","duration":"256.151444ms","start":"2026-09-29T04:45:31.428266Z","end":"2026-09-29T04:45:31.684417Z","steps":["trace[992602700] 'process raft request' (duration: 255.67553ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:31.684636Z","caller":"traceutil/trace.go:171","msg":"trace[1342033353] transaction","detail":"{read_only:false; response_revision:26816; number_of_response:1; }","duration":"255.97082ms","start":"2026-09-29T04:45:31.428621Z","end":"2026-09-29T04:45:31.684592Z","steps":["trace[1342033353] 'process raft request' (duration: 255.655261ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:31.684722Z","caller":"traceutil/trace.go:171","msg":"trace[1630930369] linearizableReadLoop","detail":"{readStateIndex:27695; appliedIndex:27691; }","duration":"248.603153ms","start":"2026-09-29T04:45:31.436073Z","end":"2026-09-29T04:45:31.684676Z","steps":["trace[1630930369] 'read index received' (duration: 19.68317ms)","trace[1630930369] 'applied index is now lower than readState.Index' (duration: 228.914923ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:31.68488Z","caller":"traceutil/trace.go:171","msg":"trace[83541431] transaction","detail":"{read_only:false; response_revision:26817; number_of_response:1; }","duration":"224.249262ms","start":"2026-09-29T04:45:31.460581Z","end":"2026-09-29T04:45:31.68483Z","steps":["trace[83541431] 'process raft request' (duration: 224.021196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:31.684993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.629786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:45:31.685039Z","caller":"traceutil/trace.go:171","msg":"trace[1777222897] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26818; }","duration":"109.75617ms","start":"2026-09-29T04:45:31.575265Z","end":"2026-09-29T04:45:31.685021Z","steps":["trace[1777222897] 'agreement among raft nodes before linearized reading' (duration: 109.624816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:31.685092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.991824ms","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-09-29T04:45:31.685177Z","caller":"traceutil/trace.go:171","msg":"trace[445892682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26818; }","duration":"249.083417ms","start":"2026-09-29T04:45:31.436052Z","end":"2026-09-29T04:45:31.685136Z","steps":["trace[445892682] 'agreement among raft nodes before linearized reading' (duration: 248.806499ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:31.903457Z","caller":"traceutil/trace.go:171","msg":"trace[438474253] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"212.116533ms","start":"2026-09-29T04:45:31.691293Z","end":"2026-09-29T04:45:31.903409Z","steps":["trace[438474253] 'process raft request' (duration: 184.666165ms)","trace[438474253] 'compare' (duration: 26.82806ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:32.055802Z","caller":"traceutil/trace.go:171","msg":"trace[1142342539] linearizableReadLoop","detail":"{readStateIndex:27700; appliedIndex:27697; }","duration":"320.247767ms","start":"2026-09-29T04:45:31.735515Z","end":"2026-09-29T04:45:32.055763Z","steps":["trace[1142342539] 'read index received' (duration: 140.463858ms)","trace[1142342539] 'applied index is now lower than readState.Index' (duration: 179.782369ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:32.055996Z","caller":"traceutil/trace.go:171","msg":"trace[2114865170] transaction","detail":"{read_only:false; response_revision:26821; number_of_response:1; }","duration":"337.981153ms","start":"2026-09-29T04:45:31.717998Z","end":"2026-09-29T04:45:32.055979Z","steps":["trace[2114865170] 'process raft request' (duration: 337.614803ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:32.056091Z","caller":"traceutil/trace.go:171","msg":"trace[1582270041] transaction","detail":"{read_only:false; response_revision:26820; number_of_response:1; }","duration":"356.943193ms","start":"2026-09-29T04:45:31.699118Z","end":"2026-09-29T04:45:32.056061Z","steps":["trace[1582270041] 'process raft request' (duration: 315.404421ms)","trace[1582270041] 'compare' (duration: 40.795291ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:32.056234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.425408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-29T04:45:32.05631Z","caller":"traceutil/trace.go:171","msg":"trace[532295887] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26821; }","duration":"317.538891ms","start":"2026-09-29T04:45:31.738753Z","end":"2026-09-29T04:45:32.056292Z","steps":["trace[532295887] 'agreement among raft nodes before linearized reading' (duration: 317.315494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.056343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:31.738743Z","time spent":"317.592883ms","remote":"127.0.0.1:59372","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-manila-server-0\" "} {"level":"warn","ts":"2026-09-29T04:45:32.056383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.866774ms","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-09-29T04:45:32.056432Z","caller":"traceutil/trace.go:171","msg":"trace[874750632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26821; }","duration":"320.971457ms","start":"2026-09-29T04:45:31.735446Z","end":"2026-09-29T04:45:32.056417Z","steps":["trace[874750632] 'agreement among raft nodes before linearized reading' (duration: 320.776782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.056468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:31.735416Z","time spent":"321.044499ms","remote":"127.0.0.1:59472","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-09-29T04:45:32.056278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:31.699084Z","time spent":"357.138769ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10656,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:45:32.056171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:31.717973Z","time spent":"338.056175ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:45:32.362248Z","caller":"traceutil/trace.go:171","msg":"trace[677288861] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"299.944239ms","start":"2026-09-29T04:45:32.062273Z","end":"2026-09-29T04:45:32.362218Z","steps":["trace[677288861] 'process raft request' (duration: 299.646381ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:32.362503Z","caller":"traceutil/trace.go:171","msg":"trace[268845160] transaction","detail":"{read_only:false; response_revision:26823; number_of_response:1; }","duration":"298.475698ms","start":"2026-09-29T04:45:32.064003Z","end":"2026-09-29T04:45:32.362479Z","steps":["trace[268845160] 'process raft request' (duration: 298.115928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.362618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:32.062238Z","time spent":"300.192116ms","remote":"127.0.0.1:59472","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-09-29T04:45:32.362939Z","caller":"traceutil/trace.go:171","msg":"trace[951021038] transaction","detail":"{read_only:false; response_revision:26824; number_of_response:1; }","duration":"298.589192ms","start":"2026-09-29T04:45:32.064328Z","end":"2026-09-29T04:45:32.362917Z","steps":["trace[951021038] 'process raft request' (duration: 298.072647ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:32.362972Z","caller":"traceutil/trace.go:171","msg":"trace[1076165999] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"298.279743ms","start":"2026-09-29T04:45:32.064649Z","end":"2026-09-29T04:45:32.362929Z","steps":["trace[1076165999] 'process raft request' (duration: 298.082457ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:32.362916Z","caller":"traceutil/trace.go:171","msg":"trace[1586690386] linearizableReadLoop","detail":"{readStateIndex:27705; appliedIndex:27701; }","duration":"281.319608ms","start":"2026-09-29T04:45:32.081564Z","end":"2026-09-29T04:45:32.362883Z","steps":["trace[1586690386] 'read index received' (duration: 280.437013ms)","trace[1586690386] 'applied index is now lower than readState.Index' (duration: 881.265µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:32.363136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.035108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e2a390c8-4620-454a-b644-c4791e24c82d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-29T04:45:32.363219Z","caller":"traceutil/trace.go:171","msg":"trace[733868749] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e2a390c8-4620-454a-b644-c4791e24c82d; range_end:; response_count:1; response_revision:26825; }","duration":"302.184202ms","start":"2026-09-29T04:45:32.061017Z","end":"2026-09-29T04:45:32.363201Z","steps":["trace[733868749] 'agreement among raft nodes before linearized reading' (duration: 301.962735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.363263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:32.060994Z","time spent":"302.259604ms","remote":"127.0.0.1:59366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-e2a390c8-4620-454a-b644-c4791e24c82d\" "} {"level":"info","ts":"2026-09-29T04:45:32.511846Z","caller":"traceutil/trace.go:171","msg":"trace[358122808] linearizableReadLoop","detail":"{readStateIndex:27708; appliedIndex:27705; }","duration":"148.690348ms","start":"2026-09-29T04:45:32.363132Z","end":"2026-09-29T04:45:32.511823Z","steps":["trace[358122808] 'read index received' (duration: 117.789034ms)","trace[358122808] 'applied index is now lower than readState.Index' (duration: 30.899874ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:32.512057Z","caller":"traceutil/trace.go:171","msg":"trace[1420172718] transaction","detail":"{read_only:false; response_revision:26827; number_of_response:1; }","duration":"426.145059ms","start":"2026-09-29T04:45:32.085868Z","end":"2026-09-29T04:45:32.512013Z","steps":["trace[1420172718] 'process raft request' (duration: 425.816419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.512279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:32.085833Z","time spent":"426.334724ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:45:32.512444Z","caller":"traceutil/trace.go:171","msg":"trace[2069317660] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"349.997369ms","start":"2026-09-29T04:45:32.162425Z","end":"2026-09-29T04:45:32.512422Z","steps":["trace[2069317660] 'process raft request' (duration: 349.3344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.512551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.963565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-09-29T04:45:32.512586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.450935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:45:32.512636Z","caller":"traceutil/trace.go:171","msg":"trace[315423567] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26828; }","duration":"327.110318ms","start":"2026-09-29T04:45:32.185506Z","end":"2026-09-29T04:45:32.512616Z","steps":["trace[315423567] 'agreement among raft nodes before linearized reading' (duration: 326.82714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.512678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:32.185479Z","time spent":"327.191291ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-29T04:45:32.512602Z","caller":"traceutil/trace.go:171","msg":"trace[1145091981] transaction","detail":"{read_only:false; response_revision:26826; number_of_response:1; }","duration":"426.822148ms","start":"2026-09-29T04:45:32.085679Z","end":"2026-09-29T04:45:32.512501Z","steps":["trace[1145091981] 'process raft request' (duration: 395.388659ms)","trace[1145091981] 'compare' (duration: 30.426511ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:32.512819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:32.16238Z","time spent":"350.184284ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:45:32.512681Z","caller":"traceutil/trace.go:171","msg":"trace[444633287] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26828; }","duration":"112.61214ms","start":"2026-09-29T04:45:32.400048Z","end":"2026-09-29T04:45:32.51266Z","steps":["trace[444633287] 'agreement among raft nodes before linearized reading' (duration: 112.312501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.513089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:32.085662Z","time spent":"427.205508ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9632,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:45:32.719588Z","caller":"traceutil/trace.go:171","msg":"trace[261016798] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"194.382066ms","start":"2026-09-29T04:45:32.525179Z","end":"2026-09-29T04:45:32.719561Z","steps":["trace[261016798] 'process raft request' (duration: 112.827726ms)","trace[261016798] 'compare' (duration: 81.285333ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:32.719706Z","caller":"traceutil/trace.go:171","msg":"trace[425835077] linearizableReadLoop","detail":"{readStateIndex:27710; appliedIndex:27708; }","duration":"181.497716ms","start":"2026-09-29T04:45:32.53817Z","end":"2026-09-29T04:45:32.719668Z","steps":["trace[425835077] 'read index received' (duration: 99.855043ms)","trace[425835077] 'applied index is now lower than readState.Index' (duration: 81.640743ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:32.719875Z","caller":"traceutil/trace.go:171","msg":"trace[202583679] transaction","detail":"{read_only:false; response_revision:26830; number_of_response:1; }","duration":"183.192244ms","start":"2026-09-29T04:45:32.536676Z","end":"2026-09-29T04:45:32.719868Z","steps":["trace[202583679] 'process raft request' (duration: 182.846074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.720012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.677499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:45:32.720067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.269519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-29T04:45:32.720091Z","caller":"traceutil/trace.go:171","msg":"trace[782203319] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26830; }","duration":"113.765262ms","start":"2026-09-29T04:45:32.606305Z","end":"2026-09-29T04:45:32.72007Z","steps":["trace[782203319] 'agreement among raft nodes before linearized reading' (duration: 113.633088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:32.72008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.921468ms","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-09-29T04:45:32.720117Z","caller":"traceutil/trace.go:171","msg":"trace[941415250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26830; }","duration":"167.367462ms","start":"2026-09-29T04:45:32.552736Z","end":"2026-09-29T04:45:32.720104Z","steps":["trace[941415250] 'agreement among raft nodes before linearized reading' (duration: 167.158186ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:32.720188Z","caller":"traceutil/trace.go:171","msg":"trace[2023513291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26830; }","duration":"182.044682ms","start":"2026-09-29T04:45:32.538096Z","end":"2026-09-29T04:45:32.72014Z","steps":["trace[2023513291] 'agreement among raft nodes before linearized reading' (duration: 181.837956ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:38.645013Z","caller":"traceutil/trace.go:171","msg":"trace[2116419686] transaction","detail":"{read_only:false; response_revision:26878; number_of_response:1; }","duration":"113.219816ms","start":"2026-09-29T04:45:38.531768Z","end":"2026-09-29T04:45:38.644988Z","steps":["trace[2116419686] 'process raft request' (duration: 73.983949ms)","trace[2116419686] 'compare' (duration: 38.883377ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:41.481806Z","caller":"traceutil/trace.go:171","msg":"trace[984191106] transaction","detail":"{read_only:false; response_revision:26899; number_of_response:1; }","duration":"116.056297ms","start":"2026-09-29T04:45:41.365727Z","end":"2026-09-29T04:45:41.481784Z","steps":["trace[984191106] 'process raft request' (duration: 115.880902ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:41.703901Z","caller":"traceutil/trace.go:171","msg":"trace[1956324025] linearizableReadLoop","detail":"{readStateIndex:27782; appliedIndex:27781; }","duration":"203.979175ms","start":"2026-09-29T04:45:41.499892Z","end":"2026-09-29T04:45:41.703871Z","steps":["trace[1956324025] 'read index received' (duration: 203.787039ms)","trace[1956324025] 'applied index is now lower than readState.Index' (duration: 190.206µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:41.704184Z","caller":"traceutil/trace.go:171","msg":"trace[2098390064] transaction","detail":"{read_only:false; response_revision:26900; number_of_response:1; }","duration":"309.444495ms","start":"2026-09-29T04:45:41.394683Z","end":"2026-09-29T04:45:41.704127Z","steps":["trace[2098390064] 'process raft request' (duration: 308.937921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:41.704214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.298283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-09-29T04:45:41.704267Z","caller":"traceutil/trace.go:171","msg":"trace[1571768806] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26900; }","duration":"204.411376ms","start":"2026-09-29T04:45:41.499841Z","end":"2026-09-29T04:45:41.704252Z","steps":["trace[1571768806] 'agreement among raft nodes before linearized reading' (duration: 204.153929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:41.704354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:45:41.394656Z","time spent":"309.597738ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:45:41.705986Z","caller":"traceutil/trace.go:171","msg":"trace[1108530586] transaction","detail":"{read_only:false; response_revision:26901; number_of_response:1; }","duration":"122.536797ms","start":"2026-09-29T04:45:41.583407Z","end":"2026-09-29T04:45:41.705944Z","steps":["trace[1108530586] 'process raft request' (duration: 122.225098ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:41.866765Z","caller":"traceutil/trace.go:171","msg":"trace[1675918347] transaction","detail":"{read_only:false; response_revision:26903; number_of_response:1; }","duration":"141.805245ms","start":"2026-09-29T04:45:41.724917Z","end":"2026-09-29T04:45:41.866722Z","steps":["trace[1675918347] 'process raft request' (duration: 65.561293ms)","trace[1675918347] 'compare' (duration: 75.724578ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:41.868084Z","caller":"traceutil/trace.go:171","msg":"trace[666559311] transaction","detail":"{read_only:false; response_revision:26904; number_of_response:1; }","duration":"140.915181ms","start":"2026-09-29T04:45:41.727118Z","end":"2026-09-29T04:45:41.868033Z","steps":["trace[666559311] 'process raft request' (duration: 140.355625ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:41.868269Z","caller":"traceutil/trace.go:171","msg":"trace[1057475561] transaction","detail":"{read_only:false; response_revision:26905; number_of_response:1; }","duration":"139.013988ms","start":"2026-09-29T04:45:41.729215Z","end":"2026-09-29T04:45:41.868229Z","steps":["trace[1057475561] 'process raft request' (duration: 138.439242ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:41.868429Z","caller":"traceutil/trace.go:171","msg":"trace[774923758] transaction","detail":"{read_only:false; response_revision:26906; number_of_response:1; }","duration":"139.175413ms","start":"2026-09-29T04:45:41.729241Z","end":"2026-09-29T04:45:41.868416Z","steps":["trace[774923758] 'process raft request' (duration: 138.460293ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:42.830991Z","caller":"traceutil/trace.go:171","msg":"trace[332003418] transaction","detail":"{read_only:false; response_revision:26911; number_of_response:1; }","duration":"172.403152ms","start":"2026-09-29T04:45:42.658558Z","end":"2026-09-29T04:45:42.830961Z","steps":["trace[332003418] 'process raft request' (duration: 172.195556ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:42.871035Z","caller":"traceutil/trace.go:171","msg":"trace[161682706] transaction","detail":"{read_only:false; response_revision:26912; number_of_response:1; }","duration":"105.004297ms","start":"2026-09-29T04:45:42.765985Z","end":"2026-09-29T04:45:42.870989Z","steps":["trace[161682706] 'process raft request' (duration: 104.645366ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.006189Z","caller":"traceutil/trace.go:171","msg":"trace[469053567] linearizableReadLoop","detail":"{readStateIndex:27797; appliedIndex:27795; }","duration":"126.021154ms","start":"2026-09-29T04:45:42.88012Z","end":"2026-09-29T04:45:43.006141Z","steps":["trace[469053567] 'read index received' (duration: 47.256572ms)","trace[469053567] 'applied index is now lower than readState.Index' (duration: 78.763332ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:43.006339Z","caller":"traceutil/trace.go:171","msg":"trace[224003748] transaction","detail":"{read_only:false; response_revision:26914; number_of_response:1; }","duration":"129.362018ms","start":"2026-09-29T04:45:42.876969Z","end":"2026-09-29T04:45:43.006331Z","steps":["trace[224003748] 'process raft request' (duration: 129.119281ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.006281Z","caller":"traceutil/trace.go:171","msg":"trace[326098816] transaction","detail":"{read_only:false; response_revision:26913; number_of_response:1; }","duration":"130.335855ms","start":"2026-09-29T04:45:42.875926Z","end":"2026-09-29T04:45:43.006261Z","steps":["trace[326098816] 'process raft request' (duration: 51.372366ms)","trace[326098816] 'compare' (duration: 78.622399ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:45:43.006528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.400935ms","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-09-29T04:45:43.006619Z","caller":"traceutil/trace.go:171","msg":"trace[780260774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26914; }","duration":"126.526678ms","start":"2026-09-29T04:45:42.880075Z","end":"2026-09-29T04:45:43.006601Z","steps":["trace[780260774] 'agreement among raft nodes before linearized reading' (duration: 126.340513ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.973687Z","caller":"traceutil/trace.go:171","msg":"trace[316970152] transaction","detail":"{read_only:false; response_revision:26917; number_of_response:1; }","duration":"260.737032ms","start":"2026-09-29T04:45:43.712907Z","end":"2026-09-29T04:45:43.973645Z","steps":["trace[316970152] 'process raft request' (duration: 254.188779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:45:43.973858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.128216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:45:43.973971Z","caller":"traceutil/trace.go:171","msg":"trace[1953937758] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26917; }","duration":"240.337972ms","start":"2026-09-29T04:45:43.733614Z","end":"2026-09-29T04:45:43.973952Z","steps":["trace[1953937758] 'agreement among raft nodes before linearized reading' (duration: 240.088885ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.97367Z","caller":"traceutil/trace.go:171","msg":"trace[69703433] linearizableReadLoop","detail":"{readStateIndex:27800; appliedIndex:27798; }","duration":"239.9322ms","start":"2026-09-29T04:45:43.733676Z","end":"2026-09-29T04:45:43.973608Z","steps":["trace[69703433] 'read index received' (duration: 47.688603ms)","trace[69703433] 'applied index is now lower than readState.Index' (duration: 192.241597ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:43.979999Z","caller":"traceutil/trace.go:171","msg":"trace[2083766531] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"104.985757ms","start":"2026-09-29T04:45:43.874965Z","end":"2026-09-29T04:45:43.97995Z","steps":["trace[2083766531] 'process raft request' (duration: 104.661188ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.980069Z","caller":"traceutil/trace.go:171","msg":"trace[936060841] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"104.968556ms","start":"2026-09-29T04:45:43.875077Z","end":"2026-09-29T04:45:43.980045Z","steps":["trace[936060841] 'process raft request' (duration: 104.631697ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.98011Z","caller":"traceutil/trace.go:171","msg":"trace[1361152901] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"104.662797ms","start":"2026-09-29T04:45:43.875378Z","end":"2026-09-29T04:45:43.980041Z","steps":["trace[1361152901] 'process raft request' (duration: 104.41213ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:43.980224Z","caller":"traceutil/trace.go:171","msg":"trace[1163508682] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"107.709522ms","start":"2026-09-29T04:45:43.872474Z","end":"2026-09-29T04:45:43.980184Z","steps":["trace[1163508682] 'process raft request' (duration: 106.90451ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:47.227039Z","caller":"traceutil/trace.go:171","msg":"trace[496422411] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"158.491653ms","start":"2026-09-29T04:45:47.0685Z","end":"2026-09-29T04:45:47.226991Z","steps":["trace[496422411] 'process raft request' (duration: 113.990478ms)","trace[496422411] 'compare' (duration: 44.023711ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:45:47.227323Z","caller":"traceutil/trace.go:171","msg":"trace[367050691] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"157.175726ms","start":"2026-09-29T04:45:47.070106Z","end":"2026-09-29T04:45:47.227282Z","steps":["trace[367050691] 'process raft request' (duration: 156.820486ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:45:59.065914Z","caller":"traceutil/trace.go:171","msg":"trace[1667858213] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"117.149277ms","start":"2026-09-29T04:45:58.948675Z","end":"2026-09-29T04:45:59.065824Z","steps":["trace[1667858213] 'process raft request' (duration: 116.932131ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:46:05.203383Z","caller":"traceutil/trace.go:171","msg":"trace[1816150796] transaction","detail":"{read_only:false; response_revision:27071; number_of_response:1; }","duration":"113.168475ms","start":"2026-09-29T04:46:05.090177Z","end":"2026-09-29T04:46:05.203346Z","steps":["trace[1816150796] 'process raft request' (duration: 33.108546ms)","trace[1816150796] 'compare' (duration: 79.613967ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:46:05.387617Z","caller":"traceutil/trace.go:171","msg":"trace[537826246] linearizableReadLoop","detail":"{readStateIndex:27959; appliedIndex:27958; }","duration":"155.024416ms","start":"2026-09-29T04:46:05.232558Z","end":"2026-09-29T04:46:05.387583Z","steps":["trace[537826246] 'read index received' (duration: 81.323675ms)","trace[537826246] 'applied index is now lower than readState.Index' (duration: 73.699171ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:46:05.38791Z","caller":"traceutil/trace.go:171","msg":"trace[1329926365] transaction","detail":"{read_only:false; response_revision:27072; number_of_response:1; }","duration":"177.552286ms","start":"2026-09-29T04:46:05.210342Z","end":"2026-09-29T04:46:05.387895Z","steps":["trace[1329926365] 'process raft request' (duration: 103.549096ms)","trace[1329926365] 'compare' (duration: 73.522196ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:46:05.388302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.717875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-29T04:46:05.388347Z","caller":"traceutil/trace.go:171","msg":"trace[1505053247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27072; }","duration":"155.810588ms","start":"2026-09-29T04:46:05.232524Z","end":"2026-09-29T04:46:05.388334Z","steps":["trace[1505053247] 'agreement among raft nodes before linearized reading' (duration: 155.602852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:25.450762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.720604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:46:25.450885Z","caller":"traceutil/trace.go:171","msg":"trace[1491172095] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27371; }","duration":"101.90135ms","start":"2026-09-29T04:46:25.34896Z","end":"2026-09-29T04:46:25.450862Z","steps":["trace[1491172095] 'range keys from in-memory index tree' (duration: 101.623732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:25.450981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.915764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:46:25.451049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.202952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:46:25.451099Z","caller":"traceutil/trace.go:171","msg":"trace[1360414146] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27371; }","duration":"105.140901ms","start":"2026-09-29T04:46:25.345931Z","end":"2026-09-29T04:46:25.451072Z","steps":["trace[1360414146] 'range keys from in-memory index tree' (duration: 104.75711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:25.451117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.058612ms","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-09-29T04:46:25.451192Z","caller":"traceutil/trace.go:171","msg":"trace[52883953] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27371; }","duration":"105.421638ms","start":"2026-09-29T04:46:25.345741Z","end":"2026-09-29T04:46:25.451162Z","steps":["trace[52883953] 'range keys from in-memory index tree' (duration: 105.084569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:25.45109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.06246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:46:25.451252Z","caller":"traceutil/trace.go:171","msg":"trace[2111242797] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27371; }","duration":"103.216257ms","start":"2026-09-29T04:46:25.348009Z","end":"2026-09-29T04:46:25.451225Z","steps":["trace[2111242797] 'range keys from in-memory index tree' (duration: 102.973209ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:46:25.451325Z","caller":"traceutil/trace.go:171","msg":"trace[2068394490] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:27371; }","duration":"104.297197ms","start":"2026-09-29T04:46:25.346972Z","end":"2026-09-29T04:46:25.45127Z","steps":["trace[2068394490] 'count revisions from in-memory index tree' (duration: 103.906436ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:46:25.762245Z","caller":"traceutil/trace.go:171","msg":"trace[250257458] transaction","detail":"{read_only:false; response_revision:27373; number_of_response:1; }","duration":"165.323334ms","start":"2026-09-29T04:46:25.596869Z","end":"2026-09-29T04:46:25.762192Z","steps":["trace[250257458] 'process raft request' (duration: 77.94401ms)","trace[250257458] 'compare' (duration: 87.042865ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:46:25.76225Z","caller":"traceutil/trace.go:171","msg":"trace[2045895538] transaction","detail":"{read_only:false; response_revision:27374; number_of_response:1; }","duration":"165.225672ms","start":"2026-09-29T04:46:25.596978Z","end":"2026-09-29T04:46:25.762204Z","steps":["trace[2045895538] 'process raft request' (duration: 165.119929ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:46:26.37241Z","caller":"traceutil/trace.go:171","msg":"trace[2099041712] transaction","detail":"{read_only:false; response_revision:27376; number_of_response:1; }","duration":"287.589883ms","start":"2026-09-29T04:46:26.084784Z","end":"2026-09-29T04:46:26.372374Z","steps":["trace[2099041712] 'process raft request' (duration: 269.22758ms)","trace[2099041712] 'compare' (duration: 17.976012ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:46:26.674304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.249794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:46:26.674609Z","caller":"traceutil/trace.go:171","msg":"trace[1989339746] linearizableReadLoop","detail":"{readStateIndex:28273; appliedIndex:28267; }","duration":"336.754279ms","start":"2026-09-29T04:46:26.337835Z","end":"2026-09-29T04:46:26.674589Z","steps":["trace[1989339746] 'read index received' (duration: 16.195653ms)","trace[1989339746] 'applied index is now lower than readState.Index' (duration: 320.557436ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:46:26.674617Z","caller":"traceutil/trace.go:171","msg":"trace[1116933850] transaction","detail":"{read_only:false; response_revision:27378; number_of_response:1; }","duration":"509.303885ms","start":"2026-09-29T04:46:26.165292Z","end":"2026-09-29T04:46:26.674596Z","steps":["trace[1116933850] 'process raft request' (duration: 509.12707ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:46:26.674703Z","caller":"traceutil/trace.go:171","msg":"trace[253561210] transaction","detail":"{read_only:false; response_revision:27379; number_of_response:1; }","duration":"495.025255ms","start":"2026-09-29T04:46:26.179669Z","end":"2026-09-29T04:46:26.674694Z","steps":["trace[253561210] 'process raft request' (duration: 494.813159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.674761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.179637Z","time spent":"495.091247ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:46:26.674741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.165266Z","time spent":"509.395717ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:46:26.674958Z","caller":"traceutil/trace.go:171","msg":"trace[1131229084] transaction","detail":"{read_only:false; response_revision:27380; number_of_response:1; }","duration":"490.388086ms","start":"2026-09-29T04:46:26.18456Z","end":"2026-09-29T04:46:26.674948Z","steps":["trace[1131229084] 'process raft request' (duration: 489.954544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.675016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.184546Z","time spent":"490.439437ms","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:46:26.675094Z","caller":"traceutil/trace.go:171","msg":"trace[1063348613] transaction","detail":"{read_only:false; response_revision:27381; number_of_response:1; }","duration":"486.283941ms","start":"2026-09-29T04:46:26.188802Z","end":"2026-09-29T04:46:26.675086Z","steps":["trace[1063348613] 'process raft request' (duration: 485.745886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.675162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.18879Z","time spent":"486.331842ms","remote":"127.0.0.1:59472","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-09-29T04:46:26.675244Z","caller":"traceutil/trace.go:171","msg":"trace[1931757158] transaction","detail":"{read_only:false; response_revision:27377; number_of_response:1; }","duration":"536.765173ms","start":"2026-09-29T04:46:26.138462Z","end":"2026-09-29T04:46:26.675228Z","steps":["trace[1931757158] 'process raft request' (duration: 371.287595ms)","trace[1931757158] 'compare' (duration: 163.690257ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:46:26.67537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.138429Z","time spent":"536.899076ms","remote":"127.0.0.1:59472","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-09-29T04:46:26.675411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.585471ms","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-09-29T04:46:26.675448Z","caller":"traceutil/trace.go:171","msg":"trace[168891449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27381; }","duration":"337.674914ms","start":"2026-09-29T04:46:26.337762Z","end":"2026-09-29T04:46:26.675437Z","steps":["trace[168891449] 'agreement among raft nodes before linearized reading' (duration: 337.51035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.67548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.337732Z","time spent":"337.741566ms","remote":"127.0.0.1:59472","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-09-29T04:46:26.675584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.454812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:46:26.675614Z","caller":"traceutil/trace.go:171","msg":"trace[1053229607] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27381; }","duration":"320.500804ms","start":"2026-09-29T04:46:26.355103Z","end":"2026-09-29T04:46:26.675604Z","steps":["trace[1053229607] 'agreement among raft nodes before linearized reading' (duration: 320.458153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.675637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.355089Z","time spent":"320.542665ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-09-29T04:46:26.67576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.812345ms","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-09-29T04:46:26.675837Z","caller":"traceutil/trace.go:171","msg":"trace[116849420] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27381; }","duration":"319.961119ms","start":"2026-09-29T04:46:26.355858Z","end":"2026-09-29T04:46:26.675819Z","steps":["trace[116849420] 'agreement among raft nodes before linearized reading' (duration: 319.813135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.675871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.355832Z","time spent":"320.034241ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-09-29T04:46:26.675952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.813175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:46:26.675984Z","caller":"traceutil/trace.go:171","msg":"trace[1657690349] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27381; }","duration":"319.864716ms","start":"2026-09-29T04:46:26.356111Z","end":"2026-09-29T04:46:26.675975Z","steps":["trace[1657690349] 'agreement among raft nodes before linearized reading' (duration: 319.819395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.675789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.917878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:46:26.676013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.356103Z","time spent":"319.900697ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-09-29T04:46:26.67602Z","caller":"traceutil/trace.go:171","msg":"trace[333131494] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27381; }","duration":"320.150585ms","start":"2026-09-29T04:46:26.355863Z","end":"2026-09-29T04:46:26.676014Z","steps":["trace[333131494] 'agreement among raft nodes before linearized reading' (duration: 319.902948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:46:26.676037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:46:26.355787Z","time spent":"320.244577ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-09-29T04:46:58.575541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24747} {"level":"info","ts":"2026-09-29T04:46:58.735677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24747,"took":"152.985449ms","hash":1468539716,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":36302848,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-29T04:46:58.735764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1468539716,"revision":24747,"compact-revision":21685} {"level":"info","ts":"2026-09-29T04:49:29.935733Z","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-09-29T04:49:29.943709Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-29T04:49:29.943836Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-29T04:51:58.583755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27650} {"level":"info","ts":"2026-09-29T04:51:58.739882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27650,"took":"149.4511ms","hash":2791677089,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":35246080,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-29T04:51:58.739989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2791677089,"revision":27650,"compact-revision":24747} {"level":"info","ts":"2026-09-29T04:56:58.591044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30351} {"level":"info","ts":"2026-09-29T04:56:58.754793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30351,"took":"158.419472ms","hash":1215214837,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30687232,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-29T04:56:58.754895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1215214837,"revision":30351,"compact-revision":27650} {"level":"info","ts":"2026-09-29T05:01:58.599007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32599} {"level":"info","ts":"2026-09-29T05:01:58.770938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32599,"took":"165.378778ms","hash":2429915179,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-29T05:01:58.77104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2429915179,"revision":32599,"compact-revision":30351} {"level":"info","ts":"2026-09-29T05:06:58.608281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34887} {"level":"info","ts":"2026-09-29T05:06:58.753832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34887,"took":"139.573101ms","hash":106462644,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":31158272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-29T05:06:58.753931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":106462644,"revision":34887,"compact-revision":32599} {"level":"info","ts":"2026-09-29T05:10:25.10005Z","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-09-29T05:10:25.111434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-29T05:10:25.111898Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-29T05:11:58.615651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37148} {"level":"info","ts":"2026-09-29T05:11:58.75906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37148,"took":"135.561357ms","hash":3919504170,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":31768576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T05:11:58.759133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3919504170,"revision":37148,"compact-revision":34887} {"level":"info","ts":"2026-09-29T05:16:58.623022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39482} {"level":"info","ts":"2026-09-29T05:16:58.762762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39482,"took":"135.418704ms","hash":1733801000,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":32030720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T05:16:58.762844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1733801000,"revision":39482,"compact-revision":37148} {"level":"info","ts":"2026-09-29T05:21:58.630586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41712} {"level":"info","ts":"2026-09-29T05:21:58.761925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41712,"took":"127.280124ms","hash":909935383,"current-db-size-bytes":60735488,"current-db-size":"61 MB","current-db-size-in-use-bytes":30429184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-29T05:21:58.762005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":909935383,"revision":41712,"compact-revision":39482}