{"level":"warn","ts":"2026-04-17T02:31:17.628318Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T02:31:17.628442Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.44:2380","--initial-cluster=instance=https://199.19.213.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.44: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-04-17T02:31:17.628531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T02:31:17.628553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-04-17T02:31:17.628581Z","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-04-17T02:31:17.629408Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"]} {"level":"info","ts":"2026-04-17T02:31:17.629526Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.44: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-04-17T02:31:17.633517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.62506ms"} {"level":"info","ts":"2026-04-17T02:31:17.642985Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4f2979fc0f638003","cluster-id":"ce06e7bc3b23612b"} {"level":"info","ts":"2026-04-17T02:31:17.644625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T02:31:17.644726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 0"} {"level":"info","ts":"2026-04-17T02:31:17.644784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4f2979fc0f638003 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T02:31:17.644888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 1"} {"level":"info","ts":"2026-04-17T02:31:17.645026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"warn","ts":"2026-04-17T02:31:17.653176Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T02:31:17.659895Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T02:31:17.66122Z","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-04-17T02:31:17.663494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4f2979fc0f638003","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T02:31:17.663684Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4f2979fc0f638003","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T02:31:17.663844Z","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-04-17T02:31:17.663987Z","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-04-17T02:31:17.664008Z","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-04-17T02:31:17.664829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"info","ts":"2026-04-17T02:31:17.66502Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","added-peer-id":"4f2979fc0f638003","added-peer-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-04-17T02:31:17.667014Z","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-04-17T02:31:17.667133Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-04-17T02:31:17.667196Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-04-17T02:31:17.667389Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4f2979fc0f638003","initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T02:31:17.667454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T02:31:17.746258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T02:31:17.746342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T02:31:17.746376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgPreVoteResp from 4f2979fc0f638003 at term 1"} {"level":"info","ts":"2026-04-17T02:31:17.746396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became candidate at term 2"} {"level":"info","ts":"2026-04-17T02:31:17.746408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgVoteResp from 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-04-17T02:31:17.746425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became leader at term 2"} {"level":"info","ts":"2026-04-17T02:31:17.74644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4f2979fc0f638003 elected leader 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-04-17T02:31:17.747384Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:31:17.74878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T02:31:17.748769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4f2979fc0f638003","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.44:2379]}","request-path":"/0/members/4f2979fc0f638003/attributes","cluster-id":"ce06e7bc3b23612b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T02:31:17.748847Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T02:31:17.749017Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:31:17.749176Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:31:17.749232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:31:17.749294Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T02:31:17.749323Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T02:31:17.752627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T02:31:17.752818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.44:2379"} {"level":"info","ts":"2026-04-17T02:31:37.900115Z","caller":"traceutil/trace.go:171","msg":"trace[984647664] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"137.103224ms","start":"2026-04-17T02:31:37.762986Z","end":"2026-04-17T02:31:37.90009Z","steps":["trace[984647664] 'process raft request' (duration: 136.94313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:31:43.582538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.29639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:31:43.582721Z","caller":"traceutil/trace.go:171","msg":"trace[1752787595] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"214.813269ms","start":"2026-04-17T02:31:43.367895Z","end":"2026-04-17T02:31:43.582709Z","steps":["trace[1752787595] 'process raft request' (duration: 214.774038ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:31:43.582791Z","caller":"traceutil/trace.go:171","msg":"trace[283996936] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"214.88788ms","start":"2026-04-17T02:31:43.367862Z","end":"2026-04-17T02:31:43.58275Z","steps":["trace[283996936] 'process raft request' (duration: 82.000006ms)","trace[283996936] 'compare' (duration: 132.141567ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:31:51.097844Z","caller":"traceutil/trace.go:171","msg":"trace[45563980] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"104.67198ms","start":"2026-04-17T02:31:50.993141Z","end":"2026-04-17T02:31:51.097813Z","steps":["trace[45563980] 'process raft request' (duration: 104.545537ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:31:51.476981Z","caller":"traceutil/trace.go:171","msg":"trace[1619622745] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"147.8788ms","start":"2026-04-17T02:31:51.329067Z","end":"2026-04-17T02:31:51.476946Z","steps":["trace[1619622745] 'process raft request' (duration: 78.385843ms)","trace[1619622745] 'compare' (duration: 69.364154ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:33:20.678718Z","caller":"traceutil/trace.go:171","msg":"trace[1866354136] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"239.591771ms","start":"2026-04-17T02:33:20.439098Z","end":"2026-04-17T02:33:20.67869Z","steps":["trace[1866354136] 'read index received' (duration: 239.429126ms)","trace[1866354136] 'applied index is now lower than readState.Index' (duration: 161.295µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:33:20.678879Z","caller":"traceutil/trace.go:171","msg":"trace[842281553] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"302.958798ms","start":"2026-04-17T02:33:20.375872Z","end":"2026-04-17T02:33:20.678831Z","steps":["trace[842281553] 'process raft request' (duration: 302.646539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:33:20.679137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.024263ms","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-04-17T02:33:20.67924Z","caller":"traceutil/trace.go:171","msg":"trace[2049773262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"240.188467ms","start":"2026-04-17T02:33:20.439027Z","end":"2026-04-17T02:33:20.679216Z","steps":["trace[2049773262] 'agreement among raft nodes before linearized reading' (duration: 239.794686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:33:20.67935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:33:20.375842Z","time spent":"303.154403ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:26.922849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.558248ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:26.922988Z","caller":"traceutil/trace.go:171","msg":"trace[713868352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2074; }","duration":"128.719242ms","start":"2026-04-17T02:38:26.794251Z","end":"2026-04-17T02:38:26.92297Z","steps":["trace[713868352] 'range keys from in-memory index tree' (duration: 128.539758ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:28.182967Z","caller":"traceutil/trace.go:171","msg":"trace[652642855] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"159.089612ms","start":"2026-04-17T02:38:28.023841Z","end":"2026-04-17T02:38:28.182931Z","steps":["trace[652642855] 'process raft request' (duration: 158.864347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:36.888077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.416665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T02:38:36.888213Z","caller":"traceutil/trace.go:171","msg":"trace[1761017410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2107; }","duration":"184.624059ms","start":"2026-04-17T02:38:36.70356Z","end":"2026-04-17T02:38:36.888184Z","steps":["trace[1761017410] 'range keys from in-memory index tree' (duration: 184.189582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:41.575444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.32082ms","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-04-17T02:38:41.575543Z","caller":"traceutil/trace.go:171","msg":"trace[649647291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2121; }","duration":"150.466643ms","start":"2026-04-17T02:38:41.425055Z","end":"2026-04-17T02:38:41.575522Z","steps":["trace[649647291] 'range keys from in-memory index tree' (duration: 150.151536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:46.968773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.643442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:38:46.968841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.231361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T02:38:46.96892Z","caller":"traceutil/trace.go:171","msg":"trace[759679776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2138; }","duration":"230.357553ms","start":"2026-04-17T02:38:46.738544Z","end":"2026-04-17T02:38:46.968901Z","steps":["trace[759679776] 'range keys from in-memory index tree' (duration: 230.080179ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:46.968912Z","caller":"traceutil/trace.go:171","msg":"trace[1727004390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2138; }","duration":"357.835076ms","start":"2026-04-17T02:38:46.611046Z","end":"2026-04-17T02:38:46.968881Z","steps":["trace[1727004390] 'range keys from in-memory index tree' (duration: 357.54356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:46.968976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:46.611016Z","time spent":"357.944977ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T02:38:46.969089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.636424ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:46.969275Z","caller":"traceutil/trace.go:171","msg":"trace[120786773] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2138; }","duration":"175.862058ms","start":"2026-04-17T02:38:46.79339Z","end":"2026-04-17T02:38:46.969252Z","steps":["trace[120786773] 'range keys from in-memory index tree' (duration: 175.608713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:46.969037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.338555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:38:46.96955Z","caller":"traceutil/trace.go:171","msg":"trace[1085499017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2138; }","duration":"451.906266ms","start":"2026-04-17T02:38:46.517591Z","end":"2026-04-17T02:38:46.969497Z","steps":["trace[1085499017] 'range keys from in-memory index tree' (duration: 451.08776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:46.969606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:46.517511Z","time spent":"452.077849ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T02:38:47.075704Z","caller":"traceutil/trace.go:171","msg":"trace[897478237] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"102.422751ms","start":"2026-04-17T02:38:46.973249Z","end":"2026-04-17T02:38:47.075672Z","steps":["trace[897478237] 'process raft request' (duration: 102.246248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:47.643896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:38:47.644062Z","caller":"traceutil/trace.go:171","msg":"trace[626880792] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"670.323516ms","start":"2026-04-17T02:38:46.973693Z","end":"2026-04-17T02:38:47.644017Z","steps":["trace[626880792] 'process raft request' (duration: 507.441771ms)","trace[626880792] 'compare' (duration: 162.387425ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:47.644171Z","caller":"traceutil/trace.go:171","msg":"trace[1040141095] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2241; }","duration":"585.659401ms","start":"2026-04-17T02:38:47.058431Z","end":"2026-04-17T02:38:47.644091Z","steps":["trace[1040141095] 'read index received' (duration: 17.106605ms)","trace[1040141095] 'applied index is now lower than readState.Index' (duration: 568.549826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:47.644283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:46.97368Z","time spent":"670.474418ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:47.791169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.142597ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T02:38:47.791302Z","caller":"traceutil/trace.go:171","msg":"trace[2042750195] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2243; }","duration":"103.396151ms","start":"2026-04-17T02:38:47.687882Z","end":"2026-04-17T02:38:47.791278Z","steps":["trace[2042750195] 'read index received' (duration: 56.031µs)","trace[2042750195] 'applied index is now lower than readState.Index' (duration: 103.33821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:47.791632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.727158ms","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-04-17T02:38:47.791747Z","caller":"traceutil/trace.go:171","msg":"trace[1001656022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"103.897131ms","start":"2026-04-17T02:38:47.687824Z","end":"2026-04-17T02:38:47.791721Z","steps":["trace[1001656022] 'agreement among raft nodes before linearized reading' (duration: 103.541765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:47.792287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.985321ms","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-04-17T02:38:47.792412Z","caller":"traceutil/trace.go:171","msg":"trace[877370830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2140; }","duration":"734.019041ms","start":"2026-04-17T02:38:47.058367Z","end":"2026-04-17T02:38:47.792386Z","steps":["trace[877370830] 'agreement among raft nodes before linearized reading' (duration: 585.917786ms)","trace[877370830] 'range keys from in-memory index tree' (duration: 146.917983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:47.792463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.05835Z","time spent":"734.101462ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T02:38:47.987113Z","caller":"traceutil/trace.go:171","msg":"trace[793578961] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"191.167597ms","start":"2026-04-17T02:38:47.795909Z","end":"2026-04-17T02:38:47.987076Z","steps":["trace[793578961] 'process raft request' (duration: 190.566116ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:48.340705Z","caller":"traceutil/trace.go:171","msg":"trace[1491181734] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"542.579199ms","start":"2026-04-17T02:38:47.79811Z","end":"2026-04-17T02:38:48.340689Z","steps":["trace[1491181734] 'process raft request' (duration: 542.514318ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:48.340691Z","caller":"traceutil/trace.go:171","msg":"trace[1977128650] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"543.945745ms","start":"2026-04-17T02:38:47.796717Z","end":"2026-04-17T02:38:48.340662Z","steps":["trace[1977128650] 'process raft request' (duration: 464.600345ms)","trace[1977128650] 'compare' (duration: 79.106186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:48.340856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.798074Z","time spent":"542.691751ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:48.340973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.796687Z","time spent":"544.171549ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:48.69673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.089893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:38:48.696937Z","caller":"traceutil/trace.go:171","msg":"trace[711685691] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"309.524331ms","start":"2026-04-17T02:38:48.38738Z","end":"2026-04-17T02:38:48.696905Z","steps":["trace[711685691] 'process raft request' (duration: 86.132774ms)","trace[711685691] 'compare' (duration: 222.941489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:48.697084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:48.387359Z","time spent":"309.666254ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:38:50.116106Z","caller":"traceutil/trace.go:171","msg":"trace[464080303] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"119.356123ms","start":"2026-04-17T02:38:49.996716Z","end":"2026-04-17T02:38:50.116072Z","steps":["trace[464080303] 'process raft request' (duration: 119.123119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:52.0219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.19937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-17T02:38:52.022008Z","caller":"traceutil/trace.go:171","msg":"trace[2013213223] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:2153; }","duration":"120.361804ms","start":"2026-04-17T02:38:51.901623Z","end":"2026-04-17T02:38:52.021984Z","steps":["trace[2013213223] 'range keys from in-memory index tree' (duration: 119.992706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:52.633439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.778836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:38:52.633657Z","caller":"traceutil/trace.go:171","msg":"trace[1252742468] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"270.302855ms","start":"2026-04-17T02:38:52.363314Z","end":"2026-04-17T02:38:52.633616Z","steps":["trace[1252742468] 'process raft request' (duration: 117.201142ms)","trace[1252742468] 'compare' (duration: 152.593503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:53.256141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.094396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T02:38:53.256796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.186149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:38:53.256917Z","caller":"traceutil/trace.go:171","msg":"trace[1014617972] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2158; }","duration":"367.341092ms","start":"2026-04-17T02:38:52.889551Z","end":"2026-04-17T02:38:53.256892Z","steps":["trace[1014617972] 'count revisions from in-memory index tree' (duration: 366.419304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:53.256996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:52.88892Z","time spent":"368.057626ms","remote":"127.0.0.1:50084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:38:53.256842Z","caller":"traceutil/trace.go:171","msg":"trace[1402299202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2158; }","duration":"126.862861ms","start":"2026-04-17T02:38:53.129951Z","end":"2026-04-17T02:38:53.256814Z","steps":["trace[1402299202] 'range keys from in-memory index tree' (duration: 125.859141ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:54.249784Z","caller":"traceutil/trace.go:171","msg":"trace[380053486] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"105.169626ms","start":"2026-04-17T02:38:54.144589Z","end":"2026-04-17T02:38:54.249758Z","steps":["trace[380053486] 'process raft request' (duration: 104.930531ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:54.372026Z","caller":"traceutil/trace.go:171","msg":"trace[2045780538] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"117.569709ms","start":"2026-04-17T02:38:54.254426Z","end":"2026-04-17T02:38:54.371996Z","steps":["trace[2045780538] 'process raft request' (duration: 91.947668ms)","trace[2045780538] 'compare' (duration: 25.509739ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:54.556274Z","caller":"traceutil/trace.go:171","msg":"trace[1025062858] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"128.197306ms","start":"2026-04-17T02:38:54.428045Z","end":"2026-04-17T02:38:54.556242Z","steps":["trace[1025062858] 'process raft request' (duration: 52.285732ms)","trace[1025062858] 'compare' (duration: 75.788202ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:56.978721Z","caller":"traceutil/trace.go:171","msg":"trace[1789882474] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"121.301761ms","start":"2026-04-17T02:38:56.857397Z","end":"2026-04-17T02:38:56.978699Z","steps":["trace[1789882474] 'process raft request' (duration: 121.145388ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:59.146894Z","caller":"traceutil/trace.go:171","msg":"trace[1060448852] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"133.630802ms","start":"2026-04-17T02:38:59.013226Z","end":"2026-04-17T02:38:59.146856Z","steps":["trace[1060448852] 'process raft request' (duration: 97.532607ms)","trace[1060448852] 'compare' (duration: 35.976923ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:10.501319Z","caller":"traceutil/trace.go:171","msg":"trace[59257427] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"208.65954ms","start":"2026-04-17T02:39:10.292636Z","end":"2026-04-17T02:39:10.501296Z","steps":["trace[59257427] 'read index received' (duration: 208.436666ms)","trace[59257427] 'applied index is now lower than readState.Index' (duration: 219.244µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:10.501499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.827523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:10.501535Z","caller":"traceutil/trace.go:171","msg":"trace[446153130] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2214; }","duration":"208.945686ms","start":"2026-04-17T02:39:10.292579Z","end":"2026-04-17T02:39:10.501525Z","steps":["trace[446153130] 'agreement among raft nodes before linearized reading' (duration: 208.812743ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:10.501747Z","caller":"traceutil/trace.go:171","msg":"trace[899760675] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"358.57144ms","start":"2026-04-17T02:39:10.143153Z","end":"2026-04-17T02:39:10.501725Z","steps":["trace[899760675] 'process raft request' (duration: 357.990409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:10.501865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:10.143128Z","time spent":"358.661163ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:11.309036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.208806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:39:11.30916Z","caller":"traceutil/trace.go:171","msg":"trace[1830214046] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2214; }","duration":"351.39344ms","start":"2026-04-17T02:39:10.957734Z","end":"2026-04-17T02:39:11.309128Z","steps":["trace[1830214046] 'count revisions from in-memory index tree' (duration: 351.128145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:11.309244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:10.957716Z","time spent":"351.510992ms","remote":"127.0.0.1:50328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T02:39:11.309241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.25114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T02:39:11.309278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.805782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T02:39:11.309339Z","caller":"traceutil/trace.go:171","msg":"trace[608431328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2214; }","duration":"345.391042ms","start":"2026-04-17T02:39:10.963925Z","end":"2026-04-17T02:39:11.309316Z","steps":["trace[608431328] 'range keys from in-memory index tree' (duration: 345.008215ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:11.309376Z","caller":"traceutil/trace.go:171","msg":"trace[1818375368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2214; }","duration":"677.975095ms","start":"2026-04-17T02:39:10.631379Z","end":"2026-04-17T02:39:11.309354Z","steps":["trace[1818375368] 'range keys from in-memory index tree' (duration: 677.624949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:11.309386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:10.963898Z","time spent":"345.474853ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T02:39:11.309278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.057981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T02:39:11.309427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:10.631354Z","time spent":"678.062867ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T02:39:11.309456Z","caller":"traceutil/trace.go:171","msg":"trace[2087199176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2214; }","duration":"492.241115ms","start":"2026-04-17T02:39:10.817192Z","end":"2026-04-17T02:39:11.309433Z","steps":["trace[2087199176] 'range keys from in-memory index tree' (duration: 491.834167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:11.309508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:10.817171Z","time spent":"492.320136ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T02:39:11.521162Z","caller":"traceutil/trace.go:171","msg":"trace[1275118624] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"206.714322ms","start":"2026-04-17T02:39:11.314401Z","end":"2026-04-17T02:39:11.521115Z","steps":["trace[1275118624] 'process raft request' (duration: 206.493908ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:11.521166Z","caller":"traceutil/trace.go:171","msg":"trace[2097356010] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"206.370905ms","start":"2026-04-17T02:39:11.314764Z","end":"2026-04-17T02:39:11.521135Z","steps":["trace[2097356010] 'process raft request' (duration: 206.164541ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:11.521296Z","caller":"traceutil/trace.go:171","msg":"trace[233449274] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"207.369234ms","start":"2026-04-17T02:39:11.313863Z","end":"2026-04-17T02:39:11.521232Z","steps":["trace[233449274] 'process raft request' (duration: 181.732083ms)","trace[233449274] 'compare' (duration: 25.180662ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:15.721274Z","caller":"traceutil/trace.go:171","msg":"trace[1254788724] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2339; }","duration":"111.251526ms","start":"2026-04-17T02:39:15.610001Z","end":"2026-04-17T02:39:15.721252Z","steps":["trace[1254788724] 'read index received' (duration: 505.72µs)","trace[1254788724] 'applied index is now lower than readState.Index' (duration: 110.744306ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:15.7213Z","caller":"traceutil/trace.go:171","msg":"trace[1654137341] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"119.557658ms","start":"2026-04-17T02:39:15.601711Z","end":"2026-04-17T02:39:15.721269Z","steps":["trace[1654137341] 'process raft request' (duration: 119.494457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:15.721429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.402578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:15.721461Z","caller":"traceutil/trace.go:171","msg":"trace[841721384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2233; }","duration":"111.516991ms","start":"2026-04-17T02:39:15.609935Z","end":"2026-04-17T02:39:15.721452Z","steps":["trace[841721384] 'agreement among raft nodes before linearized reading' (duration: 111.421609ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:15.721533Z","caller":"traceutil/trace.go:171","msg":"trace[1393599869] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"122.140319ms","start":"2026-04-17T02:39:15.599369Z","end":"2026-04-17T02:39:15.72151Z","steps":["trace[1393599869] 'process raft request' (duration: 111.018731ms)","trace[1393599869] 'compare' (duration: 10.666539ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:16.853619Z","caller":"traceutil/trace.go:171","msg":"trace[1828883599] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"153.546012ms","start":"2026-04-17T02:39:16.700045Z","end":"2026-04-17T02:39:16.853591Z","steps":["trace[1828883599] 'process raft request' (duration: 153.311528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:18.963003Z","caller":"traceutil/trace.go:171","msg":"trace[580349689] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"100.058457ms","start":"2026-04-17T02:39:18.862906Z","end":"2026-04-17T02:39:18.962964Z","steps":["trace[580349689] 'process raft request' (duration: 99.864773ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:20.012621Z","caller":"traceutil/trace.go:171","msg":"trace[1999949685] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2352; }","duration":"336.012089ms","start":"2026-04-17T02:39:19.67656Z","end":"2026-04-17T02:39:20.012572Z","steps":["trace[1999949685] 'read index received' (duration: 275.569667ms)","trace[1999949685] 'applied index is now lower than readState.Index' (duration: 60.440372ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:20.012846Z","caller":"traceutil/trace.go:171","msg":"trace[466341758] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"359.07991ms","start":"2026-04-17T02:39:19.653745Z","end":"2026-04-17T02:39:20.012825Z","steps":["trace[466341758] 'process raft request' (duration: 298.371713ms)","trace[466341758] 'compare' (duration: 60.183857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:20.01303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:19.653733Z","time spent":"359.149741ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:20.01302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.451538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T02:39:20.013026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.721014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:39:20.01309Z","caller":"traceutil/trace.go:171","msg":"trace[1731653498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2243; }","duration":"336.57359ms","start":"2026-04-17T02:39:19.6765Z","end":"2026-04-17T02:39:20.013074Z","steps":["trace[1731653498] 'agreement among raft nodes before linearized reading' (duration: 336.357686ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:20.013098Z","caller":"traceutil/trace.go:171","msg":"trace[1165177440] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2243; }","duration":"266.869537ms","start":"2026-04-17T02:39:19.746212Z","end":"2026-04-17T02:39:20.013081Z","steps":["trace[1165177440] 'agreement among raft nodes before linearized reading' (duration: 266.661483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:20.013121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:19.676438Z","time spent":"336.676492ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T02:39:20.013022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.402129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T02:39:20.013161Z","caller":"traceutil/trace.go:171","msg":"trace[397584493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2243; }","duration":"200.586772ms","start":"2026-04-17T02:39:19.812562Z","end":"2026-04-17T02:39:20.013149Z","steps":["trace[397584493] 'agreement among raft nodes before linearized reading' (duration: 200.367668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:20.013032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.889323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T02:39:20.013264Z","caller":"traceutil/trace.go:171","msg":"trace[492054606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2243; }","duration":"212.158828ms","start":"2026-04-17T02:39:19.801093Z","end":"2026-04-17T02:39:20.013251Z","steps":["trace[492054606] 'agreement among raft nodes before linearized reading' (duration: 211.852972ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:25.279017Z","caller":"traceutil/trace.go:171","msg":"trace[189332409] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"274.6295ms","start":"2026-04-17T02:39:25.004357Z","end":"2026-04-17T02:39:25.278987Z","steps":["trace[189332409] 'process raft request' (duration: 274.404136ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:26.230432Z","caller":"traceutil/trace.go:171","msg":"trace[1898959531] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"123.111138ms","start":"2026-04-17T02:39:26.107293Z","end":"2026-04-17T02:39:26.230404Z","steps":["trace[1898959531] 'process raft request' (duration: 122.904754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:26.613586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.738537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:26.613781Z","caller":"traceutil/trace.go:171","msg":"trace[1045583235] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"377.814447ms","start":"2026-04-17T02:39:26.235925Z","end":"2026-04-17T02:39:26.61374Z","steps":["trace[1045583235] 'process raft request' (duration: 203.822285ms)","trace[1045583235] 'compare' (duration: 173.594854ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:26.613845Z","caller":"traceutil/trace.go:171","msg":"trace[1286379142] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"377.392798ms","start":"2026-04-17T02:39:26.236415Z","end":"2026-04-17T02:39:26.613808Z","steps":["trace[1286379142] 'process raft request' (duration: 377.269836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:26.614003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:26.235895Z","time spent":"377.95988ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:26.614041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:26.236399Z","time spent":"377.545241ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:26.614085Z","caller":"traceutil/trace.go:171","msg":"trace[1170096160] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"377.622192ms","start":"2026-04-17T02:39:26.236445Z","end":"2026-04-17T02:39:26.614067Z","steps":["trace[1170096160] 'process raft request' (duration: 377.293266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:26.614153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:26.236423Z","time spent":"377.697904ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:32.143457Z","caller":"traceutil/trace.go:171","msg":"trace[1473546866] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"209.69638ms","start":"2026-04-17T02:39:31.933724Z","end":"2026-04-17T02:39:32.14342Z","steps":["trace[1473546866] 'process raft request' (duration: 117.287044ms)","trace[1473546866] 'compare' (duration: 92.232503ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:32.813429Z","caller":"traceutil/trace.go:171","msg":"trace[885959913] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"107.223916ms","start":"2026-04-17T02:39:32.70617Z","end":"2026-04-17T02:39:32.813394Z","steps":["trace[885959913] 'process raft request' (duration: 107.017351ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:33.535477Z","caller":"traceutil/trace.go:171","msg":"trace[1950283706] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"121.575224ms","start":"2026-04-17T02:39:33.413875Z","end":"2026-04-17T02:39:33.53545Z","steps":["trace[1950283706] 'process raft request' (duration: 121.37742ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:34.976622Z","caller":"traceutil/trace.go:171","msg":"trace[892104038] linearizableReadLoop","detail":"{readStateIndex:2404; appliedIndex:2403; }","duration":"142.343838ms","start":"2026-04-17T02:39:34.834249Z","end":"2026-04-17T02:39:34.976593Z","steps":["trace[892104038] 'read index received' (duration: 142.083252ms)","trace[892104038] 'applied index is now lower than readState.Index' (duration: 258.956µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:34.976691Z","caller":"traceutil/trace.go:171","msg":"trace[1968992373] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"153.233956ms","start":"2026-04-17T02:39:34.823418Z","end":"2026-04-17T02:39:34.976652Z","steps":["trace[1968992373] 'process raft request' (duration: 152.928658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:34.976936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.667515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T02:39:34.97699Z","caller":"traceutil/trace.go:171","msg":"trace[290552942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2291; }","duration":"142.775427ms","start":"2026-04-17T02:39:34.834206Z","end":"2026-04-17T02:39:34.976981Z","steps":["trace[290552942] 'agreement among raft nodes before linearized reading' (duration: 142.531572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:34.995489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.887064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T02:39:34.995586Z","caller":"traceutil/trace.go:171","msg":"trace[1467626733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2292; }","duration":"161.017807ms","start":"2026-04-17T02:39:34.834546Z","end":"2026-04-17T02:39:34.995564Z","steps":["trace[1467626733] 'agreement among raft nodes before linearized reading' (duration: 160.730981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:35.273822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.735894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:35.274083Z","caller":"traceutil/trace.go:171","msg":"trace[845057178] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"273.604126ms","start":"2026-04-17T02:39:35.000463Z","end":"2026-04-17T02:39:35.274067Z","steps":["trace[845057178] 'process raft request' (duration: 273.511444ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:35.2742Z","caller":"traceutil/trace.go:171","msg":"trace[656568550] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"292.284795ms","start":"2026-04-17T02:39:34.981852Z","end":"2026-04-17T02:39:35.274137Z","steps":["trace[656568550] 'process raft request' (duration: 152.029451ms)","trace[656568550] 'compare' (duration: 139.524769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:38.83084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.388873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:38.830944Z","caller":"traceutil/trace.go:171","msg":"trace[670970319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2303; }","duration":"218.542567ms","start":"2026-04-17T02:39:38.612377Z","end":"2026-04-17T02:39:38.83092Z","steps":["trace[670970319] 'range keys from in-memory index tree' (duration: 218.302902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:39.417414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.827294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T02:39:39.417566Z","caller":"traceutil/trace.go:171","msg":"trace[772816373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2304; }","duration":"114.019368ms","start":"2026-04-17T02:39:39.303488Z","end":"2026-04-17T02:39:39.417507Z","steps":["trace[772816373] 'range keys from in-memory index tree' (duration: 113.476647ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:41.631217Z","caller":"traceutil/trace.go:171","msg":"trace[1916782427] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"112.128578ms","start":"2026-04-17T02:39:41.519061Z","end":"2026-04-17T02:39:41.63119Z","steps":["trace[1916782427] 'process raft request' (duration: 67.978897ms)","trace[1916782427] 'compare' (duration: 43.954307ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:44.641258Z","caller":"traceutil/trace.go:171","msg":"trace[678618807] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"129.490251ms","start":"2026-04-17T02:39:44.511733Z","end":"2026-04-17T02:39:44.641223Z","steps":["trace[678618807] 'process raft request' (duration: 129.268035ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:45.774894Z","caller":"traceutil/trace.go:171","msg":"trace[784591070] linearizableReadLoop","detail":"{readStateIndex:2441; appliedIndex:2440; }","duration":"174.262004ms","start":"2026-04-17T02:39:45.600599Z","end":"2026-04-17T02:39:45.774861Z","steps":["trace[784591070] 'read index received' (duration: 173.829225ms)","trace[784591070] 'applied index is now lower than readState.Index' (duration: 430.919µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:45.775021Z","caller":"traceutil/trace.go:171","msg":"trace[720767596] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"202.183036ms","start":"2026-04-17T02:39:45.572791Z","end":"2026-04-17T02:39:45.774974Z","steps":["trace[720767596] 'process raft request' (duration: 201.751917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:45.775144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.839656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:45.775198Z","caller":"traceutil/trace.go:171","msg":"trace[489758262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2326; }","duration":"162.947007ms","start":"2026-04-17T02:39:45.612237Z","end":"2026-04-17T02:39:45.775184Z","steps":["trace[489758262] 'agreement among raft nodes before linearized reading' (duration: 162.825615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:45.775202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.774471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:45.775242Z","caller":"traceutil/trace.go:171","msg":"trace[1817915594] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2326; }","duration":"123.859332ms","start":"2026-04-17T02:39:45.651373Z","end":"2026-04-17T02:39:45.775232Z","steps":["trace[1817915594] 'agreement among raft nodes before linearized reading' (duration: 123.77038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:45.775202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.57283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T02:39:45.775318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.405049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:39:45.775315Z","caller":"traceutil/trace.go:171","msg":"trace[961983475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2326; }","duration":"174.754353ms","start":"2026-04-17T02:39:45.600537Z","end":"2026-04-17T02:39:45.775291Z","steps":["trace[961983475] 'agreement among raft nodes before linearized reading' (duration: 174.452907ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:45.775381Z","caller":"traceutil/trace.go:171","msg":"trace[1454382552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2326; }","duration":"118.520061ms","start":"2026-04-17T02:39:45.656842Z","end":"2026-04-17T02:39:45.775362Z","steps":["trace[1454382552] 'agreement among raft nodes before linearized reading' (duration: 118.329737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:46.13685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.736347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:46.137188Z","caller":"traceutil/trace.go:171","msg":"trace[1872611569] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"319.984343ms","start":"2026-04-17T02:39:45.81718Z","end":"2026-04-17T02:39:46.137164Z","steps":["trace[1872611569] 'process raft request' (duration: 319.919731ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:46.137219Z","caller":"traceutil/trace.go:171","msg":"trace[306850909] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"356.711028ms","start":"2026-04-17T02:39:45.780466Z","end":"2026-04-17T02:39:46.137177Z","steps":["trace[306850909] 'process raft request' (duration: 164.51008ms)","trace[306850909] 'compare' (duration: 191.476622ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:46.137235Z","caller":"traceutil/trace.go:171","msg":"trace[127919899] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"355.730597ms","start":"2026-04-17T02:39:45.78146Z","end":"2026-04-17T02:39:46.137191Z","steps":["trace[127919899] 'process raft request' (duration: 355.536552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:46.137306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:45.817162Z","time spent":"320.095415ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:46.137442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:45.78144Z","time spent":"355.88603ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:46.137471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:45.780432Z","time spent":"356.884172ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:46.800233Z","caller":"traceutil/trace.go:171","msg":"trace[1109461757] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"149.400004ms","start":"2026-04-17T02:39:46.650808Z","end":"2026-04-17T02:39:46.800208Z","steps":["trace[1109461757] 'process raft request' (duration: 149.211511ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:49.000315Z","caller":"traceutil/trace.go:171","msg":"trace[397172471] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"190.227117ms","start":"2026-04-17T02:39:48.810054Z","end":"2026-04-17T02:39:49.000281Z","steps":["trace[397172471] 'process raft request' (duration: 189.973781ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:49.493052Z","caller":"traceutil/trace.go:171","msg":"trace[533851277] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"262.269868ms","start":"2026-04-17T02:39:49.230759Z","end":"2026-04-17T02:39:49.493028Z","steps":["trace[533851277] 'process raft request' (duration: 262.114325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:49.790136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.411467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:49.790285Z","caller":"traceutil/trace.go:171","msg":"trace[1039796289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2337; }","duration":"176.594472ms","start":"2026-04-17T02:39:49.613665Z","end":"2026-04-17T02:39:49.79026Z","steps":["trace[1039796289] 'range keys from in-memory index tree' (duration: 176.263254ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:50.524873Z","caller":"traceutil/trace.go:171","msg":"trace[1479344364] transaction","detail":"{read_only:false; response_revision:2340; number_of_response:1; }","duration":"307.573483ms","start":"2026-04-17T02:39:50.217258Z","end":"2026-04-17T02:39:50.524832Z","steps":["trace[1479344364] 'process raft request' (duration: 229.697559ms)","trace[1479344364] 'compare' (duration: 77.625669ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:50.525017Z","caller":"traceutil/trace.go:171","msg":"trace[896366568] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"220.893397ms","start":"2026-04-17T02:39:50.304099Z","end":"2026-04-17T02:39:50.524993Z","steps":["trace[896366568] 'process raft request' (duration: 220.686542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:50.525071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:50.217242Z","time spent":"307.724737ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:50.525367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.941761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T02:39:50.52547Z","caller":"traceutil/trace.go:171","msg":"trace[1470155797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2341; }","duration":"308.100364ms","start":"2026-04-17T02:39:50.217345Z","end":"2026-04-17T02:39:50.525446Z","steps":["trace[1470155797] 'agreement among raft nodes before linearized reading' (duration: 307.810188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:50.525518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:50.217292Z","time spent":"308.215917ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T02:39:50.525755Z","caller":"traceutil/trace.go:171","msg":"trace[1150795944] linearizableReadLoop","detail":"{readStateIndex:2456; appliedIndex:2455; }","duration":"225.577664ms","start":"2026-04-17T02:39:50.299349Z","end":"2026-04-17T02:39:50.524926Z","steps":["trace[1150795944] 'read index received' (duration: 147.72341ms)","trace[1150795944] 'applied index is now lower than readState.Index' (duration: 77.849794ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:51.42395Z","caller":"traceutil/trace.go:171","msg":"trace[1882789130] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"368.481524ms","start":"2026-04-17T02:39:51.055435Z","end":"2026-04-17T02:39:51.423916Z","steps":["trace[1882789130] 'process raft request' (duration: 368.27996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.424156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.055399Z","time spent":"368.645247ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:51.958993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.947762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T02:39:51.959097Z","caller":"traceutil/trace.go:171","msg":"trace[1998760310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2343; }","duration":"462.106386ms","start":"2026-04-17T02:39:51.49697Z","end":"2026-04-17T02:39:51.959076Z","steps":["trace[1998760310] 'range keys from in-memory index tree' (duration: 461.710797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.959085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.832283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T02:39:51.959145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.496946Z","time spent":"462.188117ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T02:39:51.959192Z","caller":"traceutil/trace.go:171","msg":"trace[1050837731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2343; }","duration":"137.991268ms","start":"2026-04-17T02:39:51.821172Z","end":"2026-04-17T02:39:51.959163Z","steps":["trace[1050837731] 'range keys from in-memory index tree' (duration: 137.547578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:52.265498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.959813Z","time spent":"305.679863ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T02:39:52.278602Z","caller":"traceutil/trace.go:171","msg":"trace[1020106162] transaction","detail":"{read_only:false; response_revision:2345; number_of_response:1; }","duration":"314.371204ms","start":"2026-04-17T02:39:51.964198Z","end":"2026-04-17T02:39:52.278569Z","steps":["trace[1020106162] 'process raft request' (duration: 314.280143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:52.27882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.964167Z","time spent":"314.535558ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:52.278688Z","caller":"traceutil/trace.go:171","msg":"trace[888584639] transaction","detail":"{read_only:false; response_revision:2344; number_of_response:1; }","duration":"315.469818ms","start":"2026-04-17T02:39:51.963146Z","end":"2026-04-17T02:39:52.278616Z","steps":["trace[888584639] 'process raft request' (duration: 315.06129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:52.279145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.963124Z","time spent":"315.808985ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:53.125338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.782889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:53.12558Z","caller":"traceutil/trace.go:171","msg":"trace[1080302481] linearizableReadLoop","detail":"{readStateIndex:2464; appliedIndex:2463; }","duration":"809.346206ms","start":"2026-04-17T02:39:52.316162Z","end":"2026-04-17T02:39:53.125508Z","steps":["trace[1080302481] 'read index received' (duration: 428.224379ms)","trace[1080302481] 'applied index is now lower than readState.Index' (duration: 381.119947ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:53.125711Z","caller":"traceutil/trace.go:171","msg":"trace[1196382510] transaction","detail":"{read_only:false; response_revision:2347; number_of_response:1; }","duration":"821.565219ms","start":"2026-04-17T02:39:52.30413Z","end":"2026-04-17T02:39:53.125695Z","steps":["trace[1196382510] 'process raft request' (duration: 440.29463ms)","trace[1196382510] 'compare' (duration: 380.634986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:53.125804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.30411Z","time spent":"821.650572ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:53.126304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.233091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T02:39:53.126344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.70303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:39:53.126383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.221244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-17T02:39:53.126427Z","caller":"traceutil/trace.go:171","msg":"trace[1627464527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2347; }","duration":"512.852593ms","start":"2026-04-17T02:39:52.613553Z","end":"2026-04-17T02:39:53.126406Z","steps":["trace[1627464527] 'agreement among raft nodes before linearized reading' (duration: 512.69208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:53.12643Z","caller":"traceutil/trace.go:171","msg":"trace[709208614] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2347; }","duration":"810.304065ms","start":"2026-04-17T02:39:52.316113Z","end":"2026-04-17T02:39:53.126417Z","steps":["trace[709208614] 'agreement among raft nodes before linearized reading' (duration: 810.214783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:53.126426Z","caller":"traceutil/trace.go:171","msg":"trace[2136049636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2347; }","duration":"596.444646ms","start":"2026-04-17T02:39:52.529948Z","end":"2026-04-17T02:39:53.126392Z","steps":["trace[2136049636] 'agreement among raft nodes before linearized reading' (duration: 596.062328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.126471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.613487Z","time spent":"512.976395ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T02:39:53.126475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.316086Z","time spent":"810.381227ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-17T02:39:53.126473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.104973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-17T02:39:53.126492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.529902Z","time spent":"596.579639ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T02:39:53.126538Z","caller":"traceutil/trace.go:171","msg":"trace[49409431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2347; }","duration":"554.212425ms","start":"2026-04-17T02:39:52.572307Z","end":"2026-04-17T02:39:53.126519Z","steps":["trace[49409431] 'agreement among raft nodes before linearized reading' (duration: 553.94205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.126581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.572259Z","time spent":"554.313118ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T02:39:53.4455Z","caller":"traceutil/trace.go:171","msg":"trace[1131853025] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"314.252332ms","start":"2026-04-17T02:39:53.131214Z","end":"2026-04-17T02:39:53.445466Z","steps":["trace[1131853025] 'process raft request' (duration: 295.40993ms)","trace[1131853025] 'compare' (duration: 18.475435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:53.445715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:53.131188Z","time spent":"314.433866ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:53.631356Z","caller":"traceutil/trace.go:171","msg":"trace[278856817] linearizableReadLoop","detail":"{readStateIndex:2466; appliedIndex:2465; }","duration":"202.437062ms","start":"2026-04-17T02:39:53.428894Z","end":"2026-04-17T02:39:53.631331Z","steps":["trace[278856817] 'read index received' (duration: 202.175737ms)","trace[278856817] 'applied index is now lower than readState.Index' (duration: 260.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:53.631486Z","caller":"traceutil/trace.go:171","msg":"trace[377821121] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"499.387463ms","start":"2026-04-17T02:39:53.13209Z","end":"2026-04-17T02:39:53.631478Z","steps":["trace[377821121] 'process raft request' (duration: 499.099917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.631592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:53.132056Z","time spent":"499.453845ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:53.631848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.946642ms","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-04-17T02:39:53.631929Z","caller":"traceutil/trace.go:171","msg":"trace[105322718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2349; }","duration":"203.075835ms","start":"2026-04-17T02:39:53.428834Z","end":"2026-04-17T02:39:53.63191Z","steps":["trace[105322718] 'agreement among raft nodes before linearized reading' (duration: 202.883811ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:54.268092Z","caller":"traceutil/trace.go:171","msg":"trace[303232348] linearizableReadLoop","detail":"{readStateIndex:2468; appliedIndex:2467; }","duration":"240.593466ms","start":"2026-04-17T02:39:54.027464Z","end":"2026-04-17T02:39:54.268057Z","steps":["trace[303232348] 'read index received' (duration: 240.349521ms)","trace[303232348] 'applied index is now lower than readState.Index' (duration: 242.365µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:54.268261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.771831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:54.268302Z","caller":"traceutil/trace.go:171","msg":"trace[1904133567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2351; }","duration":"240.887983ms","start":"2026-04-17T02:39:54.0274Z","end":"2026-04-17T02:39:54.268288Z","steps":["trace[1904133567] 'agreement among raft nodes before linearized reading' (duration: 240.785031ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:54.268549Z","caller":"traceutil/trace.go:171","msg":"trace[1819135612] transaction","detail":"{read_only:false; response_revision:2351; number_of_response:1; }","duration":"269.033719ms","start":"2026-04-17T02:39:53.999459Z","end":"2026-04-17T02:39:54.268493Z","steps":["trace[1819135612] 'process raft request' (duration: 268.355465ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:55.267915Z","caller":"traceutil/trace.go:171","msg":"trace[532195308] transaction","detail":"{read_only:false; response_revision:2354; number_of_response:1; }","duration":"136.169159ms","start":"2026-04-17T02:39:55.131719Z","end":"2026-04-17T02:39:55.267888Z","steps":["trace[532195308] 'process raft request' (duration: 136.006566ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:55.679288Z","caller":"traceutil/trace.go:171","msg":"trace[96208738] transaction","detail":"{read_only:false; response_revision:2355; number_of_response:1; }","duration":"147.379954ms","start":"2026-04-17T02:39:55.531872Z","end":"2026-04-17T02:39:55.679252Z","steps":["trace[96208738] 'process raft request' (duration: 147.106978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:56.041508Z","caller":"traceutil/trace.go:171","msg":"trace[1325199569] transaction","detail":"{read_only:false; response_revision:2356; number_of_response:1; }","duration":"356.33873ms","start":"2026-04-17T02:39:55.685128Z","end":"2026-04-17T02:39:56.041466Z","steps":["trace[1325199569] 'process raft request' (duration: 266.639679ms)","trace[1325199569] 'compare' (duration: 89.107949ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:56.041797Z","caller":"traceutil/trace.go:171","msg":"trace[746088661] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"343.648176ms","start":"2026-04-17T02:39:55.697981Z","end":"2026-04-17T02:39:56.041629Z","steps":["trace[746088661] 'process raft request' (duration: 343.418092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:56.041969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:55.685109Z","time spent":"356.601015ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:56.041518Z","caller":"traceutil/trace.go:171","msg":"trace[1633006388] transaction","detail":"{read_only:false; response_revision:2357; number_of_response:1; }","duration":"355.334429ms","start":"2026-04-17T02:39:55.686133Z","end":"2026-04-17T02:39:56.041467Z","steps":["trace[1633006388] 'process raft request' (duration: 355.123245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:56.042085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:55.697949Z","time spent":"343.951242ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:56.042244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:55.686109Z","time spent":"355.994503ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:56.683498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.948204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:56.683829Z","caller":"traceutil/trace.go:171","msg":"trace[2019841508] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"295.428789ms","start":"2026-04-17T02:39:56.388342Z","end":"2026-04-17T02:39:56.683771Z","steps":["trace[2019841508] 'process raft request' (duration: 100.124688ms)","trace[2019841508] 'compare' (duration: 194.841792ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:57.438264Z","caller":"traceutil/trace.go:171","msg":"trace[510204010] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"163.839857ms","start":"2026-04-17T02:39:57.274391Z","end":"2026-04-17T02:39:57.438231Z","steps":["trace[510204010] 'process raft request' (duration: 163.618343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:58.097668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.757467ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T02:39:58.097788Z","caller":"traceutil/trace.go:171","msg":"trace[1536973060] linearizableReadLoop","detail":"{readStateIndex:2479; appliedIndex:2478; }","duration":"484.927161ms","start":"2026-04-17T02:39:57.61284Z","end":"2026-04-17T02:39:58.097767Z","steps":["trace[1536973060] 'read index received' (duration: 188.951929ms)","trace[1536973060] 'applied index is now lower than readState.Index' (duration: 295.973242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:58.09797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.114904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:58.098067Z","caller":"traceutil/trace.go:171","msg":"trace[15056305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2361; }","duration":"485.263968ms","start":"2026-04-17T02:39:57.612782Z","end":"2026-04-17T02:39:58.098046Z","steps":["trace[15056305] 'agreement among raft nodes before linearized reading' (duration: 485.080594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:58.098121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:57.612758Z","time spent":"485.34855ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T02:39:58.098139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.548113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T02:39:58.098415Z","caller":"traceutil/trace.go:171","msg":"trace[112002450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2361; }","duration":"413.90237ms","start":"2026-04-17T02:39:57.684495Z","end":"2026-04-17T02:39:58.098397Z","steps":["trace[112002450] 'agreement among raft nodes before linearized reading' (duration: 413.41ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:58.098467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:57.684467Z","time spent":"413.985601ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T02:39:58.098534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.486295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:58.098628Z","caller":"traceutil/trace.go:171","msg":"trace[634077909] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2361; }","duration":"264.670038ms","start":"2026-04-17T02:39:57.833941Z","end":"2026-04-17T02:39:58.098611Z","steps":["trace[634077909] 'agreement among raft nodes before linearized reading' (duration: 264.531465ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:58.312343Z","caller":"traceutil/trace.go:171","msg":"trace[1824612409] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"209.05098ms","start":"2026-04-17T02:39:58.103255Z","end":"2026-04-17T02:39:58.312306Z","steps":["trace[1824612409] 'process raft request' (duration: 195.046288ms)","trace[1824612409] 'compare' (duration: 13.510901ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:58.312544Z","caller":"traceutil/trace.go:171","msg":"trace[1206856007] linearizableReadLoop","detail":"{readStateIndex:2482; appliedIndex:2480; }","duration":"149.712622ms","start":"2026-04-17T02:39:58.162797Z","end":"2026-04-17T02:39:58.31251Z","steps":["trace[1206856007] 'read index received' (duration: 135.407634ms)","trace[1206856007] 'applied index is now lower than readState.Index' (duration: 14.303168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:58.312721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.883905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:58.312693Z","caller":"traceutil/trace.go:171","msg":"trace[1430659923] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"208.169101ms","start":"2026-04-17T02:39:58.104481Z","end":"2026-04-17T02:39:58.31265Z","steps":["trace[1430659923] 'process raft request' (duration: 207.744962ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:58.312761Z","caller":"traceutil/trace.go:171","msg":"trace[456922045] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2364; }","duration":"150.026379ms","start":"2026-04-17T02:39:58.162722Z","end":"2026-04-17T02:39:58.312748Z","steps":["trace[456922045] 'agreement among raft nodes before linearized reading' (duration: 149.907136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:59.211078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.846445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:59.211156Z","caller":"traceutil/trace.go:171","msg":"trace[1340307671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2365; }","duration":"597.99867ms","start":"2026-04-17T02:39:58.613139Z","end":"2026-04-17T02:39:59.211138Z","steps":["trace[1340307671] 'range keys from in-memory index tree' (duration: 597.682002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:59.211192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:58.61311Z","time spent":"598.075101ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T02:39:59.211293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.639149ms","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-04-17T02:39:59.21138Z","caller":"traceutil/trace.go:171","msg":"trace[66556561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2365; }","duration":"523.805892ms","start":"2026-04-17T02:39:58.687554Z","end":"2026-04-17T02:39:59.21136Z","steps":["trace[66556561] 'range keys from in-memory index tree' (duration: 523.341803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:59.211417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:58.687526Z","time spent":"523.883504ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T02:39:59.372281Z","caller":"traceutil/trace.go:171","msg":"trace[1649774195] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"156.403651ms","start":"2026-04-17T02:39:59.215844Z","end":"2026-04-17T02:39:59.372247Z","steps":["trace[1649774195] 'process raft request' (duration: 156.187767ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:00.343862Z","caller":"traceutil/trace.go:171","msg":"trace[1138172340] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"212.596413ms","start":"2026-04-17T02:40:00.131218Z","end":"2026-04-17T02:40:00.343815Z","steps":["trace[1138172340] 'process raft request' (duration: 212.327387ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:04.662814Z","caller":"traceutil/trace.go:171","msg":"trace[1438737680] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"132.317449ms","start":"2026-04-17T02:40:04.53046Z","end":"2026-04-17T02:40:04.662777Z","steps":["trace[1438737680] 'process raft request' (duration: 132.002082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.722525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.768143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:05.722667Z","caller":"traceutil/trace.go:171","msg":"trace[521714614] linearizableReadLoop","detail":"{readStateIndex:2506; appliedIndex:2505; }","duration":"111.014975ms","start":"2026-04-17T02:40:05.611631Z","end":"2026-04-17T02:40:05.722646Z","steps":["trace[521714614] 'read index received' (duration: 8.983977ms)","trace[521714614] 'applied index is now lower than readState.Index' (duration: 102.029548ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:05.722719Z","caller":"traceutil/trace.go:171","msg":"trace[1089400121] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"243.440306ms","start":"2026-04-17T02:40:05.479265Z","end":"2026-04-17T02:40:05.722706Z","steps":["trace[1089400121] 'process raft request' (duration: 141.355456ms)","trace[1089400121] 'compare' (duration: 101.429676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:05.72274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.113477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:05.722766Z","caller":"traceutil/trace.go:171","msg":"trace[69631719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2387; }","duration":"111.139658ms","start":"2026-04-17T02:40:05.611618Z","end":"2026-04-17T02:40:05.722757Z","steps":["trace[69631719] 'agreement among raft nodes before linearized reading' (duration: 111.083956ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:06.628089Z","caller":"traceutil/trace.go:171","msg":"trace[274819510] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"173.041889ms","start":"2026-04-17T02:40:06.455018Z","end":"2026-04-17T02:40:06.62806Z","steps":["trace[274819510] 'process raft request' (duration: 172.974108ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:06.628163Z","caller":"traceutil/trace.go:171","msg":"trace[770652667] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"173.202842ms","start":"2026-04-17T02:40:06.454925Z","end":"2026-04-17T02:40:06.628128Z","steps":["trace[770652667] 'process raft request' (duration: 145.503515ms)","trace[770652667] 'compare' (duration: 27.388811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:06.974526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.477792ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:06.974701Z","caller":"traceutil/trace.go:171","msg":"trace[739628562] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2391; }","duration":"167.680477ms","start":"2026-04-17T02:40:06.806997Z","end":"2026-04-17T02:40:06.974678Z","steps":["trace[739628562] 'range keys from in-memory index tree' (duration: 167.450192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.974999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.328516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:06.975186Z","caller":"traceutil/trace.go:171","msg":"trace[1373976132] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"302.817694ms","start":"2026-04-17T02:40:06.672337Z","end":"2026-04-17T02:40:06.975155Z","steps":["trace[1373976132] 'process raft request' (duration: 115.152251ms)","trace[1373976132] 'compare' (duration: 187.01238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:06.975298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.672319Z","time spent":"302.925116ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:11.769177Z","caller":"traceutil/trace.go:171","msg":"trace[1218239159] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"140.492469ms","start":"2026-04-17T02:40:11.628667Z","end":"2026-04-17T02:40:11.76916Z","steps":["trace[1218239159] 'process raft request' (duration: 140.370727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:16.712429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.887616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:40:16.712507Z","caller":"traceutil/trace.go:171","msg":"trace[1551044840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2421; }","duration":"140.011899ms","start":"2026-04-17T02:40:16.572478Z","end":"2026-04-17T02:40:16.71249Z","steps":["trace[1551044840] 'range keys from in-memory index tree' (duration: 139.724893ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:21.250546Z","caller":"traceutil/trace.go:171","msg":"trace[148259481] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"143.759787ms","start":"2026-04-17T02:40:21.106759Z","end":"2026-04-17T02:40:21.250519Z","steps":["trace[148259481] 'process raft request' (duration: 143.317718ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:21.412028Z","caller":"traceutil/trace.go:171","msg":"trace[450915134] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"157.145428ms","start":"2026-04-17T02:40:21.254845Z","end":"2026-04-17T02:40:21.411991Z","steps":["trace[450915134] 'process raft request' (duration: 144.245208ms)","trace[450915134] 'compare' (duration: 12.772556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:22.085948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.136651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:22.086112Z","caller":"traceutil/trace.go:171","msg":"trace[896489716] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"156.108455ms","start":"2026-04-17T02:40:21.929988Z","end":"2026-04-17T02:40:22.086097Z","steps":["trace[896489716] 'process raft request' (duration: 156.066504ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:22.086322Z","caller":"traceutil/trace.go:171","msg":"trace[2015862567] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"160.510377ms","start":"2026-04-17T02:40:21.925727Z","end":"2026-04-17T02:40:22.086237Z","steps":["trace[2015862567] 'compare' (duration: 151.009289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:22.338896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.463337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:22.339014Z","caller":"traceutil/trace.go:171","msg":"trace[236511239] linearizableReadLoop","detail":"{readStateIndex:2564; appliedIndex:2563; }","duration":"250.056324ms","start":"2026-04-17T02:40:22.088942Z","end":"2026-04-17T02:40:22.338998Z","steps":["trace[236511239] 'read index received' (duration: 99.318822ms)","trace[236511239] 'applied index is now lower than readState.Index' (duration: 150.736012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:22.339083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.142476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-17T02:40:22.339101Z","caller":"traceutil/trace.go:171","msg":"trace[1956206520] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2441; }","duration":"250.189946ms","start":"2026-04-17T02:40:22.088904Z","end":"2026-04-17T02:40:22.339094Z","steps":["trace[1956206520] 'agreement among raft nodes before linearized reading' (duration: 250.135725ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:22.339103Z","caller":"traceutil/trace.go:171","msg":"trace[1579625779] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"271.214425ms","start":"2026-04-17T02:40:22.06786Z","end":"2026-04-17T02:40:22.339075Z","steps":["trace[1579625779] 'process raft request' (duration: 120.475392ms)","trace[1579625779] 'compare' (duration: 150.291363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:23.519278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.899648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:23.519417Z","caller":"traceutil/trace.go:171","msg":"trace[1126184641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2444; }","duration":"317.067321ms","start":"2026-04-17T02:40:23.202286Z","end":"2026-04-17T02:40:23.519353Z","steps":["trace[1126184641] 'range keys from in-memory index tree' (duration: 316.719474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:23.519475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.282374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T02:40:23.519525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:23.202259Z","time spent":"317.252084ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T02:40:23.519576Z","caller":"traceutil/trace.go:171","msg":"trace[1295093065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2444; }","duration":"102.464197ms","start":"2026-04-17T02:40:23.41709Z","end":"2026-04-17T02:40:23.519554Z","steps":["trace[1295093065] 'range keys from in-memory index tree' (duration: 102.009518ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:24.446826Z","caller":"traceutil/trace.go:171","msg":"trace[1884098575] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"101.537327ms","start":"2026-04-17T02:40:24.34527Z","end":"2026-04-17T02:40:24.446807Z","steps":["trace[1884098575] 'process raft request' (duration: 101.427744ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:46.648251Z","caller":"traceutil/trace.go:171","msg":"trace[110120670] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"111.714612ms","start":"2026-04-17T02:40:46.536495Z","end":"2026-04-17T02:40:46.648209Z","steps":["trace[110120670] 'process raft request' (duration: 111.453637ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:47.471053Z","caller":"traceutil/trace.go:171","msg":"trace[1912480838] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"180.622024ms","start":"2026-04-17T02:40:47.290387Z","end":"2026-04-17T02:40:47.471009Z","steps":["trace[1912480838] 'process raft request' (duration: 174.401313ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:53.322558Z","caller":"traceutil/trace.go:171","msg":"trace[286633] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"251.798372ms","start":"2026-04-17T02:40:53.070727Z","end":"2026-04-17T02:40:53.322526Z","steps":["trace[286633] 'process raft request' (duration: 251.593828ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:54.81782Z","caller":"traceutil/trace.go:171","msg":"trace[141864323] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"142.179303ms","start":"2026-04-17T02:40:54.675608Z","end":"2026-04-17T02:40:54.817787Z","steps":["trace[141864323] 'process raft request' (duration: 141.966918ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:00.538594Z","caller":"traceutil/trace.go:171","msg":"trace[2114141381] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"163.614945ms","start":"2026-04-17T02:41:00.374946Z","end":"2026-04-17T02:41:00.538561Z","steps":["trace[2114141381] 'process raft request' (duration: 163.41421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:00.790679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.143309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:00.790791Z","caller":"traceutil/trace.go:171","msg":"trace[1073425244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2565; }","duration":"177.325344ms","start":"2026-04-17T02:41:00.613438Z","end":"2026-04-17T02:41:00.790763Z","steps":["trace[1073425244] 'range keys from in-memory index tree' (duration: 176.997397ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:00.96045Z","caller":"traceutil/trace.go:171","msg":"trace[1757755090] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"118.049536ms","start":"2026-04-17T02:41:00.842363Z","end":"2026-04-17T02:41:00.960413Z","steps":["trace[1757755090] 'process raft request' (duration: 117.74929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:01.303413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.960435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:41:01.303541Z","caller":"traceutil/trace.go:171","msg":"trace[1672914200] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2566; }","duration":"205.188211ms","start":"2026-04-17T02:41:01.098324Z","end":"2026-04-17T02:41:01.303513Z","steps":["trace[1672914200] 'count revisions from in-memory index tree' (duration: 204.821873ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:06.684206Z","caller":"traceutil/trace.go:171","msg":"trace[828816315] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"117.173208ms","start":"2026-04-17T02:41:06.567008Z","end":"2026-04-17T02:41:06.684182Z","steps":["trace[828816315] 'process raft request' (duration: 117.009204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:07.186434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.901569ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T02:41:07.186776Z","caller":"traceutil/trace.go:171","msg":"trace[664550358] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"206.181522ms","start":"2026-04-17T02:41:06.980572Z","end":"2026-04-17T02:41:07.186754Z","steps":["trace[664550358] 'process raft request' (duration: 206.015468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:07.65488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.704106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:41:07.655031Z","caller":"traceutil/trace.go:171","msg":"trace[241305909] linearizableReadLoop","detail":"{readStateIndex:2719; appliedIndex:2718; }","duration":"236.526101ms","start":"2026-04-17T02:41:07.418476Z","end":"2026-04-17T02:41:07.655003Z","steps":["trace[241305909] 'read index received' (duration: 73.514969ms)","trace[241305909] 'applied index is now lower than readState.Index' (duration: 163.008652ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:41:07.655134Z","caller":"traceutil/trace.go:171","msg":"trace[5511858] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"253.227152ms","start":"2026-04-17T02:41:07.401859Z","end":"2026-04-17T02:41:07.655086Z","steps":["trace[5511858] 'process raft request' (duration: 90.20516ms)","trace[5511858] 'compare' (duration: 162.560643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:41:07.655339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.858788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:41:07.655411Z","caller":"traceutil/trace.go:171","msg":"trace[1379165429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2587; }","duration":"236.95712ms","start":"2026-04-17T02:41:07.418432Z","end":"2026-04-17T02:41:07.655389Z","steps":["trace[1379165429] 'agreement among raft nodes before linearized reading' (duration: 236.748766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:07.656095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.003932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T02:41:07.656385Z","caller":"traceutil/trace.go:171","msg":"trace[1140365801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2587; }","duration":"172.270267ms","start":"2026-04-17T02:41:07.483991Z","end":"2026-04-17T02:41:07.656262Z","steps":["trace[1140365801] 'agreement among raft nodes before linearized reading' (duration: 171.824437ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:07.817201Z","caller":"traceutil/trace.go:171","msg":"trace[272417207] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"156.388343ms","start":"2026-04-17T02:41:07.660769Z","end":"2026-04-17T02:41:07.817157Z","steps":["trace[272417207] 'process raft request' (duration: 94.97101ms)","trace[272417207] 'compare' (duration: 61.026995ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:41:07.817296Z","caller":"traceutil/trace.go:171","msg":"trace[1806851721] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"155.620087ms","start":"2026-04-17T02:41:07.661638Z","end":"2026-04-17T02:41:07.817258Z","steps":["trace[1806851721] 'process raft request' (duration: 155.459443ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:12.938956Z","caller":"traceutil/trace.go:171","msg":"trace[1043295400] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"224.63364ms","start":"2026-04-17T02:41:12.714292Z","end":"2026-04-17T02:41:12.938926Z","steps":["trace[1043295400] 'process raft request' (duration: 224.429655ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:13.327439Z","caller":"traceutil/trace.go:171","msg":"trace[1373094704] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"121.336676ms","start":"2026-04-17T02:41:13.20607Z","end":"2026-04-17T02:41:13.327407Z","steps":["trace[1373094704] 'process raft request' (duration: 121.141312ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:16.690373Z","caller":"traceutil/trace.go:171","msg":"trace[1017020604] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"265.03592ms","start":"2026-04-17T02:41:16.425293Z","end":"2026-04-17T02:41:16.690329Z","steps":["trace[1017020604] 'process raft request' (duration: 264.506139ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:16.840051Z","caller":"traceutil/trace.go:171","msg":"trace[36725660] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2885; }","duration":"106.158785ms","start":"2026-04-17T02:41:16.733863Z","end":"2026-04-17T02:41:16.840021Z","steps":["trace[36725660] 'read index received' (duration: 103.220373ms)","trace[36725660] 'applied index is now lower than readState.Index' (duration: 2.937292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:41:16.840203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.320568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:16.840228Z","caller":"traceutil/trace.go:171","msg":"trace[382916788] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:2748; }","duration":"106.410131ms","start":"2026-04-17T02:41:16.73381Z","end":"2026-04-17T02:41:16.84022Z","steps":["trace[382916788] 'agreement among raft nodes before linearized reading' (duration: 106.311608ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:16.840216Z","caller":"traceutil/trace.go:171","msg":"trace[1577984205] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"141.821836ms","start":"2026-04-17T02:41:16.698333Z","end":"2026-04-17T02:41:16.840155Z","steps":["trace[1577984205] 'process raft request' (duration: 138.840723ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:17.168301Z","caller":"traceutil/trace.go:171","msg":"trace[1748883425] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2886; }","duration":"215.982057ms","start":"2026-04-17T02:41:16.952301Z","end":"2026-04-17T02:41:17.168284Z","steps":["trace[1748883425] 'read index received' (duration: 215.935996ms)","trace[1748883425] 'applied index is now lower than readState.Index' (duration: 44.721µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:41:17.168514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.212062ms","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-04-17T02:41:17.168483Z","caller":"traceutil/trace.go:171","msg":"trace[1805911385] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"264.621772ms","start":"2026-04-17T02:41:16.903811Z","end":"2026-04-17T02:41:17.168433Z","steps":["trace[1805911385] 'process raft request' (duration: 264.353276ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:17.168555Z","caller":"traceutil/trace.go:171","msg":"trace[1117747480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2749; }","duration":"216.322014ms","start":"2026-04-17T02:41:16.952224Z","end":"2026-04-17T02:41:17.168547Z","steps":["trace[1117747480] 'agreement among raft nodes before linearized reading' (duration: 216.155981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.715019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.103833ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T02:41:17.715307Z","caller":"traceutil/trace.go:171","msg":"trace[1682382538] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2749; }","duration":"666.770739ms","start":"2026-04-17T02:41:17.048502Z","end":"2026-04-17T02:41:17.715272Z","steps":["trace[1682382538] 'process raft request' (duration: 666.647517ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:17.715448Z","caller":"traceutil/trace.go:171","msg":"trace[804566269] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"370.743666ms","start":"2026-04-17T02:41:17.344678Z","end":"2026-04-17T02:41:17.715421Z","steps":["trace[804566269] 'process raft request' (duration: 370.678955ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:17.715428Z","caller":"traceutil/trace.go:171","msg":"trace[131679109] linearizableReadLoop","detail":"{readStateIndex:2889; appliedIndex:2887; }","duration":"547.016018ms","start":"2026-04-17T02:41:17.168358Z","end":"2026-04-17T02:41:17.715374Z","steps":["trace[131679109] 'read index received' (duration: 110.563828ms)","trace[131679109] 'applied index is now lower than readState.Index' (duration: 436.44374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:41:17.715487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.048466Z","time spent":"666.932293ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T02:41:17.715575Z","caller":"traceutil/trace.go:171","msg":"trace[1177966327] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"542.437142ms","start":"2026-04-17T02:41:17.173084Z","end":"2026-04-17T02:41:17.715522Z","steps":["trace[1177966327] 'process raft request' (duration: 542.153056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.715612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.92799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/ingress-nginx/\" range_end:\"/registry/resourcequotas/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:41:17.71559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.556624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:17.715681Z","caller":"traceutil/trace.go:171","msg":"trace[1139787272] range","detail":"{range_begin:/registry/resourcequotas/ingress-nginx/; range_end:/registry/resourcequotas/ingress-nginx0; response_count:0; response_revision:2751; }","duration":"674.040922ms","start":"2026-04-17T02:41:17.041623Z","end":"2026-04-17T02:41:17.715664Z","steps":["trace[1139787272] 'agreement among raft nodes before linearized reading' (duration: 673.91306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.715675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.344645Z","time spent":"370.859269ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:41:17.715729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.041601Z","time spent":"674.119624ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/ingress-nginx/\" range_end:\"/registry/resourcequotas/ingress-nginx0\" "} {"level":"warn","ts":"2026-04-17T02:41:17.715818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.173035Z","time spent":"542.654817ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:41:17.715872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.994112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:17.715907Z","caller":"traceutil/trace.go:171","msg":"trace[2001146991] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:2751; }","duration":"419.066293ms","start":"2026-04-17T02:41:17.29683Z","end":"2026-04-17T02:41:17.715896Z","steps":["trace[2001146991] 'agreement among raft nodes before linearized reading' (duration: 419.000442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.715896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.467217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:41:17.715953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.296809Z","time spent":"419.137145ms","remote":"127.0.0.1:50098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/memcached-metrics\" "} {"level":"info","ts":"2026-04-17T02:41:17.715972Z","caller":"traceutil/trace.go:171","msg":"trace[240741627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2751; }","duration":"102.590969ms","start":"2026-04-17T02:41:17.613359Z","end":"2026-04-17T02:41:17.71595Z","steps":["trace[240741627] 'agreement among raft nodes before linearized reading' (duration: 102.427126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.716011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.790048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-coredns-7dd4958666-5rbpg\" ","response":"range_response_count:1 size:5009"} {"level":"info","ts":"2026-04-17T02:41:17.716033Z","caller":"traceutil/trace.go:171","msg":"trace[1074096498] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-osd; range_end:; response_count:0; response_revision:2751; }","duration":"570.784869ms","start":"2026-04-17T02:41:17.144927Z","end":"2026-04-17T02:41:17.715712Z","steps":["trace[1074096498] 'agreement among raft nodes before linearized reading' (duration: 570.481483ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:17.716079Z","caller":"traceutil/trace.go:171","msg":"trace[157330985] range","detail":"{range_begin:/registry/pods/openstack/neutron-coredns-7dd4958666-5rbpg; range_end:; response_count:1; response_revision:2751; }","duration":"541.873579ms","start":"2026-04-17T02:41:17.174183Z","end":"2026-04-17T02:41:17.716056Z","steps":["trace[157330985] 'agreement among raft nodes before linearized reading' (duration: 541.555183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.716099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.144886Z","time spent":"571.198527ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" "} {"level":"warn","ts":"2026-04-17T02:41:17.716124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.174169Z","time spent":"541.946581ms","remote":"127.0.0.1:50084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":5032,"request content":"key:\"/registry/pods/openstack/neutron-coredns-7dd4958666-5rbpg\" "} {"level":"warn","ts":"2026-04-17T02:41:17.716169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.072833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:17.71623Z","caller":"traceutil/trace.go:171","msg":"trace[1342936319] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:2751; }","duration":"637.168416ms","start":"2026-04-17T02:41:17.079042Z","end":"2026-04-17T02:41:17.71621Z","steps":["trace[1342936319] 'agreement among raft nodes before linearized reading' (duration: 636.41945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.716266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.07902Z","time spent":"637.237077ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"info","ts":"2026-04-17T02:41:17.999573Z","caller":"traceutil/trace.go:171","msg":"trace[383938664] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2891; }","duration":"279.029605ms","start":"2026-04-17T02:41:17.720484Z","end":"2026-04-17T02:41:17.999514Z","steps":["trace[383938664] 'read index received' (duration: 273.027909ms)","trace[383938664] 'applied index is now lower than readState.Index' (duration: 5.999766ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:41:17.999598Z","caller":"traceutil/trace.go:171","msg":"trace[184438037] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"280.477646ms","start":"2026-04-17T02:41:17.719083Z","end":"2026-04-17T02:41:17.999561Z","steps":["trace[184438037] 'process raft request' (duration: 274.355207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:17.999836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.326032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:17.999891Z","caller":"traceutil/trace.go:171","msg":"trace[479297013] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:0; response_revision:2752; }","duration":"279.473076ms","start":"2026-04-17T02:41:17.720401Z","end":"2026-04-17T02:41:17.999874Z","steps":["trace[479297013] 'agreement among raft nodes before linearized reading' (duration: 279.311852ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:18.430043Z","caller":"traceutil/trace.go:171","msg":"trace[556244745] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"702.626435ms","start":"2026-04-17T02:41:17.727381Z","end":"2026-04-17T02:41:18.430007Z","steps":["trace[556244745] 'process raft request' (duration: 635.253906ms)","trace[556244745] 'compare' (duration: 66.981481ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:41:18.430159Z","caller":"traceutil/trace.go:171","msg":"trace[1274938548] linearizableReadLoop","detail":"{readStateIndex:2895; appliedIndex:2892; }","duration":"430.463193ms","start":"2026-04-17T02:41:17.99967Z","end":"2026-04-17T02:41:18.430133Z","steps":["trace[1274938548] 'read index received' (duration: 363.065524ms)","trace[1274938548] 'applied index is now lower than readState.Index' (duration: 67.396119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:41:18.430244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.727342Z","time spent":"702.790618ms","remote":"127.0.0.1:50084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:41:18.430462Z","caller":"traceutil/trace.go:171","msg":"trace[1594465805] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"698.277553ms","start":"2026-04-17T02:41:17.732128Z","end":"2026-04-17T02:41:18.430405Z","steps":["trace[1594465805] 'process raft request' (duration: 697.794153ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:18.430633Z","caller":"traceutil/trace.go:171","msg":"trace[30310836] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"609.390221ms","start":"2026-04-17T02:41:17.821221Z","end":"2026-04-17T02:41:18.430611Z","steps":["trace[30310836] 'process raft request' (duration: 608.83214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.430645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.732115Z","time spent":"698.423246ms","remote":"127.0.0.1:49968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":27782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T02:41:18.430624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.508949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:111714"} {"level":"info","ts":"2026-04-17T02:41:18.430725Z","caller":"traceutil/trace.go:171","msg":"trace[2024248228] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2755; }","duration":"708.642322ms","start":"2026-04-17T02:41:17.722058Z","end":"2026-04-17T02:41:18.4307Z","steps":["trace[2024248228] 'agreement among raft nodes before linearized reading' (duration: 708.241794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.430708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.927972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T02:41:18.430765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.821178Z","time spent":"609.496513ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:41:18.430772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.722049Z","time spent":"708.713713ms","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":111737,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-17T02:41:18.430763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.453019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:18.43081Z","caller":"traceutil/trace.go:171","msg":"trace[156163609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2755; }","duration":"581.078945ms","start":"2026-04-17T02:41:17.849704Z","end":"2026-04-17T02:41:18.430783Z","steps":["trace[156163609] 'agreement among raft nodes before linearized reading' (duration: 580.909601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.430797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.92527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T02:41:18.430879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.84967Z","time spent":"581.188566ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T02:41:18.430881Z","caller":"traceutil/trace.go:171","msg":"trace[920278286] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-mgr; range_end:; response_count:0; response_revision:2755; }","duration":"709.614142ms","start":"2026-04-17T02:41:17.721237Z","end":"2026-04-17T02:41:18.430851Z","steps":["trace[920278286] 'agreement among raft nodes before linearized reading' (duration: 709.397838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.430846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.115989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:18.430894Z","caller":"traceutil/trace.go:171","msg":"trace[7151251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2755; }","duration":"495.065044ms","start":"2026-04-17T02:41:17.935806Z","end":"2026-04-17T02:41:18.430871Z","steps":["trace[7151251] 'agreement among raft nodes before linearized reading' (duration: 494.743547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.430934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.72122Z","time spent":"709.705534ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" "} {"level":"warn","ts":"2026-04-17T02:41:18.430959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.935795Z","time spent":"495.154105ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T02:41:18.43096Z","caller":"traceutil/trace.go:171","msg":"trace[1599449978] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2755; }","duration":"546.277592ms","start":"2026-04-17T02:41:17.884654Z","end":"2026-04-17T02:41:18.430932Z","steps":["trace[1599449978] 'agreement among raft nodes before linearized reading' (duration: 546.043207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.430943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.029324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T02:41:18.431019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.884643Z","time spent":"546.365034ms","remote":"127.0.0.1:50118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:41:18.431076Z","caller":"traceutil/trace.go:171","msg":"trace[2120413908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2755; }","duration":"571.80048ms","start":"2026-04-17T02:41:17.859243Z","end":"2026-04-17T02:41:18.431044Z","steps":["trace[2120413908] 'agreement among raft nodes before linearized reading' (duration: 570.905801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.431172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.859231Z","time spent":"571.894443ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T02:41:18.431175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.55602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:41:18.431219Z","caller":"traceutil/trace.go:171","msg":"trace[1362528681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2755; }","duration":"575.670762ms","start":"2026-04-17T02:41:17.855537Z","end":"2026-04-17T02:41:18.431207Z","steps":["trace[1362528681] 'agreement among raft nodes before linearized reading' (duration: 574.723241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.431264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:17.855508Z","time spent":"575.749744ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T02:41:18.506086Z","caller":"traceutil/trace.go:171","msg":"trace[1084892369] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"501.492899ms","start":"2026-04-17T02:41:18.004509Z","end":"2026-04-17T02:41:18.506001Z","steps":["trace[1084892369] 'process raft request' (duration: 501.220633ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:18.5062Z","caller":"traceutil/trace.go:171","msg":"trace[1939041765] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"475.292467ms","start":"2026-04-17T02:41:18.03088Z","end":"2026-04-17T02:41:18.506172Z","steps":["trace[1939041765] 'process raft request' (duration: 475.100573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.506363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:18.004492Z","time spent":"501.748905ms","remote":"127.0.0.1:49984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T02:41:18.506456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.558437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:41:18.506548Z","caller":"traceutil/trace.go:171","msg":"trace[844879254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2758; }","duration":"403.723101ms","start":"2026-04-17T02:41:18.10281Z","end":"2026-04-17T02:41:18.506533Z","steps":["trace[844879254] 'agreement among raft nodes before linearized reading' (duration: 403.465955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.506614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:18.102759Z","time spent":"403.842913ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:41:18.506474Z","caller":"traceutil/trace.go:171","msg":"trace[1652055861] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"501.846857ms","start":"2026-04-17T02:41:18.004599Z","end":"2026-04-17T02:41:18.506446Z","steps":["trace[1652055861] 'process raft request' (duration: 501.294275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.506919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:18.004592Z","time spent":"502.270977ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":168,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T02:41:18.506966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.881509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:41:18.50709Z","caller":"traceutil/trace.go:171","msg":"trace[1299112587] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:2758; }","duration":"503.059993ms","start":"2026-04-17T02:41:18.003995Z","end":"2026-04-17T02:41:18.507055Z","steps":["trace[1299112587] 'agreement among raft nodes before linearized reading' (duration: 502.836088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:41:18.507162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:18.003975Z","time spent":"503.176324ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-17T02:41:18.507555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:41:18.030847Z","time spent":"475.574222ms","remote":"127.0.0.1:49968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T02:41:18.542953Z","caller":"traceutil/trace.go:171","msg":"trace[1770766809] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"100.798282ms","start":"2026-04-17T02:41:18.442121Z","end":"2026-04-17T02:41:18.542919Z","steps":["trace[1770766809] 'process raft request' (duration: 87.466482ms)","trace[1770766809] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/services/endpoints/openstack/neutron-coredns-metrics; req_size:1040; } (duration: 12.83267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:41:18.543418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.531436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:41:18.543453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.559385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-6r66m\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-04-17T02:41:18.543502Z","caller":"traceutil/trace.go:171","msg":"trace[432041369] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter; range_end:; response_count:0; response_revision:2775; }","duration":"108.674999ms","start":"2026-04-17T02:41:18.434811Z","end":"2026-04-17T02:41:18.543486Z","steps":["trace[432041369] 'agreement among raft nodes before linearized reading' (duration: 108.533686ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:18.543515Z","caller":"traceutil/trace.go:171","msg":"trace[1108915630] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-6r66m; range_end:; response_count:1; response_revision:2775; }","duration":"107.674428ms","start":"2026-04-17T02:41:18.435826Z","end":"2026-04-17T02:41:18.5435Z","steps":["trace[1108915630] 'agreement among raft nodes before linearized reading' (duration: 107.439943ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:18.54531Z","caller":"traceutil/trace.go:171","msg":"trace[14366197] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"100.861374ms","start":"2026-04-17T02:41:18.442234Z","end":"2026-04-17T02:41:18.543095Z","steps":["trace[14366197] 'process raft request' (duration: 100.343482ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:41:18.750225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1653} {"level":"info","ts":"2026-04-17T02:41:18.800639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1653,"took":"49.605744ms","hash":2873997669,"current-db-size-bytes":25526272,"current-db-size":"26 MB","current-db-size-in-use-bytes":7397376,"current-db-size-in-use":"7.4 MB"} {"level":"info","ts":"2026-04-17T02:41:18.800712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2873997669,"revision":1653,"compact-revision":-1} {"level":"info","ts":"2026-04-17T02:46:18.757384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2828} {"level":"info","ts":"2026-04-17T02:46:18.78095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2828,"took":"22.182593ms","hash":2996505366,"current-db-size-bytes":25526272,"current-db-size":"26 MB","current-db-size-in-use-bytes":24403968,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-17T02:46:18.781017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996505366,"revision":2828,"compact-revision":1653} {"level":"info","ts":"2026-04-17T02:50:01.776556Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236d.wal"} {"level":"info","ts":"2026-04-17T02:50:37.540598Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4f2979fc0f638003","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T02:50:37.589671Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-04-17T02:50:37.589827Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-04-17T02:50:37.740821Z","caller":"traceutil/trace.go:171","msg":"trace[23744272] transaction","detail":"{read_only:false; response_revision:9653; number_of_response:1; }","duration":"139.600726ms","start":"2026-04-17T02:50:37.60119Z","end":"2026-04-17T02:50:37.74079Z","steps":["trace[23744272] 'process raft request' (duration: 42.239252ms)","trace[23744272] 'compare' (duration: 96.932475ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:50:37.740841Z","caller":"traceutil/trace.go:171","msg":"trace[567878418] linearizableReadLoop","detail":"{readStateIndex:10009; appliedIndex:10007; }","duration":"136.325757ms","start":"2026-04-17T02:50:37.604485Z","end":"2026-04-17T02:50:37.74081Z","steps":["trace[567878418] 'read index received' (duration: 38.938692ms)","trace[567878418] 'applied index is now lower than readState.Index' (duration: 97.385485ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:50:37.740966Z","caller":"traceutil/trace.go:171","msg":"trace[1489018397] transaction","detail":"{read_only:false; response_revision:9654; number_of_response:1; }","duration":"139.006024ms","start":"2026-04-17T02:50:37.601954Z","end":"2026-04-17T02:50:37.74096Z","steps":["trace[1489018397] 'process raft request' (duration: 138.761778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:50:37.74117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.643975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-ks-user-5vtxs\" ","response":"range_response_count:1 size:9135"} {"level":"info","ts":"2026-04-17T02:50:37.741357Z","caller":"traceutil/trace.go:171","msg":"trace[1456276432] range","detail":"{range_begin:/registry/pods/openstack/placement-ks-user-5vtxs; range_end:; response_count:1; response_revision:9654; }","duration":"136.768788ms","start":"2026-04-17T02:50:37.604439Z","end":"2026-04-17T02:50:37.741207Z","steps":["trace[1456276432] 'agreement among raft nodes before linearized reading' (duration: 136.535042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:50:37.741417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.693325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-17T02:50:37.74145Z","caller":"traceutil/trace.go:171","msg":"trace[1600286908] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:9654; }","duration":"135.792927ms","start":"2026-04-17T02:50:37.605646Z","end":"2026-04-17T02:50:37.741439Z","steps":["trace[1600286908] 'agreement among raft nodes before linearized reading' (duration: 135.543762ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:51:15.25778Z","caller":"traceutil/trace.go:171","msg":"trace[49742038] transaction","detail":"{read_only:false; response_revision:10191; number_of_response:1; }","duration":"100.127934ms","start":"2026-04-17T02:51:15.157628Z","end":"2026-04-17T02:51:15.257756Z","steps":["trace[49742038] 'process raft request' (duration: 99.705645ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:51:18.764039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5968} {"level":"info","ts":"2026-04-17T02:51:18.84803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5968,"took":"81.00548ms","hash":3559909712,"current-db-size-bytes":56696832,"current-db-size":"57 MB","current-db-size-in-use-bytes":44310528,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-04-17T02:51:18.848115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3559909712,"revision":5968,"compact-revision":2828} {"level":"info","ts":"2026-04-17T02:55:53.424944Z","caller":"traceutil/trace.go:171","msg":"trace[786024975] transaction","detail":"{read_only:false; response_revision:12976; number_of_response:1; }","duration":"113.088566ms","start":"2026-04-17T02:55:53.311833Z","end":"2026-04-17T02:55:53.424921Z","steps":["trace[786024975] 'process raft request' (duration: 107.322473ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:00.191943Z","caller":"traceutil/trace.go:171","msg":"trace[1696506022] transaction","detail":"{read_only:false; response_revision:13073; number_of_response:1; }","duration":"156.855861ms","start":"2026-04-17T02:56:00.035038Z","end":"2026-04-17T02:56:00.191894Z","steps":["trace[1696506022] 'process raft request' (duration: 89.156515ms)","trace[1696506022] 'compare' (duration: 67.494482ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:00.433988Z","caller":"traceutil/trace.go:171","msg":"trace[376215829] transaction","detail":"{read_only:false; number_of_response:1; response_revision:13074; }","duration":"390.891872ms","start":"2026-04-17T02:56:00.043062Z","end":"2026-04-17T02:56:00.433954Z","steps":["trace[376215829] 'process raft request' (duration: 326.535157ms)","trace[376215829] 'compare' (duration: 64.157351ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:00.434093Z","caller":"traceutil/trace.go:171","msg":"trace[2068710959] transaction","detail":"{read_only:false; response_revision:13075; number_of_response:1; }","duration":"118.941451ms","start":"2026-04-17T02:56:00.315121Z","end":"2026-04-17T02:56:00.434062Z","steps":["trace[2068710959] 'process raft request' (duration: 118.827799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:00.43422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:56:00.043035Z","time spent":"391.031724ms","remote":"127.0.0.1:50030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-17T02:56:00.434088Z","caller":"traceutil/trace.go:171","msg":"trace[932318504] linearizableReadLoop","detail":"{readStateIndex:13518; appliedIndex:13516; }","duration":"315.953431ms","start":"2026-04-17T02:56:00.118091Z","end":"2026-04-17T02:56:00.434044Z","steps":["trace[932318504] 'read index received' (duration: 6.0708ms)","trace[932318504] 'applied index is now lower than readState.Index' (duration: 309.875261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:56:00.434378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.239897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:499"} {"level":"info","ts":"2026-04-17T02:56:00.434426Z","caller":"traceutil/trace.go:171","msg":"trace[954791342] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:13075; }","duration":"316.37065ms","start":"2026-04-17T02:56:00.118046Z","end":"2026-04-17T02:56:00.434416Z","steps":["trace[954791342] 'agreement among raft nodes before linearized reading' (duration: 316.110714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:00.434479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:56:00.118033Z","time spent":"316.439161ms","remote":"127.0.0.1:49984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":522,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-04-17T02:56:00.434614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.361628ms","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-04-17T02:56:00.434643Z","caller":"traceutil/trace.go:171","msg":"trace[1668665935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13075; }","duration":"132.424328ms","start":"2026-04-17T02:56:00.30221Z","end":"2026-04-17T02:56:00.434634Z","steps":["trace[1668665935] 'agreement among raft nodes before linearized reading' (duration: 132.302296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:00.434619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.101448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8plv2\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-04-17T02:56:00.434706Z","caller":"traceutil/trace.go:171","msg":"trace[48140422] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8plv2; range_end:; response_count:1; response_revision:13075; }","duration":"239.20098ms","start":"2026-04-17T02:56:00.195481Z","end":"2026-04-17T02:56:00.434682Z","steps":["trace[48140422] 'agreement among raft nodes before linearized reading' (duration: 238.944545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:00.75091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.035083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/groups.openstack.k-orc.cloud\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:00.751008Z","caller":"traceutil/trace.go:171","msg":"trace[1622072031] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/groups.openstack.k-orc.cloud; range_end:; response_count:0; response_revision:13091; }","duration":"111.165925ms","start":"2026-04-17T02:56:00.63982Z","end":"2026-04-17T02:56:00.750986Z","steps":["trace[1622072031] 'range keys from in-memory index tree' (duration: 110.965921ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:00.776327Z","caller":"traceutil/trace.go:171","msg":"trace[2116125948] transaction","detail":"{read_only:false; response_revision:13092; number_of_response:1; }","duration":"135.017084ms","start":"2026-04-17T02:56:00.641286Z","end":"2026-04-17T02:56:00.776303Z","steps":["trace[2116125948] 'process raft request' (duration: 134.86393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.035135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.598916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:56:04.035448Z","caller":"traceutil/trace.go:171","msg":"trace[576894366] transaction","detail":"{read_only:false; response_revision:13366; number_of_response:1; }","duration":"250.730146ms","start":"2026-04-17T02:56:03.784667Z","end":"2026-04-17T02:56:04.035397Z","steps":["trace[576894366] 'process raft request' (duration: 250.583613ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.035543Z","caller":"traceutil/trace.go:171","msg":"trace[906893002] transaction","detail":"{read_only:false; response_revision:13365; number_of_response:1; }","duration":"250.521791ms","start":"2026-04-17T02:56:03.784867Z","end":"2026-04-17T02:56:04.035388Z","steps":["trace[906893002] 'process raft request' (duration: 130.571728ms)","trace[906893002] 'compare' (duration: 119.288479ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:04.035715Z","caller":"traceutil/trace.go:171","msg":"trace[960530002] transaction","detail":"{read_only:false; response_revision:13367; number_of_response:1; }","duration":"248.889656ms","start":"2026-04-17T02:56:03.786797Z","end":"2026-04-17T02:56:04.035686Z","steps":["trace[960530002] 'process raft request' (duration: 248.515029ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.035752Z","caller":"traceutil/trace.go:171","msg":"trace[1691551789] transaction","detail":"{read_only:false; response_revision:13369; number_of_response:1; }","duration":"243.620445ms","start":"2026-04-17T02:56:03.792123Z","end":"2026-04-17T02:56:04.035743Z","steps":["trace[1691551789] 'process raft request' (duration: 243.447301ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.035699Z","caller":"traceutil/trace.go:171","msg":"trace[235366509] transaction","detail":"{read_only:false; response_revision:13368; number_of_response:1; }","duration":"246.533437ms","start":"2026-04-17T02:56:03.789145Z","end":"2026-04-17T02:56:04.035678Z","steps":["trace[235366509] 'process raft request' (duration: 246.364324ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.036039Z","caller":"traceutil/trace.go:171","msg":"trace[1230973643] transaction","detail":"{read_only:false; response_revision:13370; number_of_response:1; }","duration":"243.88097ms","start":"2026-04-17T02:56:03.792131Z","end":"2026-04-17T02:56:04.036012Z","steps":["trace[1230973643] 'process raft request' (duration: 243.489772ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.036289Z","caller":"traceutil/trace.go:171","msg":"trace[1738224136] transaction","detail":"{read_only:false; response_revision:13371; number_of_response:1; }","duration":"239.832844ms","start":"2026-04-17T02:56:03.796446Z","end":"2026-04-17T02:56:04.036279Z","steps":["trace[1738224136] 'process raft request' (duration: 239.473095ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.036735Z","caller":"traceutil/trace.go:171","msg":"trace[403816697] transaction","detail":"{read_only:false; response_revision:13373; number_of_response:1; }","duration":"208.026965ms","start":"2026-04-17T02:56:03.82869Z","end":"2026-04-17T02:56:04.036717Z","steps":["trace[403816697] 'process raft request' (duration: 207.929663ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.037203Z","caller":"traceutil/trace.go:171","msg":"trace[1647844516] transaction","detail":"{read_only:false; number_of_response:1; response_revision:13371; }","duration":"229.553545ms","start":"2026-04-17T02:56:03.807627Z","end":"2026-04-17T02:56:04.037181Z","steps":["trace[1647844516] 'process raft request' (duration: 228.666156ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.038548Z","caller":"traceutil/trace.go:171","msg":"trace[488983499] transaction","detail":"{read_only:false; response_revision:13372; number_of_response:1; }","duration":"229.759749ms","start":"2026-04-17T02:56:03.808767Z","end":"2026-04-17T02:56:04.038527Z","steps":["trace[488983499] 'process raft request' (duration: 227.690906ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.038599Z","caller":"traceutil/trace.go:171","msg":"trace[271241559] linearizableReadLoop","detail":"{readStateIndex:13817; appliedIndex:13809; }","duration":"245.77111ms","start":"2026-04-17T02:56:03.792804Z","end":"2026-04-17T02:56:04.038575Z","steps":["trace[271241559] 'read index received' (duration: 28.948138ms)","trace[271241559] 'applied index is now lower than readState.Index' (duration: 216.820602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:56:04.038888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.74784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-04-17T02:56:04.038888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.548892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-04-17T02:56:04.038961Z","caller":"traceutil/trace.go:171","msg":"trace[1790011341] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:13373; }","duration":"245.854573ms","start":"2026-04-17T02:56:03.793092Z","end":"2026-04-17T02:56:04.038947Z","steps":["trace[1790011341] 'agreement among raft nodes before linearized reading' (duration: 245.702709ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.038969Z","caller":"traceutil/trace.go:171","msg":"trace[1386422375] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:13373; }","duration":"249.651664ms","start":"2026-04-17T02:56:03.789303Z","end":"2026-04-17T02:56:04.038955Z","steps":["trace[1386422375] 'agreement among raft nodes before linearized reading' (duration: 249.448929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.039083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.279479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ztq46\" ","response":"range_response_count:1 size:6447"} {"level":"warn","ts":"2026-04-17T02:56:04.039121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.66917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-17T02:56:04.03915Z","caller":"traceutil/trace.go:171","msg":"trace[659101314] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ztq46; range_end:; response_count:1; response_revision:13373; }","duration":"126.386291ms","start":"2026-04-17T02:56:03.912749Z","end":"2026-04-17T02:56:04.039135Z","steps":["trace[659101314] 'agreement among raft nodes before linearized reading' (duration: 126.159196ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.03917Z","caller":"traceutil/trace.go:171","msg":"trace[298047302] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:13373; }","duration":"173.767102ms","start":"2026-04-17T02:56:03.865388Z","end":"2026-04-17T02:56:04.039155Z","steps":["trace[298047302] 'agreement among raft nodes before linearized reading' (duration: 173.605839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.039201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.461789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/openstack\" ","response":"range_response_count:1 size:368"} {"level":"info","ts":"2026-04-17T02:56:04.039228Z","caller":"traceutil/trace.go:171","msg":"trace[1117124492] range","detail":"{range_begin:/registry/namespaces/openstack; range_end:; response_count:1; response_revision:13373; }","duration":"241.50612ms","start":"2026-04-17T02:56:03.797716Z","end":"2026-04-17T02:56:04.039222Z","steps":["trace[1117124492] 'agreement among raft nodes before linearized reading' (duration: 241.436078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.03942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.72399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-cgf8c\" ","response":"range_response_count:1 size:19052"} {"level":"info","ts":"2026-04-17T02:56:04.039461Z","caller":"traceutil/trace.go:171","msg":"trace[834883654] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-cgf8c; range_end:; response_count:1; response_revision:13373; }","duration":"252.791621ms","start":"2026-04-17T02:56:03.78666Z","end":"2026-04-17T02:56:04.039451Z","steps":["trace[834883654] 'agreement among raft nodes before linearized reading' (duration: 252.683899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.039136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.84537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/nova-api-metadata\" ","response":"range_response_count:1 size:9885"} {"level":"info","ts":"2026-04-17T02:56:04.039638Z","caller":"traceutil/trace.go:171","msg":"trace[1744094704] range","detail":"{range_begin:/registry/deployments/openstack/nova-api-metadata; range_end:; response_count:1; response_revision:13373; }","duration":"236.37688ms","start":"2026-04-17T02:56:03.803252Z","end":"2026-04-17T02:56:04.039628Z","steps":["trace[1744094704] 'agreement among raft nodes before linearized reading' (duration: 235.829759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.040188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.66046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-17T02:56:04.040238Z","caller":"traceutil/trace.go:171","msg":"trace[1311008101] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:13373; }","duration":"129.728011ms","start":"2026-04-17T02:56:03.910497Z","end":"2026-04-17T02:56:04.040225Z","steps":["trace[1311008101] 'agreement among raft nodes before linearized reading' (duration: 128.822662ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.204545Z","caller":"traceutil/trace.go:171","msg":"trace[864495474] transaction","detail":"{read_only:false; response_revision:13374; number_of_response:1; }","duration":"154.555203ms","start":"2026-04-17T02:56:04.04996Z","end":"2026-04-17T02:56:04.204515Z","steps":["trace[864495474] 'process raft request' (duration: 102.96006ms)","trace[864495474] 'compare' (duration: 51.45278ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:04.205484Z","caller":"traceutil/trace.go:171","msg":"trace[402768710] linearizableReadLoop","detail":"{readStateIndex:13827; appliedIndex:13821; }","duration":"151.476636ms","start":"2026-04-17T02:56:04.053991Z","end":"2026-04-17T02:56:04.205468Z","steps":["trace[402768710] 'read index received' (duration: 99.006025ms)","trace[402768710] 'applied index is now lower than readState.Index' (duration: 52.469251ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:04.205483Z","caller":"traceutil/trace.go:171","msg":"trace[936002647] transaction","detail":"{read_only:false; response_revision:13378; number_of_response:1; }","duration":"155.108473ms","start":"2026-04-17T02:56:04.05033Z","end":"2026-04-17T02:56:04.205439Z","steps":["trace[936002647] 'process raft request' (duration: 154.854018ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.205595Z","caller":"traceutil/trace.go:171","msg":"trace[1264049666] transaction","detail":"{read_only:false; response_revision:13380; number_of_response:1; }","duration":"147.811668ms","start":"2026-04-17T02:56:04.057774Z","end":"2026-04-17T02:56:04.205585Z","steps":["trace[1264049666] 'process raft request' (duration: 147.492182ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.20559Z","caller":"traceutil/trace.go:171","msg":"trace[781729923] transaction","detail":"{read_only:false; response_revision:13379; number_of_response:1; }","duration":"151.606729ms","start":"2026-04-17T02:56:04.053962Z","end":"2026-04-17T02:56:04.205569Z","steps":["trace[781729923] 'process raft request' (duration: 151.268502ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.205645Z","caller":"traceutil/trace.go:171","msg":"trace[690148358] transaction","detail":"{read_only:false; response_revision:13376; number_of_response:1; }","duration":"155.393099ms","start":"2026-04-17T02:56:04.050236Z","end":"2026-04-17T02:56:04.205629Z","steps":["trace[690148358] 'process raft request' (duration: 154.866288ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.205648Z","caller":"traceutil/trace.go:171","msg":"trace[551483618] transaction","detail":"{read_only:false; response_revision:13382; number_of_response:1; }","duration":"143.171549ms","start":"2026-04-17T02:56:04.062446Z","end":"2026-04-17T02:56:04.205618Z","steps":["trace[551483618] 'process raft request' (duration: 142.921714ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.205748Z","caller":"traceutil/trace.go:171","msg":"trace[94238519] transaction","detail":"{read_only:false; response_revision:13377; number_of_response:1; }","duration":"155.43471ms","start":"2026-04-17T02:56:04.050273Z","end":"2026-04-17T02:56:04.205707Z","steps":["trace[94238519] 'process raft request' (duration: 154.868618ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.205838Z","caller":"traceutil/trace.go:171","msg":"trace[178538894] transaction","detail":"{read_only:false; response_revision:13375; number_of_response:1; }","duration":"155.616983ms","start":"2026-04-17T02:56:04.050215Z","end":"2026-04-17T02:56:04.205832Z","steps":["trace[178538894] 'process raft request' (duration: 154.795416ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.205737Z","caller":"traceutil/trace.go:171","msg":"trace[408155223] transaction","detail":"{read_only:false; response_revision:13381; number_of_response:1; }","duration":"143.475985ms","start":"2026-04-17T02:56:04.062244Z","end":"2026-04-17T02:56:04.20572Z","steps":["trace[408155223] 'process raft request' (duration: 143.053496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.206187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.17999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-api-metadata\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-17T02:56:04.206239Z","caller":"traceutil/trace.go:171","msg":"trace[774237191] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-api-metadata; range_end:; response_count:1; response_revision:13383; }","duration":"152.295183ms","start":"2026-04-17T02:56:04.053933Z","end":"2026-04-17T02:56:04.206228Z","steps":["trace[774237191] 'agreement among raft nodes before linearized reading' (duration: 152.097719ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.20652Z","caller":"traceutil/trace.go:171","msg":"trace[362706101] transaction","detail":"{read_only:false; response_revision:13383; number_of_response:1; }","duration":"142.197117ms","start":"2026-04-17T02:56:04.06431Z","end":"2026-04-17T02:56:04.206507Z","steps":["trace[362706101] 'process raft request' (duration: 141.114824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.213614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.40836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-compute\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-17T02:56:04.213695Z","caller":"traceutil/trace.go:171","msg":"trace[2077276943] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-compute; range_end:; response_count:1; response_revision:13384; }","duration":"155.526444ms","start":"2026-04-17T02:56:04.058154Z","end":"2026-04-17T02:56:04.21368Z","steps":["trace[2077276943] 'agreement among raft nodes before linearized reading' (duration: 155.326559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.213709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.507493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-scheduler\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-04-17T02:56:04.213736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.090185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-17T02:56:04.213748Z","caller":"traceutil/trace.go:171","msg":"trace[1412811989] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-scheduler; range_end:; response_count:1; response_revision:13384; }","duration":"155.541884ms","start":"2026-04-17T02:56:04.058189Z","end":"2026-04-17T02:56:04.213731Z","steps":["trace[1412811989] 'agreement among raft nodes before linearized reading' (duration: 155.479842ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.213775Z","caller":"traceutil/trace.go:171","msg":"trace[2123053256] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:13384; }","duration":"127.169196ms","start":"2026-04-17T02:56:04.08659Z","end":"2026-04-17T02:56:04.213759Z","steps":["trace[2123053256] 'agreement among raft nodes before linearized reading' (duration: 127.081844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.213842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.730488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-conductor\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-04-17T02:56:04.213619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.415081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-novncproxy\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-17T02:56:04.213866Z","caller":"traceutil/trace.go:171","msg":"trace[1492556544] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-conductor; range_end:; response_count:1; response_revision:13384; }","duration":"155.769588ms","start":"2026-04-17T02:56:04.058091Z","end":"2026-04-17T02:56:04.213861Z","steps":["trace[1492556544] 'agreement among raft nodes before linearized reading' (duration: 155.705557ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:04.213875Z","caller":"traceutil/trace.go:171","msg":"trace[1177357147] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-novncproxy; range_end:; response_count:1; response_revision:13384; }","duration":"155.695746ms","start":"2026-04-17T02:56:04.058171Z","end":"2026-04-17T02:56:04.213867Z","steps":["trace[1177357147] 'agreement among raft nodes before linearized reading' (duration: 155.305178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:04.213631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.405861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-cell-setup\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-17T02:56:04.213948Z","caller":"traceutil/trace.go:171","msg":"trace[1342777227] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-cell-setup; range_end:; response_count:1; response_revision:13384; }","duration":"155.737328ms","start":"2026-04-17T02:56:04.058204Z","end":"2026-04-17T02:56:04.213941Z","steps":["trace[1342777227] 'agreement among raft nodes before linearized reading' (duration: 155.306889ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:09.014468Z","caller":"traceutil/trace.go:171","msg":"trace[1553223346] transaction","detail":"{read_only:false; response_revision:13569; number_of_response:1; }","duration":"137.550578ms","start":"2026-04-17T02:56:08.876884Z","end":"2026-04-17T02:56:09.014435Z","steps":["trace[1553223346] 'process raft request' (duration: 48.26406ms)","trace[1553223346] 'compare' (duration: 89.039903ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:09.014979Z","caller":"traceutil/trace.go:171","msg":"trace[1311313991] transaction","detail":"{read_only:false; response_revision:13570; number_of_response:1; }","duration":"127.246119ms","start":"2026-04-17T02:56:08.887718Z","end":"2026-04-17T02:56:09.014964Z","steps":["trace[1311313991] 'process raft request' (duration: 126.992994ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:09.015155Z","caller":"traceutil/trace.go:171","msg":"trace[280690252] transaction","detail":"{read_only:false; response_revision:13571; number_of_response:1; }","duration":"113.898884ms","start":"2026-04-17T02:56:08.901249Z","end":"2026-04-17T02:56:09.015148Z","steps":["trace[280690252] 'process raft request' (duration: 113.582697ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:11.921957Z","caller":"traceutil/trace.go:171","msg":"trace[942525691] linearizableReadLoop","detail":"{readStateIndex:14356; appliedIndex:14354; }","duration":"128.025896ms","start":"2026-04-17T02:56:11.793906Z","end":"2026-04-17T02:56:11.921931Z","steps":["trace[942525691] 'read index received' (duration: 29.692484ms)","trace[942525691] 'applied index is now lower than readState.Index' (duration: 98.332262ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:56:11.922075Z","caller":"traceutil/trace.go:171","msg":"trace[1267830972] transaction","detail":"{read_only:false; response_revision:13896; number_of_response:1; }","duration":"116.382756ms","start":"2026-04-17T02:56:11.805684Z","end":"2026-04-17T02:56:11.922068Z","steps":["trace[1267830972] 'process raft request' (duration: 116.161851ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:11.92225Z","caller":"traceutil/trace.go:171","msg":"trace[807883468] transaction","detail":"{read_only:false; response_revision:13895; number_of_response:1; }","duration":"128.671499ms","start":"2026-04-17T02:56:11.79356Z","end":"2026-04-17T02:56:11.922231Z","steps":["trace[807883468] 'process raft request' (duration: 107.736831ms)","trace[807883468] 'compare' (duration: 20.335604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:56:11.922248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.315782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.922476Z","caller":"traceutil/trace.go:171","msg":"trace[1233813929] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:13896; }","duration":"128.560296ms","start":"2026-04-17T02:56:11.793881Z","end":"2026-04-17T02:56:11.922441Z","steps":["trace[1233813929] 'agreement among raft nodes before linearized reading' (duration: 128.314181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.924955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.934098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.925007Z","caller":"traceutil/trace.go:171","msg":"trace[1644887600] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:13901; }","duration":"130.985839ms","start":"2026-04-17T02:56:11.794001Z","end":"2026-04-17T02:56:11.924987Z","steps":["trace[1644887600] 'agreement among raft nodes before linearized reading' (duration: 130.900267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.925883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.159598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.925973Z","caller":"traceutil/trace.go:171","msg":"trace[904515581] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:13901; }","duration":"113.481905ms","start":"2026-04-17T02:56:11.812469Z","end":"2026-04-17T02:56:11.925951Z","steps":["trace[904515581] 'agreement among raft nodes before linearized reading' (duration: 113.086457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.926063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.535546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capo-system/capo-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.926092Z","caller":"traceutil/trace.go:171","msg":"trace[1873401222] range","detail":"{range_begin:/registry/serviceaccounts/capo-system/capo-manager; range_end:; response_count:0; response_revision:13901; }","duration":"121.603258ms","start":"2026-04-17T02:56:11.804478Z","end":"2026-04-17T02:56:11.926081Z","steps":["trace[1873401222] 'agreement among raft nodes before linearized reading' (duration: 121.556757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.926191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.380583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.926208Z","caller":"traceutil/trace.go:171","msg":"trace[2143049170] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:13901; }","duration":"128.411483ms","start":"2026-04-17T02:56:11.797788Z","end":"2026-04-17T02:56:11.9262Z","steps":["trace[2143049170] 'agreement among raft nodes before linearized reading' (duration: 128.383383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.926396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.008024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.926449Z","caller":"traceutil/trace.go:171","msg":"trace[216379798] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:13901; }","duration":"113.096136ms","start":"2026-04-17T02:56:11.813338Z","end":"2026-04-17T02:56:11.926434Z","steps":["trace[216379798] 'agreement among raft nodes before linearized reading' (duration: 113.006784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.926415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.624001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.926567Z","caller":"traceutil/trace.go:171","msg":"trace[1186045605] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:13901; }","duration":"101.773974ms","start":"2026-04-17T02:56:11.824784Z","end":"2026-04-17T02:56:11.926558Z","steps":["trace[1186045605] 'agreement among raft nodes before linearized reading' (duration: 101.61334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:11.938949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.888096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:11.939033Z","caller":"traceutil/trace.go:171","msg":"trace[607310139] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:13901; }","duration":"114.352533ms","start":"2026-04-17T02:56:11.824661Z","end":"2026-04-17T02:56:11.939014Z","steps":["trace[607310139] 'agreement among raft nodes before linearized reading' (duration: 101.891917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:12.087819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.363699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-17T02:56:12.087901Z","caller":"traceutil/trace.go:171","msg":"trace[1672913567] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13911; }","duration":"110.465401ms","start":"2026-04-17T02:56:11.977418Z","end":"2026-04-17T02:56:12.087883Z","steps":["trace[1672913567] 'agreement among raft nodes before linearized reading' (duration: 110.241216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:12.087892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.382113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:56:12.088026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.916466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-17T02:56:12.088169Z","caller":"traceutil/trace.go:171","msg":"trace[350752846] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:13911; }","duration":"101.253994ms","start":"2026-04-17T02:56:11.986895Z","end":"2026-04-17T02:56:12.088149Z","steps":["trace[350752846] 'agreement among raft nodes before linearized reading' (duration: 100.774503ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:12.08802Z","caller":"traceutil/trace.go:171","msg":"trace[1523350450] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:13911; }","duration":"113.561506ms","start":"2026-04-17T02:56:11.974437Z","end":"2026-04-17T02:56:12.087998Z","steps":["trace[1523350450] 'agreement among raft nodes before linearized reading' (duration: 113.326171ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:12.153838Z","caller":"traceutil/trace.go:171","msg":"trace[183931500] transaction","detail":"{read_only:false; response_revision:13912; number_of_response:1; }","duration":"140.45672ms","start":"2026-04-17T02:56:12.013341Z","end":"2026-04-17T02:56:12.153798Z","steps":["trace[183931500] 'process raft request' (duration: 140.216365ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:12.153938Z","caller":"traceutil/trace.go:171","msg":"trace[1211643200] transaction","detail":"{read_only:false; response_revision:13915; number_of_response:1; }","duration":"129.376114ms","start":"2026-04-17T02:56:12.024539Z","end":"2026-04-17T02:56:12.153915Z","steps":["trace[1211643200] 'process raft request' (duration: 129.294042ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:12.15401Z","caller":"traceutil/trace.go:171","msg":"trace[92022172] transaction","detail":"{read_only:false; response_revision:13914; number_of_response:1; }","duration":"136.23933ms","start":"2026-04-17T02:56:12.017693Z","end":"2026-04-17T02:56:12.153932Z","steps":["trace[92022172] 'process raft request' (duration: 136.099147ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:12.154053Z","caller":"traceutil/trace.go:171","msg":"trace[910610396] transaction","detail":"{read_only:false; response_revision:13913; number_of_response:1; }","duration":"137.229742ms","start":"2026-04-17T02:56:12.016789Z","end":"2026-04-17T02:56:12.154018Z","steps":["trace[910610396] 'process raft request' (duration: 136.917126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:12.15416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.098914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clusters.cluster.x-k8s.io\" ","response":"range_response_count:1 size:82337"} {"level":"info","ts":"2026-04-17T02:56:12.154202Z","caller":"traceutil/trace.go:171","msg":"trace[169820459] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clusters.cluster.x-k8s.io; range_end:; response_count:1; response_revision:13915; }","duration":"134.177846ms","start":"2026-04-17T02:56:12.020013Z","end":"2026-04-17T02:56:12.154191Z","steps":["trace[169820459] 'agreement among raft nodes before linearized reading' (duration: 133.935951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:12.154317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.158598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-04-17T02:56:12.154345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.512191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/capo-system/capo-serving-cert\" ","response":"range_response_count:1 size:2486"} {"level":"info","ts":"2026-04-17T02:56:12.154348Z","caller":"traceutil/trace.go:171","msg":"trace[1704477993] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:13915; }","duration":"135.239889ms","start":"2026-04-17T02:56:12.0191Z","end":"2026-04-17T02:56:12.15434Z","steps":["trace[1704477993] 'agreement among raft nodes before linearized reading' (duration: 135.107306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:12.154366Z","caller":"traceutil/trace.go:171","msg":"trace[2014334557] range","detail":"{range_begin:/registry/cert-manager.io/certificates/capo-system/capo-serving-cert; range_end:; response_count:1; response_revision:13915; }","duration":"132.545051ms","start":"2026-04-17T02:56:12.021814Z","end":"2026-04-17T02:56:12.154359Z","steps":["trace[2014334557] 'agreement among raft nodes before linearized reading' (duration: 132.47772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:12.154406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.445204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wbj9l\" ","response":"range_response_count:1 size:9708"} {"level":"info","ts":"2026-04-17T02:56:12.154456Z","caller":"traceutil/trace.go:171","msg":"trace[511452213] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wbj9l; range_end:; response_count:1; response_revision:13915; }","duration":"121.544207ms","start":"2026-04-17T02:56:12.032898Z","end":"2026-04-17T02:56:12.154443Z","steps":["trace[511452213] 'agreement among raft nodes before linearized reading' (duration: 121.358942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:56:12.154304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.748194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:56:12.154555Z","caller":"traceutil/trace.go:171","msg":"trace[1967532294] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:13915; }","duration":"139.048311ms","start":"2026-04-17T02:56:12.01548Z","end":"2026-04-17T02:56:12.154528Z","steps":["trace[1967532294] 'agreement among raft nodes before linearized reading' (duration: 138.736813ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:56:18.768836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10211} {"level":"info","ts":"2026-04-17T02:56:18.90553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10211,"took":"133.92049ms","hash":2082112064,"current-db-size-bytes":69222400,"current-db-size":"69 MB","current-db-size-in-use-bytes":46120960,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-04-17T02:56:18.905708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2082112064,"revision":10211,"compact-revision":5968} {"level":"info","ts":"2026-04-17T03:01:18.775303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14120} {"level":"info","ts":"2026-04-17T03:01:18.910525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14120,"took":"129.963125ms","hash":1396377387,"current-db-size-bytes":69222400,"current-db-size":"69 MB","current-db-size-in-use-bytes":46981120,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-04-17T03:01:18.910637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1396377387,"revision":14120,"compact-revision":10211} {"level":"info","ts":"2026-04-17T03:04:08.076346Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4f2979fc0f638003","local-member-applied-index":20004,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T03:04:08.083175Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-04-17T03:04:08.08335Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"warn","ts":"2026-04-17T03:04:46.063473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.940884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/octavia-client-ca\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:04:46.063573Z","caller":"traceutil/trace.go:171","msg":"trace[1950227613] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/octavia-client-ca; range_end:; response_count:0; response_revision:19743; }","duration":"116.076127ms","start":"2026-04-17T03:04:45.947472Z","end":"2026-04-17T03:04:46.063549Z","steps":["trace[1950227613] 'range keys from in-memory index tree' (duration: 115.843072ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:04:46.297022Z","caller":"traceutil/trace.go:171","msg":"trace[1382945005] transaction","detail":"{read_only:false; response_revision:19745; number_of_response:1; }","duration":"199.637857ms","start":"2026-04-17T03:04:46.097344Z","end":"2026-04-17T03:04:46.296981Z","steps":["trace[1382945005] 'process raft request' (duration: 199.432492ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:04:46.327181Z","caller":"traceutil/trace.go:171","msg":"trace[1124352995] linearizableReadLoop","detail":"{readStateIndex:20350; appliedIndex:20347; }","duration":"158.263401ms","start":"2026-04-17T03:04:46.168889Z","end":"2026-04-17T03:04:46.327152Z","steps":["trace[1124352995] 'read index received' (duration: 127.921261ms)","trace[1124352995] 'applied index is now lower than readState.Index' (duration: 30.34115ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:04:46.327511Z","caller":"traceutil/trace.go:171","msg":"trace[1842298630] transaction","detail":"{read_only:false; response_revision:19746; number_of_response:1; }","duration":"229.859133ms","start":"2026-04-17T03:04:46.097635Z","end":"2026-04-17T03:04:46.327494Z","steps":["trace[1842298630] 'process raft request' (duration: 229.300392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:04:46.327661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.750811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:04:46.327645Z","caller":"traceutil/trace.go:171","msg":"trace[451334669] transaction","detail":"{read_only:false; number_of_response:1; response_revision:19746; }","duration":"227.32142ms","start":"2026-04-17T03:04:46.100282Z","end":"2026-04-17T03:04:46.327604Z","steps":["trace[451334669] 'process raft request' (duration: 226.830119ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:04:46.327749Z","caller":"traceutil/trace.go:171","msg":"trace[336825170] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:19746; }","duration":"158.902634ms","start":"2026-04-17T03:04:46.168828Z","end":"2026-04-17T03:04:46.32773Z","steps":["trace[336825170] 'agreement among raft nodes before linearized reading' (duration: 158.7184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:04:46.352131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.368547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-cgf8c\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-04-17T03:04:46.352211Z","caller":"traceutil/trace.go:171","msg":"trace[305773117] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-cgf8c; range_end:; response_count:1; response_revision:19747; }","duration":"133.508331ms","start":"2026-04-17T03:04:46.218684Z","end":"2026-04-17T03:04:46.352192Z","steps":["trace[305773117] 'agreement among raft nodes before linearized reading' (duration: 133.241555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:22.964398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.802293ms","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-04-17T03:05:22.964508Z","caller":"traceutil/trace.go:171","msg":"trace[1908544146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20356; }","duration":"173.957417ms","start":"2026-04-17T03:05:22.790528Z","end":"2026-04-17T03:05:22.964486Z","steps":["trace[1908544146] 'range keys from in-memory index tree' (duration: 173.63005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:22.964705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.574111ms","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-04-17T03:05:22.964813Z","caller":"traceutil/trace.go:171","msg":"trace[135206523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20356; }","duration":"175.721194ms","start":"2026-04-17T03:05:22.789061Z","end":"2026-04-17T03:05:22.964782Z","steps":["trace[135206523] 'range keys from in-memory index tree' (duration: 175.322245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:22.965165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.464534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:05:22.9654Z","caller":"traceutil/trace.go:171","msg":"trace[1139160352] linearizableReadLoop","detail":"{readStateIndex:20978; appliedIndex:20977; }","duration":"170.620995ms","start":"2026-04-17T03:05:22.794765Z","end":"2026-04-17T03:05:22.965386Z","steps":["trace[1139160352] 'read index received' (duration: 11.781682ms)","trace[1139160352] 'applied index is now lower than readState.Index' (duration: 158.837793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:22.965507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.741688ms","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-04-17T03:05:22.965572Z","caller":"traceutil/trace.go:171","msg":"trace[1488823821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20357; }","duration":"170.866071ms","start":"2026-04-17T03:05:22.794694Z","end":"2026-04-17T03:05:22.96556Z","steps":["trace[1488823821] 'agreement among raft nodes before linearized reading' (duration: 170.730718ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:22.965721Z","caller":"traceutil/trace.go:171","msg":"trace[423207951] transaction","detail":"{read_only:false; response_revision:20357; number_of_response:1; }","duration":"174.345686ms","start":"2026-04-17T03:05:22.791363Z","end":"2026-04-17T03:05:22.965708Z","steps":["trace[423207951] 'process raft request' (duration: 15.248617ms)","trace[423207951] 'compare' (duration: 157.723879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:23.282099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.707437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:05:23.282504Z","caller":"traceutil/trace.go:171","msg":"trace[1239755475] linearizableReadLoop","detail":"{readStateIndex:20981; appliedIndex:20978; }","duration":"301.239793ms","start":"2026-04-17T03:05:22.981239Z","end":"2026-04-17T03:05:23.282479Z","steps":["trace[1239755475] 'read index received' (duration: 29.690316ms)","trace[1239755475] 'applied index is now lower than readState.Index' (duration: 271.547777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:23.282751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.50369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-17T03:05:23.282789Z","caller":"traceutil/trace.go:171","msg":"trace[148367611] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20360; }","duration":"301.578791ms","start":"2026-04-17T03:05:22.9812Z","end":"2026-04-17T03:05:23.282779Z","steps":["trace[148367611] 'agreement among raft nodes before linearized reading' (duration: 301.365106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.282824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:22.981184Z","time spent":"301.632122ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-17T03:05:23.282927Z","caller":"traceutil/trace.go:171","msg":"trace[1003192819] transaction","detail":"{read_only:false; response_revision:20359; number_of_response:1; }","duration":"313.865244ms","start":"2026-04-17T03:05:22.969022Z","end":"2026-04-17T03:05:23.282888Z","steps":["trace[1003192819] 'process raft request' (duration: 142.226137ms)","trace[1003192819] 'compare' (duration: 170.448241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:23.283269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:22.968993Z","time spent":"314.132599ms","remote":"127.0.0.1:50170","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-04-17T03:05:23.284057Z","caller":"traceutil/trace.go:171","msg":"trace[2093413116] transaction","detail":"{read_only:false; response_revision:20360; number_of_response:1; }","duration":"310.340367ms","start":"2026-04-17T03:05:22.973682Z","end":"2026-04-17T03:05:23.284023Z","steps":["trace[2093413116] 'process raft request' (duration: 308.637401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.284213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:22.973655Z","time spent":"310.507131ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T03:05:23.298719Z","caller":"traceutil/trace.go:171","msg":"trace[1078072016] transaction","detail":"{read_only:false; response_revision:20361; number_of_response:1; }","duration":"216.590181ms","start":"2026-04-17T03:05:23.082107Z","end":"2026-04-17T03:05:23.298698Z","steps":["trace[1078072016] 'process raft request' (duration: 216.455697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.525052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.181661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:05:23.525266Z","caller":"traceutil/trace.go:171","msg":"trace[310196955] transaction","detail":"{read_only:false; response_revision:20362; number_of_response:1; }","duration":"404.842902ms","start":"2026-04-17T03:05:23.120389Z","end":"2026-04-17T03:05:23.525232Z","steps":["trace[310196955] 'process raft request' (duration: 265.190651ms)","trace[310196955] 'compare' (duration: 138.855984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:23.525437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:23.12036Z","time spent":"404.989675ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T03:05:23.525513Z","caller":"traceutil/trace.go:171","msg":"trace[2145477979] transaction","detail":"{read_only:false; response_revision:20363; number_of_response:1; }","duration":"235.121475ms","start":"2026-04-17T03:05:23.290371Z","end":"2026-04-17T03:05:23.525492Z","steps":["trace[2145477979] 'process raft request' (duration: 234.802839ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.525738Z","caller":"traceutil/trace.go:171","msg":"trace[273238007] linearizableReadLoop","detail":"{readStateIndex:20986; appliedIndex:20982; }","duration":"152.1489ms","start":"2026-04-17T03:05:23.373561Z","end":"2026-04-17T03:05:23.52571Z","steps":["trace[273238007] 'read index received' (duration: 12.039499ms)","trace[273238007] 'applied index is now lower than readState.Index' (duration: 140.108321ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:05:23.525738Z","caller":"traceutil/trace.go:171","msg":"trace[1524503238] transaction","detail":"{read_only:false; response_revision:20364; number_of_response:1; }","duration":"216.709823ms","start":"2026-04-17T03:05:23.308984Z","end":"2026-04-17T03:05:23.525694Z","steps":["trace[1524503238] 'process raft request' (duration: 216.317003ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.525797Z","caller":"traceutil/trace.go:171","msg":"trace[698559257] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"155.90808ms","start":"2026-04-17T03:05:23.369858Z","end":"2026-04-17T03:05:23.525766Z","steps":["trace[698559257] 'process raft request' (duration: 155.730216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.525891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.323324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:05:23.525937Z","caller":"traceutil/trace.go:171","msg":"trace[943158741] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:20365; }","duration":"152.421906ms","start":"2026-04-17T03:05:23.373503Z","end":"2026-04-17T03:05:23.525925Z","steps":["trace[943158741] 'agreement among raft nodes before linearized reading' (duration: 152.342574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.547401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.64669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-17T03:05:23.547418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.013926ms","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-04-17T03:05:23.547408Z","caller":"traceutil/trace.go:171","msg":"trace[561177054] transaction","detail":"{read_only:false; response_revision:20366; number_of_response:1; }","duration":"120.295657ms","start":"2026-04-17T03:05:23.427069Z","end":"2026-04-17T03:05:23.547365Z","steps":["trace[561177054] 'process raft request' (duration: 120.032611ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.547466Z","caller":"traceutil/trace.go:171","msg":"trace[1866966045] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20366; }","duration":"131.769393ms","start":"2026-04-17T03:05:23.415682Z","end":"2026-04-17T03:05:23.547452Z","steps":["trace[1866966045] 'agreement among raft nodes before linearized reading' (duration: 131.62782ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.547485Z","caller":"traceutil/trace.go:171","msg":"trace[734221477] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:20366; }","duration":"131.101077ms","start":"2026-04-17T03:05:23.41637Z","end":"2026-04-17T03:05:23.547471Z","steps":["trace[734221477] 'agreement among raft nodes before linearized reading' (duration: 130.948334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.547679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.26532ms","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-04-17T03:05:23.547717Z","caller":"traceutil/trace.go:171","msg":"trace[1893521407] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20366; }","duration":"131.309472ms","start":"2026-04-17T03:05:23.416398Z","end":"2026-04-17T03:05:23.547708Z","steps":["trace[1893521407] 'agreement among raft nodes before linearized reading' (duration: 131.257551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.547805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.186211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:05:23.547836Z","caller":"traceutil/trace.go:171","msg":"trace[347645902] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:20366; }","duration":"132.241893ms","start":"2026-04-17T03:05:23.415586Z","end":"2026-04-17T03:05:23.547828Z","steps":["trace[347645902] 'agreement among raft nodes before linearized reading' (duration: 132.199452ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.902424Z","caller":"traceutil/trace.go:171","msg":"trace[1872404522] linearizableReadLoop","detail":"{readStateIndex:20988; appliedIndex:20987; }","duration":"344.558981ms","start":"2026-04-17T03:05:23.557825Z","end":"2026-04-17T03:05:23.902384Z","steps":["trace[1872404522] 'read index received' (duration: 269.587264ms)","trace[1872404522] 'applied index is now lower than readState.Index' (duration: 74.970217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:23.902862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.012501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-17T03:05:23.902956Z","caller":"traceutil/trace.go:171","msg":"trace[1172932100] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20367; }","duration":"345.159774ms","start":"2026-04-17T03:05:23.557766Z","end":"2026-04-17T03:05:23.902926Z","steps":["trace[1172932100] 'agreement among raft nodes before linearized reading' (duration: 344.916419ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.902697Z","caller":"traceutil/trace.go:171","msg":"trace[1856504915] transaction","detail":"{read_only:false; response_revision:20367; number_of_response:1; }","duration":"351.116431ms","start":"2026-04-17T03:05:23.55154Z","end":"2026-04-17T03:05:23.902657Z","steps":["trace[1856504915] 'process raft request' (duration: 275.843969ms)","trace[1856504915] 'compare' (duration: 74.588838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:05:23.903018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:23.557725Z","time spent":"345.279896ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-17T03:05:23.903164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:23.551512Z","time spent":"351.537221ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T03:05:23.909006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.250945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:05:23.909081Z","caller":"traceutil/trace.go:171","msg":"trace[1027742726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20368; }","duration":"296.377568ms","start":"2026-04-17T03:05:23.612686Z","end":"2026-04-17T03:05:23.909064Z","steps":["trace[1027742726] 'agreement among raft nodes before linearized reading' (duration: 296.238125ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:23.909101Z","caller":"traceutil/trace.go:171","msg":"trace[1413199224] transaction","detail":"{read_only:false; response_revision:20368; number_of_response:1; }","duration":"317.570563ms","start":"2026-04-17T03:05:23.591504Z","end":"2026-04-17T03:05:23.909075Z","steps":["trace[1413199224] 'process raft request' (duration: 317.226665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:05:23.90921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:05:23.59147Z","time spent":"317.687565ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T03:05:23.909483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.749352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29606580-dbrg4\" ","response":"range_response_count:1 size:6959"} {"level":"info","ts":"2026-04-17T03:05:23.909572Z","caller":"traceutil/trace.go:171","msg":"trace[714919203] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29606580-dbrg4; range_end:; response_count:1; response_revision:20368; }","duration":"264.832944ms","start":"2026-04-17T03:05:23.644715Z","end":"2026-04-17T03:05:23.909548Z","steps":["trace[714919203] 'agreement among raft nodes before linearized reading' (duration: 264.425384ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:05:27.087631Z","caller":"traceutil/trace.go:171","msg":"trace[5288606] transaction","detail":"{read_only:false; response_revision:20403; number_of_response:1; }","duration":"106.378778ms","start":"2026-04-17T03:05:26.981195Z","end":"2026-04-17T03:05:27.087574Z","steps":["trace[5288606] 'process raft request' (duration: 64.91664ms)","trace[5288606] 'compare' (duration: 41.221653ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:06:18.797589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17661} {"level":"info","ts":"2026-04-17T03:06:18.93295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17661,"took":"131.819904ms","hash":2358591740,"current-db-size-bytes":69222400,"current-db-size":"69 MB","current-db-size-in-use-bytes":40054784,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-17T03:06:18.933033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2358591740,"revision":17661,"compact-revision":14120} {"level":"info","ts":"2026-04-17T03:06:41.650496Z","caller":"traceutil/trace.go:171","msg":"trace[591483457] transaction","detail":"{read_only:false; response_revision:21266; number_of_response:1; }","duration":"147.02839ms","start":"2026-04-17T03:06:41.503441Z","end":"2026-04-17T03:06:41.650469Z","steps":["trace[591483457] 'process raft request' (duration: 129.646497ms)","trace[591483457] 'compare' (duration: 16.839001ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:06:41.650862Z","caller":"traceutil/trace.go:171","msg":"trace[551506557] transaction","detail":"{read_only:false; response_revision:21267; number_of_response:1; }","duration":"145.469347ms","start":"2026-04-17T03:06:41.505359Z","end":"2026-04-17T03:06:41.650828Z","steps":["trace[551506557] 'process raft request' (duration: 144.782642ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:06:41.650906Z","caller":"traceutil/trace.go:171","msg":"trace[1423929047] linearizableReadLoop","detail":"{readStateIndex:21906; appliedIndex:21903; }","duration":"102.19924ms","start":"2026-04-17T03:06:41.548697Z","end":"2026-04-17T03:06:41.650896Z","steps":["trace[1423929047] 'read index received' (duration: 6.05382ms)","trace[1423929047] 'applied index is now lower than readState.Index' (duration: 96.14448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:06:41.651027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.316132ms","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-04-17T03:06:41.651049Z","caller":"traceutil/trace.go:171","msg":"trace[378110327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21269; }","duration":"102.384854ms","start":"2026-04-17T03:06:41.548657Z","end":"2026-04-17T03:06:41.651041Z","steps":["trace[378110327] 'agreement among raft nodes before linearized reading' (duration: 102.276842ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:07:25.428209Z","caller":"traceutil/trace.go:171","msg":"trace[1980205483] transaction","detail":"{read_only:false; response_revision:21741; number_of_response:1; }","duration":"123.391823ms","start":"2026-04-17T03:07:25.304788Z","end":"2026-04-17T03:07:25.42818Z","steps":["trace[1980205483] 'process raft request' (duration: 123.21631ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:07:25.621505Z","caller":"traceutil/trace.go:171","msg":"trace[1431169877] linearizableReadLoop","detail":"{readStateIndex:22391; appliedIndex:22389; }","duration":"283.85232ms","start":"2026-04-17T03:07:25.337627Z","end":"2026-04-17T03:07:25.621479Z","steps":["trace[1431169877] 'read index received' (duration: 90.375746ms)","trace[1431169877] 'applied index is now lower than readState.Index' (duration: 193.475104ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:07:25.621798Z","caller":"traceutil/trace.go:171","msg":"trace[2147364981] transaction","detail":"{read_only:false; response_revision:21742; number_of_response:1; }","duration":"310.626605ms","start":"2026-04-17T03:07:25.311159Z","end":"2026-04-17T03:07:25.621785Z","steps":["trace[2147364981] 'process raft request' (duration: 310.160615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.621914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.311134Z","time spent":"310.693145ms","remote":"127.0.0.1:50084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7923,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T03:07:25.622077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.417712ms","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-04-17T03:07:25.622179Z","caller":"traceutil/trace.go:171","msg":"trace[735185107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21742; }","duration":"284.577975ms","start":"2026-04-17T03:07:25.337578Z","end":"2026-04-17T03:07:25.622156Z","steps":["trace[735185107] 'agreement among raft nodes before linearized reading' (duration: 284.291219ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:07:25.641923Z","caller":"traceutil/trace.go:171","msg":"trace[1995746166] transaction","detail":"{read_only:false; response_revision:21743; number_of_response:1; }","duration":"209.321455ms","start":"2026-04-17T03:07:25.432542Z","end":"2026-04-17T03:07:25.641863Z","steps":["trace[1995746166] 'process raft request' (duration: 208.937247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.642038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.290123ms","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":"info","ts":"2026-04-17T03:07:25.642117Z","caller":"traceutil/trace.go:171","msg":"trace[1698545522] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:21744; }","duration":"265.389605ms","start":"2026-04-17T03:07:25.376708Z","end":"2026-04-17T03:07:25.642097Z","steps":["trace[1698545522] 'agreement among raft nodes before linearized reading' (duration: 265.258072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.642355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.377662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:07:25.64241Z","caller":"traceutil/trace.go:171","msg":"trace[692325122] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21744; }","duration":"277.489514ms","start":"2026-04-17T03:07:25.364908Z","end":"2026-04-17T03:07:25.642397Z","steps":["trace[692325122] 'agreement among raft nodes before linearized reading' (duration: 277.363331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.642471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.839562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:1 size:12607"} {"level":"info","ts":"2026-04-17T03:07:25.642584Z","caller":"traceutil/trace.go:171","msg":"trace[249795887] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:1; response_revision:21744; }","duration":"270.986794ms","start":"2026-04-17T03:07:25.37157Z","end":"2026-04-17T03:07:25.642557Z","steps":["trace[249795887] 'agreement among raft nodes before linearized reading' (duration: 270.697348ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:11:18.811837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21025} {"level":"info","ts":"2026-04-17T03:11:18.963393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21025,"took":"146.965967ms","hash":1413495118,"current-db-size-bytes":69222400,"current-db-size":"69 MB","current-db-size-in-use-bytes":35762176,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-17T03:11:18.963483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1413495118,"revision":21025,"compact-revision":17661} {"level":"info","ts":"2026-04-17T03:16:18.820436Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23793} {"level":"info","ts":"2026-04-17T03:16:18.969369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23793,"took":"144.133478ms","hash":3971848446,"current-db-size-bytes":69222400,"current-db-size":"69 MB","current-db-size-in-use-bytes":32387072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-17T03:16:18.969445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3971848446,"revision":23793,"compact-revision":21025} {"level":"info","ts":"2026-04-17T03:17:20.13197Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a8c.wal"} {"level":"info","ts":"2026-04-17T03:21:18.846636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26034} {"level":"info","ts":"2026-04-17T03:21:18.969361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26034,"took":"119.308884ms","hash":2743231877,"current-db-size-bytes":69222400,"current-db-size":"69 MB","current-db-size-in-use-bytes":33333248,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-17T03:21:18.969448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2743231877,"revision":26034,"compact-revision":23793}