{"level":"warn","ts":"2026-05-11T07:26:20.102326Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T07:26:20.10243Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-05-11T07:26:20.102519Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T07:26:20.102541Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-11T07:26:20.102562Z","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-05-11T07:26:20.10324Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-11T07:26:20.10336Z","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.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-05-11T07:26:20.107689Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.001057ms"} {"level":"info","ts":"2026-05-11T07:26:20.116618Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-11T07:26:20.116747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T07:26:20.116788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-11T07:26:20.116806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T07:26:20.116825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-11T07:26:20.116874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-11T07:26:20.123886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T07:26:20.125673Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T07:26:20.126905Z","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-05-11T07:26:20.129236Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T07:26:20.129394Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T07:26:20.13012Z","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-05-11T07:26:20.130943Z","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-05-11T07:26:20.130981Z","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-05-11T07:26:20.131132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-11T07:26:20.131406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-11T07:26:20.135034Z","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-05-11T07:26:20.135137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-11T07:26:20.135199Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-11T07:26:20.13565Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T07:26:20.135703Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T07:26:21.118105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T07:26:21.118204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T07:26:21.118247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-11T07:26:21.11827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-11T07:26:21.118293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-11T07:26:21.118311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-11T07:26:21.118327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-11T07:26:21.119551Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T07:26:21.120503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T07:26:21.120495Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T07:26:21.120624Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T07:26:21.121022Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T07:26:21.121113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T07:26:21.121163Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T07:26:21.121237Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T07:26:21.121287Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T07:26:21.124224Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T07:26:21.124237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-11T07:26:41.477999Z","caller":"traceutil/trace.go:171","msg":"trace[1102983144] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"178.797097ms","start":"2026-05-11T07:26:41.299177Z","end":"2026-05-11T07:26:41.477974Z","steps":["trace[1102983144] 'process raft request' (duration: 178.66391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:26:41.688921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.691277ms","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-05-11T07:26:41.689104Z","caller":"traceutil/trace.go:171","msg":"trace[304476708] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:455; }","duration":"155.896021ms","start":"2026-05-11T07:26:41.533189Z","end":"2026-05-11T07:26:41.689085Z","steps":["trace[304476708] 'range keys from in-memory index tree' (duration: 155.672477ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:26:53.442543Z","caller":"traceutil/trace.go:171","msg":"trace[1918970084] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"123.474877ms","start":"2026-05-11T07:26:53.319038Z","end":"2026-05-11T07:26:53.442513Z","steps":["trace[1918970084] 'process raft request' (duration: 123.309758ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:26:53.53505Z","caller":"traceutil/trace.go:171","msg":"trace[1758802665] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"158.912767ms","start":"2026-05-11T07:26:53.376093Z","end":"2026-05-11T07:26:53.535006Z","steps":["trace[1758802665] 'process raft request' (duration: 130.55244ms)","trace[1758802665] 'compare' (duration: 28.224238ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:26:53.549166Z","caller":"traceutil/trace.go:171","msg":"trace[1975311219] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"104.705251ms","start":"2026-05-11T07:26:53.444446Z","end":"2026-05-11T07:26:53.549152Z","steps":["trace[1975311219] 'process raft request' (duration: 104.115256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:26:54.067516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.22908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:26:54.067604Z","caller":"traceutil/trace.go:171","msg":"trace[208656679] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"440.768182ms","start":"2026-05-11T07:26:53.626821Z","end":"2026-05-11T07:26:54.067589Z","steps":["trace[208656679] 'process raft request' (duration: 303.844847ms)","trace[208656679] 'compare' (duration: 135.985592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:26:54.067646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:26:53.626792Z","time spent":"440.835252ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T07:27:09.728199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.278325ms","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-05-11T07:27:09.728332Z","caller":"traceutil/trace.go:171","msg":"trace[1324304989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"123.513417ms","start":"2026-05-11T07:27:09.604791Z","end":"2026-05-11T07:27:09.728304Z","steps":["trace[1324304989] 'range keys from in-memory index tree' (duration: 122.993621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:24.087801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.5997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:27:24.087896Z","caller":"traceutil/trace.go:171","msg":"trace[593082577] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:0; response_revision:934; }","duration":"131.749054ms","start":"2026-05-11T07:27:23.956126Z","end":"2026-05-11T07:27:24.087875Z","steps":["trace[593082577] 'agreement among raft nodes before linearized reading' (duration: 131.559098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:24.138193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.108375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:27:24.138286Z","caller":"traceutil/trace.go:171","msg":"trace[690954721] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd; range_end:; response_count:0; response_revision:937; }","duration":"101.250308ms","start":"2026-05-11T07:27:24.037016Z","end":"2026-05-11T07:27:24.138266Z","steps":["trace[690954721] 'agreement among raft nodes before linearized reading' (duration: 101.071364ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:24.743794Z","caller":"traceutil/trace.go:171","msg":"trace[551747346] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"144.16468ms","start":"2026-05-11T07:27:24.599603Z","end":"2026-05-11T07:27:24.743767Z","steps":["trace[551747346] 'process raft request' (duration: 143.931355ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:24.743896Z","caller":"traceutil/trace.go:171","msg":"trace[1203801368] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"141.379917ms","start":"2026-05-11T07:27:24.602483Z","end":"2026-05-11T07:27:24.743863Z","steps":["trace[1203801368] 'process raft request' (duration: 141.199723ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:24.743917Z","caller":"traceutil/trace.go:171","msg":"trace[137115253] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"128.192642ms","start":"2026-05-11T07:27:24.615695Z","end":"2026-05-11T07:27:24.743888Z","steps":["trace[137115253] 'process raft request' (duration: 128.072159ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:24.744186Z","caller":"traceutil/trace.go:171","msg":"trace[843267920] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"103.301954ms","start":"2026-05-11T07:27:24.640839Z","end":"2026-05-11T07:27:24.744141Z","steps":["trace[843267920] 'process raft request' (duration: 103.004818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:24.744253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.861571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:27:24.744382Z","caller":"traceutil/trace.go:171","msg":"trace[586339146] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:972; }","duration":"135.060235ms","start":"2026-05-11T07:27:24.60931Z","end":"2026-05-11T07:27:24.74437Z","steps":["trace[586339146] 'agreement among raft nodes before linearized reading' (duration: 134.847021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:24.744357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.52258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6249"} {"level":"info","ts":"2026-05-11T07:27:24.744462Z","caller":"traceutil/trace.go:171","msg":"trace[2039336581] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:972; }","duration":"141.653144ms","start":"2026-05-11T07:27:24.602785Z","end":"2026-05-11T07:27:24.744438Z","steps":["trace[2039336581] 'agreement among raft nodes before linearized reading' (duration: 141.375968ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:34.109356Z","caller":"traceutil/trace.go:171","msg":"trace[1968942471] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"112.739218ms","start":"2026-05-11T07:27:33.996569Z","end":"2026-05-11T07:27:34.109308Z","steps":["trace[1968942471] 'process raft request' (duration: 112.355797ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:35.01292Z","caller":"traceutil/trace.go:171","msg":"trace[57814256] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"212.51445ms","start":"2026-05-11T07:27:34.800354Z","end":"2026-05-11T07:27:35.012868Z","steps":["trace[57814256] 'process raft request' (duration: 212.317455ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:35.031446Z","caller":"traceutil/trace.go:171","msg":"trace[2007791945] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1510; }","duration":"120.191181ms","start":"2026-05-11T07:27:34.911222Z","end":"2026-05-11T07:27:35.031413Z","steps":["trace[2007791945] 'read index received' (duration: 101.450556ms)","trace[2007791945] 'applied index is now lower than readState.Index' (duration: 18.739495ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:27:35.031606Z","caller":"traceutil/trace.go:171","msg":"trace[838966368] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"126.034624ms","start":"2026-05-11T07:27:34.905541Z","end":"2026-05-11T07:27:35.031576Z","steps":["trace[838966368] 'process raft request' (duration: 125.703524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:35.031784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.538672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:27:35.03183Z","caller":"traceutil/trace.go:171","msg":"trace[779216714] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:1473; }","duration":"120.647825ms","start":"2026-05-11T07:27:34.91117Z","end":"2026-05-11T07:27:35.031818Z","steps":["trace[779216714] 'agreement among raft nodes before linearized reading' (duration: 120.397627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:35.102983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.958199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T07:27:35.103065Z","caller":"traceutil/trace.go:171","msg":"trace[2028548046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1474; }","duration":"105.065202ms","start":"2026-05-11T07:27:34.99798Z","end":"2026-05-11T07:27:35.103045Z","steps":["trace[2028548046] 'agreement among raft nodes before linearized reading' (duration: 104.910447ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:39.651163Z","caller":"traceutil/trace.go:171","msg":"trace[450722191] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"114.814637ms","start":"2026-05-11T07:27:39.536321Z","end":"2026-05-11T07:27:39.651136Z","steps":["trace[450722191] 'process raft request' (duration: 114.566659ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:39.82226Z","caller":"traceutil/trace.go:171","msg":"trace[1286175322] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"163.411592ms","start":"2026-05-11T07:27:39.658822Z","end":"2026-05-11T07:27:39.822234Z","steps":["trace[1286175322] 'process raft request' (duration: 163.222466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:40.154453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.024396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T07:27:40.269696Z","caller":"traceutil/trace.go:171","msg":"trace[1946739193] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"110.944937ms","start":"2026-05-11T07:27:40.158716Z","end":"2026-05-11T07:27:40.269661Z","steps":["trace[1946739193] 'process raft request' (duration: 110.763871ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:42.538047Z","caller":"traceutil/trace.go:171","msg":"trace[879319023] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"257.50546ms","start":"2026-05-11T07:27:42.280506Z","end":"2026-05-11T07:27:42.538011Z","steps":["trace[879319023] 'process raft request' (duration: 254.575549ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:43.317433Z","caller":"traceutil/trace.go:171","msg":"trace[1402226246] linearizableReadLoop","detail":"{readStateIndex:1562; appliedIndex:1560; }","duration":"168.628177ms","start":"2026-05-11T07:27:43.148778Z","end":"2026-05-11T07:27:43.317406Z","steps":["trace[1402226246] 'read index received' (duration: 19.844234ms)","trace[1402226246] 'applied index is now lower than readState.Index' (duration: 148.782903ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:27:43.317547Z","caller":"traceutil/trace.go:171","msg":"trace[231108506] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"196.523683ms","start":"2026-05-11T07:27:43.120989Z","end":"2026-05-11T07:27:43.317512Z","steps":["trace[231108506] 'process raft request' (duration: 196.241323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:43.31781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.996141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:27:43.317932Z","caller":"traceutil/trace.go:171","msg":"trace[903299628] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:1522; }","duration":"169.173186ms","start":"2026-05-11T07:27:43.148733Z","end":"2026-05-11T07:27:43.317906Z","steps":["trace[903299628] 'agreement among raft nodes before linearized reading' (duration: 168.808414ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:43.325023Z","caller":"traceutil/trace.go:171","msg":"trace[1034366249] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1522; }","duration":"154.557547ms","start":"2026-05-11T07:27:43.170411Z","end":"2026-05-11T07:27:43.324968Z","steps":["trace[1034366249] 'process raft request' (duration: 154.418071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:43.325576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.359929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T07:27:43.325612Z","caller":"traceutil/trace.go:171","msg":"trace[564828319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1522; }","duration":"166.447102ms","start":"2026-05-11T07:27:43.159155Z","end":"2026-05-11T07:27:43.325602Z","steps":["trace[564828319] 'agreement among raft nodes before linearized reading' (duration: 166.355969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:46.908233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.991018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:27:46.908379Z","caller":"traceutil/trace.go:171","msg":"trace[145251579] linearizableReadLoop","detail":"{readStateIndex:1667; appliedIndex:1666; }","duration":"338.619027ms","start":"2026-05-11T07:27:46.569736Z","end":"2026-05-11T07:27:46.908355Z","steps":["trace[145251579] 'read index received' (duration: 39.921µs)","trace[145251579] 'applied index is now lower than readState.Index' (duration: 338.577435ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:27:46.908483Z","caller":"traceutil/trace.go:171","msg":"trace[579498542] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"444.162285ms","start":"2026-05-11T07:27:46.464306Z","end":"2026-05-11T07:27:46.908468Z","steps":["trace[579498542] 'process raft request' (duration: 103.830505ms)","trace[579498542] 'compare' (duration: 339.77964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:27:46.908539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:27:46.4643Z","time spent":"444.214476ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T07:27:46.908699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.324405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T07:27:46.908733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.767194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T07:27:46.908775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.027952ms","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-05-11T07:27:46.908805Z","caller":"traceutil/trace.go:171","msg":"trace[110838637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1624; }","duration":"282.484711ms","start":"2026-05-11T07:27:46.626282Z","end":"2026-05-11T07:27:46.908767Z","steps":["trace[110838637] 'agreement among raft nodes before linearized reading' (duration: 282.281043ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:46.908815Z","caller":"traceutil/trace.go:171","msg":"trace[688656889] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1624; }","duration":"275.880169ms","start":"2026-05-11T07:27:46.632915Z","end":"2026-05-11T07:27:46.908795Z","steps":["trace[688656889] 'agreement among raft nodes before linearized reading' (duration: 275.679001ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:46.908849Z","caller":"traceutil/trace.go:171","msg":"trace[379362736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1624; }","duration":"339.138316ms","start":"2026-05-11T07:27:46.569695Z","end":"2026-05-11T07:27:46.908833Z","steps":["trace[379362736] 'agreement among raft nodes before linearized reading' (duration: 338.886987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:46.908875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:27:46.569675Z","time spent":"339.195237ms","remote":"127.0.0.1:49432","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-05-11T07:27:48.898951Z","caller":"traceutil/trace.go:171","msg":"trace[919533618] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"195.896156ms","start":"2026-05-11T07:27:48.703029Z","end":"2026-05-11T07:27:48.898925Z","steps":["trace[919533618] 'process raft request' (duration: 195.721879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:49.276032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.181033ms","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-05-11T07:27:49.27611Z","caller":"traceutil/trace.go:171","msg":"trace[1854457304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1644; }","duration":"282.299958ms","start":"2026-05-11T07:27:48.993797Z","end":"2026-05-11T07:27:49.276097Z","steps":["trace[1854457304] 'range keys from in-memory index tree' (duration: 282.049388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:49.501186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.389884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:27:49.50129Z","caller":"traceutil/trace.go:171","msg":"trace[1405469625] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"126.08596ms","start":"2026-05-11T07:27:49.375191Z","end":"2026-05-11T07:27:49.501277Z","steps":["trace[1405469625] 'process raft request' (duration: 12.426306ms)","trace[1405469625] 'compare' (duration: 113.274799ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:27:49.510651Z","caller":"traceutil/trace.go:171","msg":"trace[246309634] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"135.370915ms","start":"2026-05-11T07:27:49.375259Z","end":"2026-05-11T07:27:49.51063Z","steps":["trace[246309634] 'process raft request' (duration: 135.24456ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:27:53.575273Z","caller":"traceutil/trace.go:171","msg":"trace[1738462805] linearizableReadLoop","detail":"{readStateIndex:1705; appliedIndex:1704; }","duration":"151.985694ms","start":"2026-05-11T07:27:53.423263Z","end":"2026-05-11T07:27:53.575249Z","steps":["trace[1738462805] 'read index received' (duration: 151.822277ms)","trace[1738462805] 'applied index is now lower than readState.Index' (duration: 162.627µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:27:53.575374Z","caller":"traceutil/trace.go:171","msg":"trace[689563446] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"154.655553ms","start":"2026-05-11T07:27:53.420689Z","end":"2026-05-11T07:27:53.575345Z","steps":["trace[689563446] 'process raft request' (duration: 154.314998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:53.575463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.198744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:27:53.575493Z","caller":"traceutil/trace.go:171","msg":"trace[917701814] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:1661; }","duration":"152.274656ms","start":"2026-05-11T07:27:53.423211Z","end":"2026-05-11T07:27:53.575485Z","steps":["trace[917701814] 'agreement among raft nodes before linearized reading' (duration: 152.144061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:27:53.575535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.548635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-f6rjz\" ","response":"range_response_count:1 size:12779"} {"level":"info","ts":"2026-05-11T07:27:53.575557Z","caller":"traceutil/trace.go:171","msg":"trace[1037620935] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-f6rjz; range_end:; response_count:1; response_revision:1661; }","duration":"100.685441ms","start":"2026-05-11T07:27:53.474866Z","end":"2026-05-11T07:27:53.575551Z","steps":["trace[1037620935] 'agreement among raft nodes before linearized reading' (duration: 100.505574ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:07.936595Z","caller":"traceutil/trace.go:171","msg":"trace[912214059] linearizableReadLoop","detail":"{readStateIndex:1833; appliedIndex:1832; }","duration":"122.932343ms","start":"2026-05-11T07:28:07.813631Z","end":"2026-05-11T07:28:07.936564Z","steps":["trace[912214059] 'read index received' (duration: 122.734323ms)","trace[912214059] 'applied index is now lower than readState.Index' (duration: 196.88µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:28:07.936681Z","caller":"traceutil/trace.go:171","msg":"trace[1209142042] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"130.039841ms","start":"2026-05-11T07:28:07.806572Z","end":"2026-05-11T07:28:07.936612Z","steps":["trace[1209142042] 'process raft request' (duration: 129.817738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:07.936913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.243748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-zq2gm\" ","response":"range_response_count:1 size:7093"} {"level":"info","ts":"2026-05-11T07:28:07.937028Z","caller":"traceutil/trace.go:171","msg":"trace[2127017933] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-zq2gm; range_end:; response_count:1; response_revision:1785; }","duration":"123.383359ms","start":"2026-05-11T07:28:07.813616Z","end":"2026-05-11T07:28:07.936999Z","steps":["trace[2127017933] 'agreement among raft nodes before linearized reading' (duration: 123.073543ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:08.149807Z","caller":"traceutil/trace.go:171","msg":"trace[1075997494] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"204.123084ms","start":"2026-05-11T07:28:07.945643Z","end":"2026-05-11T07:28:08.149766Z","steps":["trace[1075997494] 'process raft request' (duration: 203.446355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:14.482241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.184828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:28:14.482385Z","caller":"traceutil/trace.go:171","msg":"trace[239252988] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1838; }","duration":"288.436616ms","start":"2026-05-11T07:28:14.19392Z","end":"2026-05-11T07:28:14.482357Z","steps":["trace[239252988] 'count revisions from in-memory index tree' (duration: 287.977684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:14.482465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.658925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T07:28:14.482539Z","caller":"traceutil/trace.go:171","msg":"trace[1933076269] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1838; }","duration":"116.782975ms","start":"2026-05-11T07:28:14.365741Z","end":"2026-05-11T07:28:14.482524Z","steps":["trace[1933076269] 'range keys from in-memory index tree' (duration: 116.521606ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:14.70428Z","caller":"traceutil/trace.go:171","msg":"trace[2121326525] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"160.65007ms","start":"2026-05-11T07:28:14.543596Z","end":"2026-05-11T07:28:14.704246Z","steps":["trace[2121326525] 'process raft request' (duration: 111.113642ms)","trace[2121326525] 'compare' (duration: 49.383177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:28:14.989425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.447769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:28:14.989567Z","caller":"traceutil/trace.go:171","msg":"trace[968683378] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"281.289087ms","start":"2026-05-11T07:28:14.708256Z","end":"2026-05-11T07:28:14.989545Z","steps":["trace[968683378] 'read index received' (duration: 146.701759ms)","trace[968683378] 'applied index is now lower than readState.Index' (duration: 134.585808ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:28:14.989669Z","caller":"traceutil/trace.go:171","msg":"trace[1692545944] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"281.639193ms","start":"2026-05-11T07:28:14.707952Z","end":"2026-05-11T07:28:14.989591Z","steps":["trace[1692545944] 'process raft request' (duration: 146.893093ms)","trace[1692545944] 'compare' (duration: 134.244464ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:28:14.989787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.519254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T07:28:14.989847Z","caller":"traceutil/trace.go:171","msg":"trace[383107468] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1840; }","duration":"281.589379ms","start":"2026-05-11T07:28:14.708239Z","end":"2026-05-11T07:28:14.989828Z","steps":["trace[383107468] 'agreement among raft nodes before linearized reading' (duration: 281.364413ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:16.659644Z","caller":"traceutil/trace.go:171","msg":"trace[172758249] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"106.796005ms","start":"2026-05-11T07:28:16.552819Z","end":"2026-05-11T07:28:16.659615Z","steps":["trace[172758249] 'read index received' (duration: 106.629203ms)","trace[172758249] 'applied index is now lower than readState.Index' (duration: 165.702µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:28:16.659728Z","caller":"traceutil/trace.go:171","msg":"trace[305027171] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"165.59347ms","start":"2026-05-11T07:28:16.494086Z","end":"2026-05-11T07:28:16.65968Z","steps":["trace[305027171] 'process raft request' (duration: 165.373575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:16.659831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.998059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T07:28:16.659868Z","caller":"traceutil/trace.go:171","msg":"trace[591799071] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1847; }","duration":"107.112147ms","start":"2026-05-11T07:28:16.552746Z","end":"2026-05-11T07:28:16.659859Z","steps":["trace[591799071] 'agreement among raft nodes before linearized reading' (duration: 107.021581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:17.249684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.934263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:28:17.249775Z","caller":"traceutil/trace.go:171","msg":"trace[1668024785] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"251.224873ms","start":"2026-05-11T07:28:16.998536Z","end":"2026-05-11T07:28:17.249761Z","steps":["trace[1668024785] 'process raft request' (duration: 97.14049ms)","trace[1668024785] 'compare' (duration: 153.840976ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:28:18.707369Z","caller":"traceutil/trace.go:171","msg":"trace[1735869464] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"217.231238ms","start":"2026-05-11T07:28:18.490111Z","end":"2026-05-11T07:28:18.707343Z","steps":["trace[1735869464] 'process raft request' (duration: 217.080477ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:35.974508Z","caller":"traceutil/trace.go:171","msg":"trace[1880004772] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"414.255282ms","start":"2026-05-11T07:28:35.560236Z","end":"2026-05-11T07:28:35.974492Z","steps":["trace[1880004772] 'process raft request' (duration: 413.985053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:35.974668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:35.560206Z","time spent":"414.362039ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":141069,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T07:28:35.974429Z","caller":"traceutil/trace.go:171","msg":"trace[471230271] linearizableReadLoop","detail":"{readStateIndex:2126; appliedIndex:2125; }","duration":"348.398274ms","start":"2026-05-11T07:28:35.626003Z","end":"2026-05-11T07:28:35.974401Z","steps":["trace[471230271] 'read index received' (duration: 348.107853ms)","trace[471230271] 'applied index is now lower than readState.Index' (duration: 289.651µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:28:35.974774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.75101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:28:35.97489Z","caller":"traceutil/trace.go:171","msg":"trace[1018320863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2048; }","duration":"348.882819ms","start":"2026-05-11T07:28:35.625974Z","end":"2026-05-11T07:28:35.974856Z","steps":["trace[1018320863] 'agreement among raft nodes before linearized reading' (duration: 348.629591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:35.974939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:35.625965Z","time spent":"348.965235ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T07:28:35.993274Z","caller":"traceutil/trace.go:171","msg":"trace[2015908412] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"364.226458ms","start":"2026-05-11T07:28:35.62903Z","end":"2026-05-11T07:28:35.993257Z","steps":["trace[2015908412] 'process raft request' (duration: 363.996072ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:35.993308Z","caller":"traceutil/trace.go:171","msg":"trace[807512341] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"358.91089ms","start":"2026-05-11T07:28:35.634377Z","end":"2026-05-11T07:28:35.993288Z","steps":["trace[807512341] 'process raft request' (duration: 358.758209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:35.993342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:35.629007Z","time spent":"364.296103ms","remote":"127.0.0.1:49228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T07:28:35.993448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:35.634368Z","time spent":"359.003337ms","remote":"127.0.0.1:37156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1093,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:28:35.993657Z","caller":"traceutil/trace.go:171","msg":"trace[1835491602] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"191.183978ms","start":"2026-05-11T07:28:35.802429Z","end":"2026-05-11T07:28:35.993613Z","steps":["trace[1835491602] 'process raft request' (duration: 190.756098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:35.993774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.2383ms","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-05-11T07:28:35.993815Z","caller":"traceutil/trace.go:171","msg":"trace[1143595585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2052; }","duration":"209.307295ms","start":"2026-05-11T07:28:35.784497Z","end":"2026-05-11T07:28:35.993804Z","steps":["trace[1143595585] 'agreement among raft nodes before linearized reading' (duration: 209.175295ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:35.993805Z","caller":"traceutil/trace.go:171","msg":"trace[1882830261] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"141.63122ms","start":"2026-05-11T07:28:35.85215Z","end":"2026-05-11T07:28:35.993781Z","steps":["trace[1882830261] 'process raft request' (duration: 141.069259ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:44.290466Z","caller":"traceutil/trace.go:171","msg":"trace[1157039486] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"205.56691ms","start":"2026-05-11T07:28:44.084864Z","end":"2026-05-11T07:28:44.290431Z","steps":["trace[1157039486] 'process raft request' (duration: 205.352895ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:44.360386Z","caller":"traceutil/trace.go:171","msg":"trace[1104818165] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"273.582522ms","start":"2026-05-11T07:28:44.086763Z","end":"2026-05-11T07:28:44.360346Z","steps":["trace[1104818165] 'process raft request' (duration: 273.311482ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:44.360447Z","caller":"traceutil/trace.go:171","msg":"trace[550860084] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"274.242088ms","start":"2026-05-11T07:28:44.086098Z","end":"2026-05-11T07:28:44.36034Z","steps":["trace[550860084] 'process raft request' (duration: 266.016014ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:46.019848Z","caller":"traceutil/trace.go:171","msg":"trace[1609891907] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2275; }","duration":"260.413875ms","start":"2026-05-11T07:28:45.759407Z","end":"2026-05-11T07:28:46.019821Z","steps":["trace[1609891907] 'process raft request' (duration: 260.298137ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:46.491608Z","caller":"traceutil/trace.go:171","msg":"trace[64690556] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"102.515491ms","start":"2026-05-11T07:28:46.389066Z","end":"2026-05-11T07:28:46.491582Z","steps":["trace[64690556] 'process raft request' (duration: 102.361201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:47.635444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.305625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:28:47.63579Z","caller":"traceutil/trace.go:171","msg":"trace[1118890031] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"485.413085ms","start":"2026-05-11T07:28:47.150354Z","end":"2026-05-11T07:28:47.635767Z","steps":["trace[1118890031] 'process raft request' (duration: 227.645218ms)","trace[1118890031] 'compare' (duration: 257.10303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:28:47.63588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:47.150336Z","time spent":"485.504191ms","remote":"127.0.0.1:49228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:28:47.636023Z","caller":"traceutil/trace.go:171","msg":"trace[211785454] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"258.07801ms","start":"2026-05-11T07:28:47.377894Z","end":"2026-05-11T07:28:47.635972Z","steps":["trace[211785454] 'process raft request' (duration: 257.672551ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:48.457768Z","caller":"traceutil/trace.go:171","msg":"trace[1866110489] linearizableReadLoop","detail":"{readStateIndex:2400; appliedIndex:2399; }","duration":"324.808399ms","start":"2026-05-11T07:28:48.132927Z","end":"2026-05-11T07:28:48.457735Z","steps":["trace[1866110489] 'read index received' (duration: 324.572981ms)","trace[1866110489] 'applied index is now lower than readState.Index' (duration: 233.788µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:28:48.45792Z","caller":"traceutil/trace.go:171","msg":"trace[1071721286] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"355.94586ms","start":"2026-05-11T07:28:48.101923Z","end":"2026-05-11T07:28:48.457869Z","steps":["trace[1071721286] 'process raft request' (duration: 355.588604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.458124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.169344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-05-11T07:28:48.458141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.101885Z","time spent":"356.129453ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:28:48.4582Z","caller":"traceutil/trace.go:171","msg":"trace[939732654] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:2312; }","duration":"325.303914ms","start":"2026-05-11T07:28:48.132869Z","end":"2026-05-11T07:28:48.458173Z","steps":["trace[939732654] 'agreement among raft nodes before linearized reading' (duration: 325.066317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.458227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.28714ms","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-05-11T07:28:48.458228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.361421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/libvirt-libvirt-default-fs7c5.18ae724110ee74e3\" ","response":"range_response_count:1 size:794"} {"level":"info","ts":"2026-05-11T07:28:48.458305Z","caller":"traceutil/trace.go:171","msg":"trace[1223867668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2312; }","duration":"127.41862ms","start":"2026-05-11T07:28:48.330867Z","end":"2026-05-11T07:28:48.458286Z","steps":["trace[1223867668] 'agreement among raft nodes before linearized reading' (duration: 127.184733ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:48.458356Z","caller":"traceutil/trace.go:171","msg":"trace[1344342928] range","detail":"{range_begin:/registry/events/openstack/libvirt-libvirt-default-fs7c5.18ae724110ee74e3; range_end:; response_count:1; response_revision:2312; }","duration":"298.544443ms","start":"2026-05-11T07:28:48.159779Z","end":"2026-05-11T07:28:48.458324Z","steps":["trace[1344342928] 'agreement among raft nodes before linearized reading' (duration: 298.190048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.458247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.132855Z","time spent":"325.377909ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-wcdh2\" "} {"level":"info","ts":"2026-05-11T07:28:48.841503Z","caller":"traceutil/trace.go:171","msg":"trace[1355205161] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"377.50235ms","start":"2026-05-11T07:28:48.463974Z","end":"2026-05-11T07:28:48.841476Z","steps":["trace[1355205161] 'process raft request' (duration: 377.421535ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:48.841541Z","caller":"traceutil/trace.go:171","msg":"trace[1038814021] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"377.550565ms","start":"2026-05-11T07:28:48.463945Z","end":"2026-05-11T07:28:48.841496Z","steps":["trace[1038814021] 'process raft request' (duration: 377.274374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.841671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.463951Z","time spent":"377.631451ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:28:48.841774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.463916Z","time spent":"377.726357ms","remote":"127.0.0.1:49228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:28:48.866252Z","caller":"traceutil/trace.go:171","msg":"trace[2052357909] linearizableReadLoop","detail":"{readStateIndex:2404; appliedIndex:2400; }","duration":"355.537561ms","start":"2026-05-11T07:28:48.510672Z","end":"2026-05-11T07:28:48.86621Z","steps":["trace[2052357909] 'read index received' (duration: 330.646073ms)","trace[2052357909] 'applied index is now lower than readState.Index' (duration: 24.890258ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:28:48.866314Z","caller":"traceutil/trace.go:171","msg":"trace[1680768124] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"402.231107ms","start":"2026-05-11T07:28:48.464032Z","end":"2026-05-11T07:28:48.866263Z","steps":["trace[1680768124] 'process raft request' (duration: 401.949407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.86651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.818382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-11T07:28:48.866521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.704372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T07:28:48.866537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.405533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T07:28:48.86656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.464026Z","time spent":"402.394939ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:28:48.866568Z","caller":"traceutil/trace.go:171","msg":"trace[51256445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2316; }","duration":"355.906137ms","start":"2026-05-11T07:28:48.510647Z","end":"2026-05-11T07:28:48.866553Z","steps":["trace[51256445] 'agreement among raft nodes before linearized reading' (duration: 355.687342ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:48.86658Z","caller":"traceutil/trace.go:171","msg":"trace[2042066077] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:2316; }","duration":"345.847593ms","start":"2026-05-11T07:28:48.520713Z","end":"2026-05-11T07:28:48.86656Z","steps":["trace[2042066077] 'agreement among raft nodes before linearized reading' (duration: 345.702223ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:28:48.866598Z","caller":"traceutil/trace.go:171","msg":"trace[1361091964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2316; }","duration":"239.50307ms","start":"2026-05-11T07:28:48.627077Z","end":"2026-05-11T07:28:48.86658Z","steps":["trace[1361091964] 'agreement among raft nodes before linearized reading' (duration: 239.396133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.866612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.510633Z","time spent":"355.971002ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T07:28:48.866629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.520698Z","time spent":"345.920408ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-05-11T07:28:48.867129Z","caller":"traceutil/trace.go:171","msg":"trace[134640623] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"401.966819ms","start":"2026-05-11T07:28:48.465146Z","end":"2026-05-11T07:28:48.867113Z","steps":["trace[134640623] 'process raft request' (duration: 401.013501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:28:48.867218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:28:48.465137Z","time spent":"402.025813ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:29:19.315455Z","caller":"traceutil/trace.go:171","msg":"trace[1260328572] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"107.526401ms","start":"2026-05-11T07:29:19.20789Z","end":"2026-05-11T07:29:19.315417Z","steps":["trace[1260328572] 'process raft request' (duration: 62.461388ms)","trace[1260328572] 'compare' (duration: 44.620644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:29:35.125316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.701493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:29:35.125439Z","caller":"traceutil/trace.go:171","msg":"trace[626345062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2567; }","duration":"496.847876ms","start":"2026-05-11T07:29:34.628568Z","end":"2026-05-11T07:29:35.125416Z","steps":["trace[626345062] 'range keys from in-memory index tree' (duration: 496.587301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:29:35.125484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:29:34.628523Z","time spent":"496.949978ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T07:29:35.126379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.897238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:29:35.126553Z","caller":"traceutil/trace.go:171","msg":"trace[659019092] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"447.154278ms","start":"2026-05-11T07:29:34.679387Z","end":"2026-05-11T07:29:35.126541Z","steps":["trace[659019092] 'process raft request' (duration: 313.730379ms)","trace[659019092] 'compare' (duration: 132.777294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:29:35.126628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:29:34.679343Z","time spent":"447.24827ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:29:35.126804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.314426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:29:35.126488Z","caller":"traceutil/trace.go:171","msg":"trace[1728076322] linearizableReadLoop","detail":"{readStateIndex:2666; appliedIndex:2665; }","duration":"425.000859ms","start":"2026-05-11T07:29:34.701474Z","end":"2026-05-11T07:29:35.126475Z","steps":["trace[1728076322] 'read index received' (duration: 291.657332ms)","trace[1728076322] 'applied index is now lower than readState.Index' (duration: 133.342207ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:29:35.126918Z","caller":"traceutil/trace.go:171","msg":"trace[1395155787] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:2568; }","duration":"425.462019ms","start":"2026-05-11T07:29:34.701428Z","end":"2026-05-11T07:29:35.12689Z","steps":["trace[1395155787] 'agreement among raft nodes before linearized reading' (duration: 425.199233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:29:35.126965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:29:34.701409Z","time spent":"425.546571ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-wcdh2\" "} {"level":"warn","ts":"2026-05-11T07:29:35.127101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.615214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T07:29:35.127204Z","caller":"traceutil/trace.go:171","msg":"trace[268761858] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2568; }","duration":"284.750307ms","start":"2026-05-11T07:29:34.842423Z","end":"2026-05-11T07:29:35.127174Z","steps":["trace[268761858] 'agreement among raft nodes before linearized reading' (duration: 284.568873ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:29:47.361367Z","caller":"traceutil/trace.go:171","msg":"trace[1165353024] linearizableReadLoop","detail":"{readStateIndex:2727; appliedIndex:2726; }","duration":"163.801211ms","start":"2026-05-11T07:29:47.197545Z","end":"2026-05-11T07:29:47.361346Z","steps":["trace[1165353024] 'read index received' (duration: 163.76673ms)","trace[1165353024] 'applied index is now lower than readState.Index' (duration: 33.021µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:29:47.361457Z","caller":"traceutil/trace.go:171","msg":"trace[1814173682] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"172.870443ms","start":"2026-05-11T07:29:47.188548Z","end":"2026-05-11T07:29:47.361418Z","steps":["trace[1814173682] 'process raft request' (duration: 172.668309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:29:47.361657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.093776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:29:47.361753Z","caller":"traceutil/trace.go:171","msg":"trace[1799478357] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:2627; }","duration":"164.206459ms","start":"2026-05-11T07:29:47.197529Z","end":"2026-05-11T07:29:47.361735Z","steps":["trace[1799478357] 'agreement among raft nodes before linearized reading' (duration: 163.932903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:29:47.436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.556512ms","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-05-11T07:29:47.435986Z","caller":"traceutil/trace.go:171","msg":"trace[475973852] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"128.71265ms","start":"2026-05-11T07:29:47.307234Z","end":"2026-05-11T07:29:47.435947Z","steps":["trace[475973852] 'process raft request' (duration: 128.22707ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:29:47.436003Z","caller":"traceutil/trace.go:171","msg":"trace[95737288] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"230.111661ms","start":"2026-05-11T07:29:47.205855Z","end":"2026-05-11T07:29:47.435966Z","steps":["trace[95737288] 'process raft request' (duration: 229.402236ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:29:47.436077Z","caller":"traceutil/trace.go:171","msg":"trace[1089117260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2629; }","duration":"124.677534ms","start":"2026-05-11T07:29:47.311382Z","end":"2026-05-11T07:29:47.43606Z","steps":["trace[1089117260] 'agreement among raft nodes before linearized reading' (duration: 124.446839ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:29:47.83876Z","caller":"traceutil/trace.go:171","msg":"trace[215879670] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"122.10606ms","start":"2026-05-11T07:29:47.716624Z","end":"2026-05-11T07:29:47.83873Z","steps":["trace[215879670] 'process raft request' (duration: 121.926335ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:30:31.700529Z","caller":"traceutil/trace.go:171","msg":"trace[550663935] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"122.403484ms","start":"2026-05-11T07:30:31.578094Z","end":"2026-05-11T07:30:31.700498Z","steps":["trace[550663935] 'process raft request' (duration: 122.114278ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:30:49.872193Z","caller":"traceutil/trace.go:171","msg":"trace[2004282782] transaction","detail":"{read_only:false; response_revision:2947; number_of_response:1; }","duration":"102.169044ms","start":"2026-05-11T07:30:49.77Z","end":"2026-05-11T07:30:49.87217Z","steps":["trace[2004282782] 'process raft request' (duration: 102.016101ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:30:49.882128Z","caller":"traceutil/trace.go:171","msg":"trace[1292670867] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"107.701604ms","start":"2026-05-11T07:30:49.774394Z","end":"2026-05-11T07:30:49.882096Z","steps":["trace[1292670867] 'process raft request' (duration: 107.275045ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:30:56.152056Z","caller":"traceutil/trace.go:171","msg":"trace[2093313999] transaction","detail":"{read_only:false; response_revision:2980; number_of_response:1; }","duration":"166.664642ms","start":"2026-05-11T07:30:55.985363Z","end":"2026-05-11T07:30:56.152027Z","steps":["trace[2093313999] 'process raft request' (duration: 166.356126ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:30:56.152073Z","caller":"traceutil/trace.go:171","msg":"trace[612408052] linearizableReadLoop","detail":"{readStateIndex:3096; appliedIndex:3096; }","duration":"166.51862ms","start":"2026-05-11T07:30:55.985521Z","end":"2026-05-11T07:30:56.15204Z","steps":["trace[612408052] 'read index received' (duration: 166.507269ms)","trace[612408052] 'applied index is now lower than readState.Index' (duration: 8.74µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:30:56.152562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.931708ms","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-05-11T07:30:56.152751Z","caller":"traceutil/trace.go:171","msg":"trace[150339253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2980; }","duration":"167.276075ms","start":"2026-05-11T07:30:55.985445Z","end":"2026-05-11T07:30:56.152721Z","steps":["trace[150339253] 'agreement among raft nodes before linearized reading' (duration: 166.714973ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:30:56.157574Z","caller":"traceutil/trace.go:171","msg":"trace[1535090620] transaction","detail":"{read_only:false; response_revision:2981; number_of_response:1; }","duration":"167.727596ms","start":"2026-05-11T07:30:55.989828Z","end":"2026-05-11T07:30:56.157555Z","steps":["trace[1535090620] 'process raft request' (duration: 167.637434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:30:56.158362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.062935ms","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-05-11T07:30:56.158476Z","caller":"traceutil/trace.go:171","msg":"trace[1825792487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2981; }","duration":"169.193357ms","start":"2026-05-11T07:30:55.989239Z","end":"2026-05-11T07:30:56.158429Z","steps":["trace[1825792487] 'agreement among raft nodes before linearized reading' (duration: 168.935761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:30:56.158387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.568405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:30:56.158622Z","caller":"traceutil/trace.go:171","msg":"trace[1567875124] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:2981; }","duration":"169.845423ms","start":"2026-05-11T07:30:55.988758Z","end":"2026-05-11T07:30:56.158604Z","steps":["trace[1567875124] 'agreement among raft nodes before linearized reading' (duration: 169.440973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:30:56.158363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.623798ms","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-05-11T07:30:56.158909Z","caller":"traceutil/trace.go:171","msg":"trace[469270445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2981; }","duration":"171.16947ms","start":"2026-05-11T07:30:55.987689Z","end":"2026-05-11T07:30:56.158859Z","steps":["trace[469270445] 'agreement among raft nodes before linearized reading' (duration: 170.487644ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:02.529369Z","caller":"traceutil/trace.go:171","msg":"trace[1237368909] transaction","detail":"{read_only:false; response_revision:3016; number_of_response:1; }","duration":"103.693698ms","start":"2026-05-11T07:31:02.425646Z","end":"2026-05-11T07:31:02.52934Z","steps":["trace[1237368909] 'process raft request' (duration: 103.471033ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:29.137645Z","caller":"traceutil/trace.go:171","msg":"trace[1714222939] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"140.227811ms","start":"2026-05-11T07:31:28.997371Z","end":"2026-05-11T07:31:29.137599Z","steps":["trace[1714222939] 'process raft request' (duration: 139.994366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:29.703905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.898911ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T07:31:29.998606Z","caller":"traceutil/trace.go:171","msg":"trace[1643892151] transaction","detail":"{read_only:false; response_revision:3151; number_of_response:1; }","duration":"197.640395ms","start":"2026-05-11T07:31:29.800934Z","end":"2026-05-11T07:31:29.998575Z","steps":["trace[1643892151] 'process raft request' (duration: 197.426221ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:30.206988Z","caller":"traceutil/trace.go:171","msg":"trace[534639797] transaction","detail":"{read_only:false; response_revision:3152; number_of_response:1; }","duration":"163.272322ms","start":"2026-05-11T07:31:30.04367Z","end":"2026-05-11T07:31:30.206943Z","steps":["trace[534639797] 'process raft request' (duration: 118.588683ms)","trace[534639797] 'compare' (duration: 44.509515ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:30.453625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.585335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:30.453797Z","caller":"traceutil/trace.go:171","msg":"trace[1379084448] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"151.092627ms","start":"2026-05-11T07:31:30.302663Z","end":"2026-05-11T07:31:30.453755Z","steps":["trace[1379084448] 'process raft request' (duration: 24.256037ms)","trace[1379084448] 'compare' (duration: 126.324259ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:30.783791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.736114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:30.784066Z","caller":"traceutil/trace.go:171","msg":"trace[418044547] linearizableReadLoop","detail":"{readStateIndex:3278; appliedIndex:3276; }","duration":"323.866284ms","start":"2026-05-11T07:31:30.460178Z","end":"2026-05-11T07:31:30.784045Z","steps":["trace[418044547] 'read index received' (duration: 140.764543ms)","trace[418044547] 'applied index is now lower than readState.Index' (duration: 183.100351ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:30.784144Z","caller":"traceutil/trace.go:171","msg":"trace[1120983925] transaction","detail":"{read_only:false; response_revision:3154; number_of_response:1; }","duration":"325.440087ms","start":"2026-05-11T07:31:30.45869Z","end":"2026-05-11T07:31:30.78413Z","steps":["trace[1120983925] 'process raft request' (duration: 142.232513ms)","trace[1120983925] 'compare' (duration: 182.288383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:30.784207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.45867Z","time spent":"325.500919ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:30.784314Z","caller":"traceutil/trace.go:171","msg":"trace[2136697036] transaction","detail":"{read_only:false; response_revision:3155; number_of_response:1; }","duration":"324.963057ms","start":"2026-05-11T07:31:30.459329Z","end":"2026-05-11T07:31:30.784292Z","steps":["trace[2136697036] 'process raft request' (duration: 324.59906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:30.784411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.459303Z","time spent":"325.058939ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:30.784556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.348434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:68642"} {"level":"info","ts":"2026-05-11T07:31:30.784706Z","caller":"traceutil/trace.go:171","msg":"trace[1581631659] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:3155; }","duration":"324.536368ms","start":"2026-05-11T07:31:30.460144Z","end":"2026-05-11T07:31:30.78468Z","steps":["trace[1581631659] 'agreement among raft nodes before linearized reading' (duration: 324.129109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:30.784771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.460136Z","time spent":"324.61791ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":68664,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-11T07:31:31.142976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.224174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:31.143166Z","caller":"traceutil/trace.go:171","msg":"trace[119796535] linearizableReadLoop","detail":"{readStateIndex:3280; appliedIndex:3278; }","duration":"359.050557ms","start":"2026-05-11T07:31:30.784095Z","end":"2026-05-11T07:31:31.143146Z","steps":["trace[119796535] 'read index received' (duration: 116.410884ms)","trace[119796535] 'applied index is now lower than readState.Index' (duration: 242.637973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:31.143299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.847919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:31.143321Z","caller":"traceutil/trace.go:171","msg":"trace[166496685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3157; }","duration":"516.90475ms","start":"2026-05-11T07:31:30.626408Z","end":"2026-05-11T07:31:31.143312Z","steps":["trace[166496685] 'agreement among raft nodes before linearized reading' (duration: 516.824679ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:31.143274Z","caller":"traceutil/trace.go:171","msg":"trace[59737162] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"493.068243ms","start":"2026-05-11T07:31:30.650133Z","end":"2026-05-11T07:31:31.143202Z","steps":["trace[59737162] 'process raft request' (duration: 492.95169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:31.143347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.626386Z","time spent":"516.955782ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T07:31:31.143398Z","caller":"traceutil/trace.go:171","msg":"trace[701141503] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"496.933477ms","start":"2026-05-11T07:31:30.646443Z","end":"2026-05-11T07:31:31.143377Z","steps":["trace[701141503] 'process raft request' (duration: 254.249643ms)","trace[701141503] 'compare' (duration: 242.02717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:31.143464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.650119Z","time spent":"493.245237ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:31.143486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.118414ms","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-05-11T07:31:31.143531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.646423Z","time spent":"497.035458ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:31.143575Z","caller":"traceutil/trace.go:171","msg":"trace[1708316932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3157; }","duration":"641.247606ms","start":"2026-05-11T07:31:30.502304Z","end":"2026-05-11T07:31:31.143552Z","steps":["trace[1708316932] 'agreement among raft nodes before linearized reading' (duration: 640.94259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:31.143625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.502287Z","time spent":"641.328019ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T07:31:31.143869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.181825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T07:31:31.144072Z","caller":"traceutil/trace.go:171","msg":"trace[1581794278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3157; }","duration":"641.389429ms","start":"2026-05-11T07:31:30.502585Z","end":"2026-05-11T07:31:31.143975Z","steps":["trace[1581794278] 'agreement among raft nodes before linearized reading' (duration: 640.96548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:31.144214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:30.502565Z","time spent":"641.632445ms","remote":"127.0.0.1:49432","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-05-11T07:31:31.48484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.667504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:31.485216Z","caller":"traceutil/trace.go:171","msg":"trace[2006132172] linearizableReadLoop","detail":"{readStateIndex:3283; appliedIndex:3280; }","duration":"315.40044ms","start":"2026-05-11T07:31:31.169789Z","end":"2026-05-11T07:31:31.485189Z","steps":["trace[2006132172] 'read index received' (duration: 119.328068ms)","trace[2006132172] 'applied index is now lower than readState.Index' (duration: 196.070672ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:31.485367Z","caller":"traceutil/trace.go:171","msg":"trace[1574616605] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"336.59588ms","start":"2026-05-11T07:31:31.148743Z","end":"2026-05-11T07:31:31.485339Z","steps":["trace[1574616605] 'process raft request' (duration: 336.297713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:31.485365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.626166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:31.485384Z","caller":"traceutil/trace.go:171","msg":"trace[184454482] transaction","detail":"{read_only:false; response_revision:3158; number_of_response:1; }","duration":"337.383477ms","start":"2026-05-11T07:31:31.147957Z","end":"2026-05-11T07:31:31.485341Z","steps":["trace[184454482] 'process raft request' (duration: 141.066509ms)","trace[184454482] 'compare' (duration: 195.440509ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:31.485447Z","caller":"traceutil/trace.go:171","msg":"trace[1553807002] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:3160; }","duration":"315.708757ms","start":"2026-05-11T07:31:31.169717Z","end":"2026-05-11T07:31:31.485426Z","steps":["trace[1553807002] 'agreement among raft nodes before linearized reading' (duration: 315.588095ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:31.485429Z","caller":"traceutil/trace.go:171","msg":"trace[228159586] transaction","detail":"{read_only:false; response_revision:3160; number_of_response:1; }","duration":"335.963056ms","start":"2026-05-11T07:31:31.149414Z","end":"2026-05-11T07:31:31.485377Z","steps":["trace[228159586] 'process raft request' (duration: 335.713161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:31.48548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:31.148686Z","time spent":"336.733622ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:31.485503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:31.169665Z","time spent":"315.82314ms","remote":"127.0.0.1:49478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-11T07:31:31.48556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:31.147932Z","time spent":"337.535779ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:31.485662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:31.149396Z","time spent":"336.13923ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:31.917397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.918677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:31.917536Z","caller":"traceutil/trace.go:171","msg":"trace[1285242073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3160; }","duration":"290.141201ms","start":"2026-05-11T07:31:31.627371Z","end":"2026-05-11T07:31:31.917512Z","steps":["trace[1285242073] 'range keys from in-memory index tree' (duration: 289.812624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:31.917855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.793649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:31.917953Z","caller":"traceutil/trace.go:171","msg":"trace[1193316878] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3160; }","duration":"150.959132ms","start":"2026-05-11T07:31:31.766969Z","end":"2026-05-11T07:31:31.917928Z","steps":["trace[1193316878] 'count revisions from in-memory index tree' (duration: 150.651446ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:32.039038Z","caller":"traceutil/trace.go:171","msg":"trace[472896961] transaction","detail":"{read_only:false; response_revision:3161; number_of_response:1; }","duration":"116.145529ms","start":"2026-05-11T07:31:31.922859Z","end":"2026-05-11T07:31:32.039005Z","steps":["trace[472896961] 'process raft request' (duration: 115.942195ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:32.362221Z","caller":"traceutil/trace.go:171","msg":"trace[831662151] transaction","detail":"{read_only:false; response_revision:3162; number_of_response:1; }","duration":"148.140943ms","start":"2026-05-11T07:31:32.214052Z","end":"2026-05-11T07:31:32.362193Z","steps":["trace[831662151] 'process raft request' (duration: 69.955637ms)","trace[831662151] 'compare' (duration: 77.42861ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:32.706128Z","caller":"traceutil/trace.go:171","msg":"trace[545883620] transaction","detail":"{read_only:false; response_revision:3163; number_of_response:1; }","duration":"246.933765ms","start":"2026-05-11T07:31:32.459157Z","end":"2026-05-11T07:31:32.70609Z","steps":["trace[545883620] 'process raft request' (duration: 238.334978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.167465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.934847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:33.167723Z","caller":"traceutil/trace.go:171","msg":"trace[636784053] transaction","detail":"{read_only:false; response_revision:3165; number_of_response:1; }","duration":"526.630851ms","start":"2026-05-11T07:31:32.641075Z","end":"2026-05-11T07:31:33.167706Z","steps":["trace[636784053] 'process raft request' (duration: 526.523439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.167792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.641054Z","time spent":"526.708162ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18815,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:33.167882Z","caller":"traceutil/trace.go:171","msg":"trace[529336101] linearizableReadLoop","detail":"{readStateIndex:3287; appliedIndex:3285; }","duration":"535.605207ms","start":"2026-05-11T07:31:32.632269Z","end":"2026-05-11T07:31:33.167874Z","steps":["trace[529336101] 'read index received' (duration: 65.283997ms)","trace[529336101] 'applied index is now lower than readState.Index' (duration: 470.32067ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:33.167877Z","caller":"traceutil/trace.go:171","msg":"trace[1728602928] transaction","detail":"{read_only:false; response_revision:3164; number_of_response:1; }","duration":"605.352028ms","start":"2026-05-11T07:31:32.562487Z","end":"2026-05-11T07:31:33.167839Z","steps":["trace[1728602928] 'process raft request' (duration: 345.943672ms)","trace[1728602928] 'compare' (duration: 258.760111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:33.168059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.562459Z","time spent":"605.501292ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:33.168205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.945484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:33.168234Z","caller":"traceutil/trace.go:171","msg":"trace[233631549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3166; }","duration":"536.016405ms","start":"2026-05-11T07:31:32.632208Z","end":"2026-05-11T07:31:33.168224Z","steps":["trace[233631549] 'agreement among raft nodes before linearized reading' (duration: 535.825921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.168275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.632186Z","time spent":"536.078907ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T07:31:33.168309Z","caller":"traceutil/trace.go:171","msg":"trace[2022571289] transaction","detail":"{read_only:false; response_revision:3166; number_of_response:1; }","duration":"307.534ms","start":"2026-05-11T07:31:32.860662Z","end":"2026-05-11T07:31:33.168196Z","steps":["trace[2022571289] 'process raft request' (duration: 307.003908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.168508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.860617Z","time spent":"307.780645ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:33.455029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.82786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:33.455294Z","caller":"traceutil/trace.go:171","msg":"trace[1167034221] linearizableReadLoop","detail":"{readStateIndex:3291; appliedIndex:3289; }","duration":"287.371812ms","start":"2026-05-11T07:31:33.167903Z","end":"2026-05-11T07:31:33.455274Z","steps":["trace[1167034221] 'read index received' (duration: 125.218445ms)","trace[1167034221] 'applied index is now lower than readState.Index' (duration: 162.152257ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:33.455405Z","caller":"traceutil/trace.go:171","msg":"trace[444388554] transaction","detail":"{read_only:false; response_revision:3167; number_of_response:1; }","duration":"306.623369ms","start":"2026-05-11T07:31:33.148769Z","end":"2026-05-11T07:31:33.455393Z","steps":["trace[444388554] 'process raft request' (duration: 144.256808ms)","trace[444388554] 'compare' (duration: 161.579905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:33.455474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.148749Z","time spent":"306.69085ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:33.455797Z","caller":"traceutil/trace.go:171","msg":"trace[1620038936] transaction","detail":"{read_only:false; response_revision:3168; number_of_response:1; }","duration":"305.68749ms","start":"2026-05-11T07:31:33.150099Z","end":"2026-05-11T07:31:33.455787Z","steps":["trace[1620038936] 'process raft request' (duration: 305.069537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.455865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.150071Z","time spent":"305.759691ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:33.456086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.270786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-11T07:31:33.456122Z","caller":"traceutil/trace.go:171","msg":"trace[1046330364] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3168; }","duration":"539.342747ms","start":"2026-05-11T07:31:32.91677Z","end":"2026-05-11T07:31:33.456113Z","steps":["trace[1046330364] 'agreement among raft nodes before linearized reading' (duration: 539.208074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.456153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.916749Z","time spent":"539.396758ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-11T07:31:33.456296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.78271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:33.456381Z","caller":"traceutil/trace.go:171","msg":"trace[340391932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3168; }","duration":"286.946783ms","start":"2026-05-11T07:31:33.169415Z","end":"2026-05-11T07:31:33.456362Z","steps":["trace[340391932] 'agreement among raft nodes before linearized reading' (duration: 286.774619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.456515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.097146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:31:33.456548Z","caller":"traceutil/trace.go:171","msg":"trace[935520927] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:3168; }","duration":"666.151507ms","start":"2026-05-11T07:31:32.790388Z","end":"2026-05-11T07:31:33.45654Z","steps":["trace[935520927] 'agreement among raft nodes before linearized reading' (duration: 666.047555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.456542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.616009ms","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-05-11T07:31:33.456576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.790377Z","time spent":"666.193448ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-wcdh2\" "} {"level":"info","ts":"2026-05-11T07:31:33.456607Z","caller":"traceutil/trace.go:171","msg":"trace[1221932476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3168; }","duration":"667.734932ms","start":"2026-05-11T07:31:32.788858Z","end":"2026-05-11T07:31:33.456593Z","steps":["trace[1221932476] 'agreement among raft nodes before linearized reading' (duration: 667.453386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.456649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.788839Z","time spent":"667.792973ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T07:31:33.456809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.694838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413389"} {"level":"info","ts":"2026-05-11T07:31:33.456831Z","caller":"traceutil/trace.go:171","msg":"trace[1242080902] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:3168; }","duration":"286.76589ms","start":"2026-05-11T07:31:33.17006Z","end":"2026-05-11T07:31:33.456826Z","steps":["trace[1242080902] 'agreement among raft nodes before linearized reading' (duration: 286.529635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.456944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.077ms","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-05-11T07:31:33.4571Z","caller":"traceutil/trace.go:171","msg":"trace[2121013709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3168; }","duration":"668.276873ms","start":"2026-05-11T07:31:32.788794Z","end":"2026-05-11T07:31:33.457071Z","steps":["trace[2121013709] 'agreement among raft nodes before linearized reading' (duration: 667.741772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.457181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:32.788776Z","time spent":"668.381166ms","remote":"127.0.0.1:49432","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":"warn","ts":"2026-05-11T07:31:33.857947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.707517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:33.858092Z","caller":"traceutil/trace.go:171","msg":"trace[553806027] transaction","detail":"{read_only:false; response_revision:3169; number_of_response:1; }","duration":"392.029351ms","start":"2026-05-11T07:31:33.466038Z","end":"2026-05-11T07:31:33.858068Z","steps":["trace[553806027] 'process raft request' (duration: 153.07973ms)","trace[553806027] 'compare' (duration: 238.358239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:33.858169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.466026Z","time spent":"392.106134ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:33.858805Z","caller":"traceutil/trace.go:171","msg":"trace[1066416897] linearizableReadLoop","detail":"{readStateIndex:3294; appliedIndex:3291; }","duration":"369.779599ms","start":"2026-05-11T07:31:33.488995Z","end":"2026-05-11T07:31:33.858775Z","steps":["trace[1066416897] 'read index received' (duration: 130.268196ms)","trace[1066416897] 'applied index is now lower than readState.Index' (duration: 239.509833ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:33.858842Z","caller":"traceutil/trace.go:171","msg":"trace[1922703372] transaction","detail":"{read_only:false; response_revision:3170; number_of_response:1; }","duration":"392.588774ms","start":"2026-05-11T07:31:33.466214Z","end":"2026-05-11T07:31:33.858803Z","steps":["trace[1922703372] 'process raft request' (duration: 392.374899ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:33.858948Z","caller":"traceutil/trace.go:171","msg":"trace[1120277316] transaction","detail":"{read_only:false; response_revision:3171; number_of_response:1; }","duration":"391.685414ms","start":"2026-05-11T07:31:33.467249Z","end":"2026-05-11T07:31:33.858934Z","steps":["trace[1120277316] 'process raft request' (duration: 391.474619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.859047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.466207Z","time spent":"392.723677ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:33.859068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.467215Z","time spent":"391.768325ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:33.859219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.778939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-11T07:31:33.859351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.352612ms","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-05-11T07:31:33.859357Z","caller":"traceutil/trace.go:171","msg":"trace[1278140161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3171; }","duration":"369.934073ms","start":"2026-05-11T07:31:33.489402Z","end":"2026-05-11T07:31:33.859336Z","steps":["trace[1278140161] 'agreement among raft nodes before linearized reading' (duration: 369.659227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.859406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.489399Z","time spent":"369.997515ms","remote":"127.0.0.1:49432","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-05-11T07:31:33.859397Z","caller":"traceutil/trace.go:171","msg":"trace[581142494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3171; }","duration":"370.423084ms","start":"2026-05-11T07:31:33.488963Z","end":"2026-05-11T07:31:33.859386Z","steps":["trace[581142494] 'agreement among raft nodes before linearized reading' (duration: 370.25218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.85947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.488953Z","time spent":"370.508386ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T07:31:33.859558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.563164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T07:31:33.859678Z","caller":"traceutil/trace.go:171","msg":"trace[998312636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3171; }","duration":"369.693178ms","start":"2026-05-11T07:31:33.489953Z","end":"2026-05-11T07:31:33.859646Z","steps":["trace[998312636] 'agreement among raft nodes before linearized reading' (duration: 369.405872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:33.859745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.48995Z","time spent":"369.77936ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T07:31:34.177644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.38411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:34.177957Z","caller":"traceutil/trace.go:171","msg":"trace[1430672199] linearizableReadLoop","detail":"{readStateIndex:3297; appliedIndex:3294; }","duration":"235.441446ms","start":"2026-05-11T07:31:33.942493Z","end":"2026-05-11T07:31:34.177935Z","steps":["trace[1430672199] 'read index received' (duration: 67.569435ms)","trace[1430672199] 'applied index is now lower than readState.Index' (duration: 167.870521ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:34.178066Z","caller":"traceutil/trace.go:171","msg":"trace[1792971625] transaction","detail":"{read_only:false; response_revision:3174; number_of_response:1; }","duration":"313.462927ms","start":"2026-05-11T07:31:33.864592Z","end":"2026-05-11T07:31:34.178055Z","steps":["trace[1792971625] 'process raft request' (duration: 313.290754ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:34.178095Z","caller":"traceutil/trace.go:171","msg":"trace[1003291708] transaction","detail":"{read_only:false; response_revision:3173; number_of_response:1; }","duration":"313.472308ms","start":"2026-05-11T07:31:33.864581Z","end":"2026-05-11T07:31:34.178054Z","steps":["trace[1003291708] 'process raft request' (duration: 313.200553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:34.178215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.687962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T07:31:34.178212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.893974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:34.178329Z","caller":"traceutil/trace.go:171","msg":"trace[1556757371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3174; }","duration":"235.826885ms","start":"2026-05-11T07:31:33.942476Z","end":"2026-05-11T07:31:34.178303Z","steps":["trace[1556757371] 'agreement among raft nodes before linearized reading' (duration: 235.583389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:34.178351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.86456Z","time spent":"313.634631ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:34.178382Z","caller":"traceutil/trace.go:171","msg":"trace[569633649] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3174; }","duration":"158.111339ms","start":"2026-05-11T07:31:34.020246Z","end":"2026-05-11T07:31:34.178357Z","steps":["trace[569633649] 'agreement among raft nodes before linearized reading' (duration: 157.832153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:34.178132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.864567Z","time spent":"313.529749ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:34.177938Z","caller":"traceutil/trace.go:171","msg":"trace[912465743] transaction","detail":"{read_only:false; response_revision:3172; number_of_response:1; }","duration":"313.322675ms","start":"2026-05-11T07:31:33.864569Z","end":"2026-05-11T07:31:34.177892Z","steps":["trace[912465743] 'process raft request' (duration: 145.586877ms)","trace[912465743] 'compare' (duration: 167.228217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:34.178671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:33.864548Z","time spent":"314.02987ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:34.583223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.137984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:34.583337Z","caller":"traceutil/trace.go:171","msg":"trace[1899312290] linearizableReadLoop","detail":"{readStateIndex:3298; appliedIndex:3297; }","duration":"205.762862ms","start":"2026-05-11T07:31:34.377556Z","end":"2026-05-11T07:31:34.583319Z","steps":["trace[1899312290] 'read index received' (duration: 80.337221ms)","trace[1899312290] 'applied index is now lower than readState.Index' (duration: 125.424161ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:34.583502Z","caller":"traceutil/trace.go:171","msg":"trace[94071315] transaction","detail":"{read_only:false; response_revision:3175; number_of_response:1; }","duration":"215.383801ms","start":"2026-05-11T07:31:34.368095Z","end":"2026-05-11T07:31:34.583478Z","steps":["trace[94071315] 'process raft request' (duration: 89.897479ms)","trace[94071315] 'compare' (duration: 124.882959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:34.583542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.970117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T07:31:34.58357Z","caller":"traceutil/trace.go:171","msg":"trace[1841361294] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:3175; }","duration":"206.058659ms","start":"2026-05-11T07:31:34.377502Z","end":"2026-05-11T07:31:34.58356Z","steps":["trace[1841361294] 'agreement among raft nodes before linearized reading' (duration: 205.863285ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:34.79168Z","caller":"traceutil/trace.go:171","msg":"trace[1275948847] linearizableReadLoop","detail":"{readStateIndex:3299; appliedIndex:3298; }","duration":"165.164392ms","start":"2026-05-11T07:31:34.626464Z","end":"2026-05-11T07:31:34.791629Z","steps":["trace[1275948847] 'read index received' (duration: 109.387162ms)","trace[1275948847] 'applied index is now lower than readState.Index' (duration: 55.76451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:34.791896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.367907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T07:31:34.792169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.038875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T07:31:34.792215Z","caller":"traceutil/trace.go:171","msg":"trace[1314923606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3176; }","duration":"165.792927ms","start":"2026-05-11T07:31:34.626395Z","end":"2026-05-11T07:31:34.792188Z","steps":["trace[1314923606] 'agreement among raft nodes before linearized reading' (duration: 165.374277ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:34.792231Z","caller":"traceutil/trace.go:171","msg":"trace[2043329645] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:3176; }","duration":"145.362033ms","start":"2026-05-11T07:31:34.646855Z","end":"2026-05-11T07:31:34.792217Z","steps":["trace[2043329645] 'agreement among raft nodes before linearized reading' (duration: 144.991264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:34.792216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.477515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T07:31:34.792313Z","caller":"traceutil/trace.go:171","msg":"trace[346876370] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:3176; }","duration":"127.662169ms","start":"2026-05-11T07:31:34.664628Z","end":"2026-05-11T07:31:34.79229Z","steps":["trace[346876370] 'agreement among raft nodes before linearized reading' (duration: 127.306341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:35.039058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.721627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:35.039211Z","caller":"traceutil/trace.go:171","msg":"trace[624983488] linearizableReadLoop","detail":"{readStateIndex:3301; appliedIndex:3300; }","duration":"245.834801ms","start":"2026-05-11T07:31:34.793353Z","end":"2026-05-11T07:31:35.039187Z","steps":["trace[624983488] 'read index received' (duration: 131.742897ms)","trace[624983488] 'applied index is now lower than readState.Index' (duration: 114.090124ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:35.039341Z","caller":"traceutil/trace.go:171","msg":"trace[950422751] transaction","detail":"{read_only:false; response_revision:3177; number_of_response:1; }","duration":"246.172269ms","start":"2026-05-11T07:31:34.793142Z","end":"2026-05-11T07:31:35.039314Z","steps":["trace[950422751] 'process raft request' (duration: 132.078624ms)","trace[950422751] 'compare' (duration: 113.536333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:35.039391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.043126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:35.039439Z","caller":"traceutil/trace.go:171","msg":"trace[1068161915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3177; }","duration":"246.131748ms","start":"2026-05-11T07:31:34.793295Z","end":"2026-05-11T07:31:35.039426Z","steps":["trace[1068161915] 'agreement among raft nodes before linearized reading' (duration: 245.954404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:35.82176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.516213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:35.821973Z","caller":"traceutil/trace.go:171","msg":"trace[64712591] linearizableReadLoop","detail":"{readStateIndex:3303; appliedIndex:3301; }","duration":"352.933474ms","start":"2026-05-11T07:31:35.469003Z","end":"2026-05-11T07:31:35.821937Z","steps":["trace[64712591] 'read index received' (duration: 23.895689ms)","trace[64712591] 'applied index is now lower than readState.Index' (duration: 329.036175ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:35.822025Z","caller":"traceutil/trace.go:171","msg":"trace[147585777] transaction","detail":"{read_only:false; response_revision:3179; number_of_response:1; }","duration":"358.26673ms","start":"2026-05-11T07:31:35.4637Z","end":"2026-05-11T07:31:35.821967Z","steps":["trace[147585777] 'process raft request' (duration: 166.410209ms)","trace[147585777] 'compare' (duration: 191.311279ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:35.822181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.172459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:31:35.822233Z","caller":"traceutil/trace.go:171","msg":"trace[1059638333] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:3179; }","duration":"353.252401ms","start":"2026-05-11T07:31:35.468964Z","end":"2026-05-11T07:31:35.822216Z","steps":["trace[1059638333] 'agreement among raft nodes before linearized reading' (duration: 353.058167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:35.822239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:35.463676Z","time spent":"358.450384ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:35.822289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:35.468947Z","time spent":"353.333222ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-wcdh2\" "} {"level":"warn","ts":"2026-05-11T07:31:36.099106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.802383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:36.099361Z","caller":"traceutil/trace.go:171","msg":"trace[610979200] transaction","detail":"{read_only:false; response_revision:3181; number_of_response:1; }","duration":"230.439667ms","start":"2026-05-11T07:31:35.868901Z","end":"2026-05-11T07:31:36.09934Z","steps":["trace[610979200] 'process raft request' (duration: 230.340665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:36.099735Z","caller":"traceutil/trace.go:171","msg":"trace[1518963120] transaction","detail":"{read_only:false; response_revision:3180; number_of_response:1; }","duration":"230.988979ms","start":"2026-05-11T07:31:35.86871Z","end":"2026-05-11T07:31:36.099699Z","steps":["trace[1518963120] 'process raft request' (duration: 60.496801ms)","trace[1518963120] 'compare' (duration: 169.491796ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:36.414744Z","caller":"traceutil/trace.go:171","msg":"trace[1976570453] transaction","detail":"{read_only:false; response_revision:3182; number_of_response:1; }","duration":"228.055636ms","start":"2026-05-11T07:31:36.186658Z","end":"2026-05-11T07:31:36.414714Z","steps":["trace[1976570453] 'process raft request' (duration: 227.855452ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:36.432467Z","caller":"traceutil/trace.go:171","msg":"trace[1788695474] transaction","detail":"{read_only:false; response_revision:3184; number_of_response:1; }","duration":"244.591724ms","start":"2026-05-11T07:31:36.187854Z","end":"2026-05-11T07:31:36.432446Z","steps":["trace[1788695474] 'process raft request' (duration: 244.536583ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:36.432513Z","caller":"traceutil/trace.go:171","msg":"trace[238434510] transaction","detail":"{read_only:false; response_revision:3183; number_of_response:1; }","duration":"245.035854ms","start":"2026-05-11T07:31:36.187459Z","end":"2026-05-11T07:31:36.432495Z","steps":["trace[238434510] 'process raft request' (duration: 244.815279ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:36.774152Z","caller":"traceutil/trace.go:171","msg":"trace[754905233] linearizableReadLoop","detail":"{readStateIndex:3309; appliedIndex:3308; }","duration":"146.991617ms","start":"2026-05-11T07:31:36.62712Z","end":"2026-05-11T07:31:36.774112Z","steps":["trace[754905233] 'read index received' (duration: 101.634564ms)","trace[754905233] 'applied index is now lower than readState.Index' (duration: 45.355473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:36.774308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.175631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:36.774344Z","caller":"traceutil/trace.go:171","msg":"trace[1024729587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3185; }","duration":"147.280943ms","start":"2026-05-11T07:31:36.627052Z","end":"2026-05-11T07:31:36.774333Z","steps":["trace[1024729587] 'agreement among raft nodes before linearized reading' (duration: 147.189061ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:36.774656Z","caller":"traceutil/trace.go:171","msg":"trace[2029742791] transaction","detail":"{read_only:false; response_revision:3185; number_of_response:1; }","duration":"185.143985ms","start":"2026-05-11T07:31:36.589471Z","end":"2026-05-11T07:31:36.774615Z","steps":["trace[2029742791] 'process raft request' (duration: 139.328352ms)","trace[2029742791] 'compare' (duration: 44.74715ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:37.870553Z","caller":"traceutil/trace.go:171","msg":"trace[1195264443] linearizableReadLoop","detail":"{readStateIndex:3311; appliedIndex:3310; }","duration":"243.126192ms","start":"2026-05-11T07:31:37.627393Z","end":"2026-05-11T07:31:37.870519Z","steps":["trace[1195264443] 'read index received' (duration: 242.958678ms)","trace[1195264443] 'applied index is now lower than readState.Index' (duration: 165.653µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:37.870654Z","caller":"traceutil/trace.go:171","msg":"trace[97543581] transaction","detail":"{read_only:false; response_revision:3187; number_of_response:1; }","duration":"371.051946ms","start":"2026-05-11T07:31:37.499558Z","end":"2026-05-11T07:31:37.87061Z","steps":["trace[97543581] 'process raft request' (duration: 370.695709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:37.870782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.366077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:31:37.870834Z","caller":"traceutil/trace.go:171","msg":"trace[575226191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3187; }","duration":"243.49957ms","start":"2026-05-11T07:31:37.627319Z","end":"2026-05-11T07:31:37.870819Z","steps":["trace[575226191] 'agreement among raft nodes before linearized reading' (duration: 243.384937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:37.870858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:37.499528Z","time spent":"371.218439ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:37.897532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.502882ms","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-05-11T07:31:37.897623Z","caller":"traceutil/trace.go:171","msg":"trace[1685326586] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3188; }","duration":"202.661605ms","start":"2026-05-11T07:31:37.694941Z","end":"2026-05-11T07:31:37.897603Z","steps":["trace[1685326586] 'agreement among raft nodes before linearized reading' (duration: 202.488602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:37.897825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.631197ms","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-05-11T07:31:37.898008Z","caller":"traceutil/trace.go:171","msg":"trace[514941238] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:3188; }","duration":"203.828091ms","start":"2026-05-11T07:31:37.694076Z","end":"2026-05-11T07:31:37.897904Z","steps":["trace[514941238] 'agreement among raft nodes before linearized reading' (duration: 203.516734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:38.235246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.611693ms","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-05-11T07:31:38.235374Z","caller":"traceutil/trace.go:171","msg":"trace[2113171294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3188; }","duration":"130.798347ms","start":"2026-05-11T07:31:38.104546Z","end":"2026-05-11T07:31:38.235345Z","steps":["trace[2113171294] 'range keys from in-memory index tree' (duration: 130.403528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:38.235245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.856238ms","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-05-11T07:31:38.235529Z","caller":"traceutil/trace.go:171","msg":"trace[605535026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3188; }","duration":"131.224376ms","start":"2026-05-11T07:31:38.104277Z","end":"2026-05-11T07:31:38.235501Z","steps":["trace[605535026] 'range keys from in-memory index tree' (duration: 130.588072ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:38.369817Z","caller":"traceutil/trace.go:171","msg":"trace[54296488] transaction","detail":"{read_only:false; response_revision:3189; number_of_response:1; }","duration":"128.905626ms","start":"2026-05-11T07:31:38.240879Z","end":"2026-05-11T07:31:38.369784Z","steps":["trace[54296488] 'process raft request' (duration: 128.707131ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:38.382689Z","caller":"traceutil/trace.go:171","msg":"trace[958607961] transaction","detail":"{read_only:false; response_revision:3190; number_of_response:1; }","duration":"141.60925ms","start":"2026-05-11T07:31:38.241037Z","end":"2026-05-11T07:31:38.382647Z","steps":["trace[958607961] 'process raft request' (duration: 141.428866ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:38.662114Z","caller":"traceutil/trace.go:171","msg":"trace[1440591539] linearizableReadLoop","detail":"{readStateIndex:3315; appliedIndex:3314; }","duration":"225.835208ms","start":"2026-05-11T07:31:38.436227Z","end":"2026-05-11T07:31:38.662062Z","steps":["trace[1440591539] 'read index received' (duration: 139.103047ms)","trace[1440591539] 'applied index is now lower than readState.Index' (duration: 86.730031ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:38.662149Z","caller":"traceutil/trace.go:171","msg":"trace[852489033] transaction","detail":"{read_only:false; response_revision:3191; number_of_response:1; }","duration":"237.266976ms","start":"2026-05-11T07:31:38.424846Z","end":"2026-05-11T07:31:38.662113Z","steps":["trace[852489033] 'process raft request' (duration: 150.542725ms)","trace[852489033] 'compare' (duration: 86.293802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:38.662465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.202636ms","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-05-11T07:31:38.662505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.146274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T07:31:38.662572Z","caller":"traceutil/trace.go:171","msg":"trace[1873713263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3191; }","duration":"226.279217ms","start":"2026-05-11T07:31:38.436276Z","end":"2026-05-11T07:31:38.662555Z","steps":["trace[1873713263] 'agreement among raft nodes before linearized reading' (duration: 226.071163ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:38.662519Z","caller":"traceutil/trace.go:171","msg":"trace[168521900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3191; }","duration":"226.300058ms","start":"2026-05-11T07:31:38.436201Z","end":"2026-05-11T07:31:38.662501Z","steps":["trace[168521900] 'agreement among raft nodes before linearized reading' (duration: 226.054393ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:40.596207Z","caller":"traceutil/trace.go:171","msg":"trace[391536280] linearizableReadLoop","detail":"{readStateIndex:3324; appliedIndex:3323; }","duration":"208.671235ms","start":"2026-05-11T07:31:40.38751Z","end":"2026-05-11T07:31:40.596181Z","steps":["trace[391536280] 'read index received' (duration: 208.463461ms)","trace[391536280] 'applied index is now lower than readState.Index' (duration: 205.934µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:31:40.596337Z","caller":"traceutil/trace.go:171","msg":"trace[839503791] transaction","detail":"{read_only:false; response_revision:3199; number_of_response:1; }","duration":"216.358982ms","start":"2026-05-11T07:31:40.379948Z","end":"2026-05-11T07:31:40.596307Z","steps":["trace[839503791] 'process raft request' (duration: 216.049545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:40.596773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.216338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T07:31:40.596895Z","caller":"traceutil/trace.go:171","msg":"trace[1886228648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3199; }","duration":"209.389442ms","start":"2026-05-11T07:31:40.387477Z","end":"2026-05-11T07:31:40.596867Z","steps":["trace[1886228648] 'agreement among raft nodes before linearized reading' (duration: 208.922881ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:55.792439Z","caller":"traceutil/trace.go:171","msg":"trace[1926135433] transaction","detail":"{read_only:false; response_revision:3278; number_of_response:1; }","duration":"102.618006ms","start":"2026-05-11T07:31:55.689794Z","end":"2026-05-11T07:31:55.792412Z","steps":["trace[1926135433] 'process raft request' (duration: 102.448092ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:56.064183Z","caller":"traceutil/trace.go:171","msg":"trace[909336631] transaction","detail":"{read_only:false; response_revision:3279; number_of_response:1; }","duration":"123.781944ms","start":"2026-05-11T07:31:55.940297Z","end":"2026-05-11T07:31:56.064079Z","steps":["trace[909336631] 'process raft request' (duration: 123.543099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:56.340757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.148152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:31:56.340879Z","caller":"traceutil/trace.go:171","msg":"trace[2083927488] linearizableReadLoop","detail":"{readStateIndex:3408; appliedIndex:3406; }","duration":"343.376227ms","start":"2026-05-11T07:31:55.997484Z","end":"2026-05-11T07:31:56.34086Z","steps":["trace[2083927488] 'read index received' (duration: 66.354779ms)","trace[2083927488] 'applied index is now lower than readState.Index' (duration: 277.020328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:56.341025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.550901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:31:56.341064Z","caller":"traceutil/trace.go:171","msg":"trace[703631493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:3280; }","duration":"343.621413ms","start":"2026-05-11T07:31:55.997434Z","end":"2026-05-11T07:31:56.341055Z","steps":["trace[703631493] 'agreement among raft nodes before linearized reading' (duration: 343.47495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:56.341106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:55.997421Z","time spent":"343.671184ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-wcdh2\" "} {"level":"info","ts":"2026-05-11T07:31:56.341315Z","caller":"traceutil/trace.go:171","msg":"trace[891778076] transaction","detail":"{read_only:false; response_revision:3280; number_of_response:1; }","duration":"379.895629ms","start":"2026-05-11T07:31:55.961374Z","end":"2026-05-11T07:31:56.34127Z","steps":["trace[891778076] 'process raft request' (duration: 238.107374ms)","trace[891778076] 'compare' (duration: 140.962688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:31:56.341522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:55.961337Z","time spent":"380.082603ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:57.055392Z","caller":"traceutil/trace.go:171","msg":"trace[952580740] transaction","detail":"{read_only:false; response_revision:3282; number_of_response:1; }","duration":"262.236617ms","start":"2026-05-11T07:31:56.793117Z","end":"2026-05-11T07:31:57.055354Z","steps":["trace[952580740] 'process raft request' (duration: 262.049123ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:57.345214Z","caller":"traceutil/trace.go:171","msg":"trace[1573074406] transaction","detail":"{read_only:false; response_revision:3283; number_of_response:1; }","duration":"552.009441ms","start":"2026-05-11T07:31:56.793175Z","end":"2026-05-11T07:31:57.345184Z","steps":["trace[1573074406] 'process raft request' (duration: 551.788046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:57.3454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:56.79315Z","time spent":"552.146924ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:57.34554Z","caller":"traceutil/trace.go:171","msg":"trace[423510419] transaction","detail":"{read_only:false; response_revision:3284; number_of_response:1; }","duration":"551.878209ms","start":"2026-05-11T07:31:56.793628Z","end":"2026-05-11T07:31:57.345506Z","steps":["trace[423510419] 'process raft request' (duration: 551.523161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:57.345756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:56.793598Z","time spent":"552.041672ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:57.346014Z","caller":"traceutil/trace.go:171","msg":"trace[345397629] transaction","detail":"{read_only:false; response_revision:3287; number_of_response:1; }","duration":"406.314732ms","start":"2026-05-11T07:31:56.939686Z","end":"2026-05-11T07:31:57.346001Z","steps":["trace[345397629] 'process raft request' (duration: 406.194149ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:31:57.345998Z","caller":"traceutil/trace.go:171","msg":"trace[575964893] transaction","detail":"{read_only:false; response_revision:3286; number_of_response:1; }","duration":"492.03929ms","start":"2026-05-11T07:31:56.853919Z","end":"2026-05-11T07:31:57.345958Z","steps":["trace[575964893] 'process raft request' (duration: 491.906298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:57.346118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:56.939646Z","time spent":"406.416195ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:57.346093Z","caller":"traceutil/trace.go:171","msg":"trace[501166254] transaction","detail":"{read_only:false; response_revision:3285; number_of_response:1; }","duration":"552.108504ms","start":"2026-05-11T07:31:56.793896Z","end":"2026-05-11T07:31:57.346005Z","steps":["trace[501166254] 'process raft request' (duration: 551.4799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:31:57.346179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:56.853888Z","time spent":"492.200204ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:31:57.346355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:31:56.793861Z","time spent":"552.34544ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:31:58.253829Z","caller":"traceutil/trace.go:171","msg":"trace[953243344] transaction","detail":"{read_only:false; response_revision:3288; number_of_response:1; }","duration":"183.420348ms","start":"2026-05-11T07:31:58.070381Z","end":"2026-05-11T07:31:58.253801Z","steps":["trace[953243344] 'process raft request' (duration: 183.236453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:07.541957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.774513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T07:32:07.542067Z","caller":"traceutil/trace.go:171","msg":"trace[1759230706] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:3333; }","duration":"176.951107ms","start":"2026-05-11T07:32:07.365089Z","end":"2026-05-11T07:32:07.54204Z","steps":["trace[1759230706] 'count revisions from in-memory index tree' (duration: 176.666271ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:07.542171Z","caller":"traceutil/trace.go:171","msg":"trace[1913483419] transaction","detail":"{read_only:false; response_revision:3334; number_of_response:1; }","duration":"156.866541ms","start":"2026-05-11T07:32:07.385282Z","end":"2026-05-11T07:32:07.542148Z","steps":["trace[1913483419] 'process raft request' (duration: 82.312105ms)","trace[1913483419] 'compare' (duration: 74.391773ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:07.586769Z","caller":"traceutil/trace.go:171","msg":"trace[466533317] linearizableReadLoop","detail":"{readStateIndex:3465; appliedIndex:3463; }","duration":"188.14954ms","start":"2026-05-11T07:32:07.398594Z","end":"2026-05-11T07:32:07.586744Z","steps":["trace[466533317] 'read index received' (duration: 68.972506ms)","trace[466533317] 'applied index is now lower than readState.Index' (duration: 119.176034ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:07.586857Z","caller":"traceutil/trace.go:171","msg":"trace[933184877] transaction","detail":"{read_only:false; response_revision:3335; number_of_response:1; }","duration":"195.98598ms","start":"2026-05-11T07:32:07.390828Z","end":"2026-05-11T07:32:07.586814Z","steps":["trace[933184877] 'process raft request' (duration: 195.783736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:07.587006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.388435ms","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-05-11T07:32:07.587037Z","caller":"traceutil/trace.go:171","msg":"trace[1147962926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3335; }","duration":"188.470267ms","start":"2026-05-11T07:32:07.398559Z","end":"2026-05-11T07:32:07.587029Z","steps":["trace[1147962926] 'agreement among raft nodes before linearized reading' (duration: 188.317103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:07.587025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.798902ms","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-05-11T07:32:07.587066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.67302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T07:32:07.587161Z","caller":"traceutil/trace.go:171","msg":"trace[1268995190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3335; }","duration":"187.794943ms","start":"2026-05-11T07:32:07.399351Z","end":"2026-05-11T07:32:07.587146Z","steps":["trace[1268995190] 'agreement among raft nodes before linearized reading' (duration: 187.584729ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:07.587085Z","caller":"traceutil/trace.go:171","msg":"trace[497097533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3335; }","duration":"187.894294ms","start":"2026-05-11T07:32:07.399175Z","end":"2026-05-11T07:32:07.58707Z","steps":["trace[497097533] 'agreement among raft nodes before linearized reading' (duration: 187.748931ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:07.828559Z","caller":"traceutil/trace.go:171","msg":"trace[517401863] transaction","detail":"{read_only:false; response_revision:3336; number_of_response:1; }","duration":"236.659992ms","start":"2026-05-11T07:32:07.591857Z","end":"2026-05-11T07:32:07.828517Z","steps":["trace[517401863] 'process raft request' (duration: 214.370218ms)","trace[517401863] 'compare' (duration: 22.012278ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:07.991421Z","caller":"traceutil/trace.go:171","msg":"trace[1630242642] transaction","detail":"{read_only:false; response_revision:3337; number_of_response:1; }","duration":"399.110006ms","start":"2026-05-11T07:32:07.592263Z","end":"2026-05-11T07:32:07.991373Z","steps":["trace[1630242642] 'process raft request' (duration: 378.992009ms)","trace[1630242642] 'compare' (duration: 19.882711ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:07.991489Z","caller":"traceutil/trace.go:171","msg":"trace[636604299] transaction","detail":"{read_only:false; response_revision:3338; number_of_response:1; }","duration":"399.037825ms","start":"2026-05-11T07:32:07.592402Z","end":"2026-05-11T07:32:07.99144Z","steps":["trace[636604299] 'process raft request' (duration: 398.889511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:07.991655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:32:07.592225Z","time spent":"399.27872ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:32:07.991811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:32:07.592381Z","time spent":"399.264329ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:32:15.336213Z","caller":"traceutil/trace.go:171","msg":"trace[1465240165] transaction","detail":"{read_only:false; response_revision:3373; number_of_response:1; }","duration":"124.282335ms","start":"2026-05-11T07:32:15.211896Z","end":"2026-05-11T07:32:15.336178Z","steps":["trace[1465240165] 'process raft request' (duration: 124.0526ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:15.688903Z","caller":"traceutil/trace.go:171","msg":"trace[1893794301] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"116.281771ms","start":"2026-05-11T07:32:15.572582Z","end":"2026-05-11T07:32:15.688863Z","steps":["trace[1893794301] 'process raft request' (duration: 115.992665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:15.713755Z","caller":"traceutil/trace.go:171","msg":"trace[1403567484] transaction","detail":"{read_only:false; response_revision:3375; number_of_response:1; }","duration":"100.223664ms","start":"2026-05-11T07:32:15.613492Z","end":"2026-05-11T07:32:15.713715Z","steps":["trace[1403567484] 'process raft request' (duration: 100.0599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:15.986286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.900248ms","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-05-11T07:32:15.986368Z","caller":"traceutil/trace.go:171","msg":"trace[2056273664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3375; }","duration":"119.038202ms","start":"2026-05-11T07:32:15.867312Z","end":"2026-05-11T07:32:15.98635Z","steps":["trace[2056273664] 'range keys from in-memory index tree' (duration: 118.684603ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:16.142673Z","caller":"traceutil/trace.go:171","msg":"trace[459153514] linearizableReadLoop","detail":"{readStateIndex:3508; appliedIndex:3507; }","duration":"114.228227ms","start":"2026-05-11T07:32:16.028417Z","end":"2026-05-11T07:32:16.142645Z","steps":["trace[459153514] 'read index received' (duration: 114.021572ms)","trace[459153514] 'applied index is now lower than readState.Index' (duration: 205.315µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:16.142769Z","caller":"traceutil/trace.go:171","msg":"trace[1931240678] transaction","detail":"{read_only:false; response_revision:3376; number_of_response:1; }","duration":"151.411974ms","start":"2026-05-11T07:32:15.991316Z","end":"2026-05-11T07:32:16.142728Z","steps":["trace[1931240678] 'process raft request' (duration: 151.118257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:16.142964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.524803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T07:32:16.142998Z","caller":"traceutil/trace.go:171","msg":"trace[42208939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3376; }","duration":"114.637505ms","start":"2026-05-11T07:32:16.028351Z","end":"2026-05-11T07:32:16.142989Z","steps":["trace[42208939] 'agreement among raft nodes before linearized reading' (duration: 114.470022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:16.143397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.265128ms","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-05-11T07:32:16.143496Z","caller":"traceutil/trace.go:171","msg":"trace[1093462495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3376; }","duration":"114.424442ms","start":"2026-05-11T07:32:16.02905Z","end":"2026-05-11T07:32:16.143474Z","steps":["trace[1093462495] 'agreement among raft nodes before linearized reading' (duration: 114.154686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:21.716861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.453067ms","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-05-11T07:32:21.717005Z","caller":"traceutil/trace.go:171","msg":"trace[1225256477] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3404; }","duration":"182.60789ms","start":"2026-05-11T07:32:21.534372Z","end":"2026-05-11T07:32:21.71698Z","steps":["trace[1225256477] 'range keys from in-memory index tree' (duration: 182.436806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:21.717182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.590035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:32:21.717321Z","caller":"traceutil/trace.go:171","msg":"trace[1561650326] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:3404; }","duration":"138.764639ms","start":"2026-05-11T07:32:21.578514Z","end":"2026-05-11T07:32:21.717278Z","steps":["trace[1561650326] 'count revisions from in-memory index tree' (duration: 138.500724ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:21.844811Z","caller":"traceutil/trace.go:171","msg":"trace[725317031] linearizableReadLoop","detail":"{readStateIndex:3538; appliedIndex:3537; }","duration":"126.431011ms","start":"2026-05-11T07:32:21.718355Z","end":"2026-05-11T07:32:21.844787Z","steps":["trace[725317031] 'read index received' (duration: 126.328089ms)","trace[725317031] 'applied index is now lower than readState.Index' (duration: 101.792µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:21.844937Z","caller":"traceutil/trace.go:171","msg":"trace[315690107] transaction","detail":"{read_only:false; response_revision:3405; number_of_response:1; }","duration":"134.281402ms","start":"2026-05-11T07:32:21.710616Z","end":"2026-05-11T07:32:21.844898Z","steps":["trace[315690107] 'process raft request' (duration: 133.967065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:21.844993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.613525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T07:32:21.84504Z","caller":"traceutil/trace.go:171","msg":"trace[1667633069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3405; }","duration":"126.719248ms","start":"2026-05-11T07:32:21.71831Z","end":"2026-05-11T07:32:21.84503Z","steps":["trace[1667633069] 'agreement among raft nodes before linearized reading' (duration: 126.576435ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:21.988629Z","caller":"traceutil/trace.go:171","msg":"trace[2026049442] transaction","detail":"{read_only:false; response_revision:3406; number_of_response:1; }","duration":"256.533243ms","start":"2026-05-11T07:32:21.732056Z","end":"2026-05-11T07:32:21.988589Z","steps":["trace[2026049442] 'process raft request' (duration: 220.625884ms)","trace[2026049442] 'compare' (duration: 35.743565ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:28.43306Z","caller":"traceutil/trace.go:171","msg":"trace[1523438142] linearizableReadLoop","detail":"{readStateIndex:3571; appliedIndex:3570; }","duration":"168.455493ms","start":"2026-05-11T07:32:28.264572Z","end":"2026-05-11T07:32:28.433027Z","steps":["trace[1523438142] 'read index received' (duration: 168.409342ms)","trace[1523438142] 'applied index is now lower than readState.Index' (duration: 45.081µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:32:28.433103Z","caller":"traceutil/trace.go:171","msg":"trace[1018903914] transaction","detail":"{read_only:false; response_revision:3437; number_of_response:1; }","duration":"170.439906ms","start":"2026-05-11T07:32:28.262643Z","end":"2026-05-11T07:32:28.433083Z","steps":["trace[1018903914] 'process raft request' (duration: 170.210981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:28.433335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.753529ms","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-05-11T07:32:28.433456Z","caller":"traceutil/trace.go:171","msg":"trace[1365539997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3437; }","duration":"168.877662ms","start":"2026-05-11T07:32:28.264492Z","end":"2026-05-11T07:32:28.43337Z","steps":["trace[1365539997] 'agreement among raft nodes before linearized reading' (duration: 168.654537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:28.437819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.415477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T07:32:28.437929Z","caller":"traceutil/trace.go:171","msg":"trace[2101696939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3438; }","duration":"172.585241ms","start":"2026-05-11T07:32:28.265319Z","end":"2026-05-11T07:32:28.437905Z","steps":["trace[2101696939] 'agreement among raft nodes before linearized reading' (duration: 172.270354ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:33.649329Z","caller":"traceutil/trace.go:171","msg":"trace[288430287] transaction","detail":"{read_only:false; response_revision:3464; number_of_response:1; }","duration":"124.385669ms","start":"2026-05-11T07:32:33.524911Z","end":"2026-05-11T07:32:33.649297Z","steps":["trace[288430287] 'process raft request' (duration: 124.188735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:58.802157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.482668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:68642"} {"level":"info","ts":"2026-05-11T07:32:58.80228Z","caller":"traceutil/trace.go:171","msg":"trace[76392594] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:3595; }","duration":"112.678622ms","start":"2026-05-11T07:32:58.689579Z","end":"2026-05-11T07:32:58.802258Z","steps":["trace[76392594] 'range keys from in-memory index tree' (duration: 112.12709ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:32:59.600506Z","caller":"traceutil/trace.go:171","msg":"trace[351629614] transaction","detail":"{read_only:false; response_revision:3596; number_of_response:1; }","duration":"369.3222ms","start":"2026-05-11T07:32:59.231154Z","end":"2026-05-11T07:32:59.600477Z","steps":["trace[351629614] 'process raft request' (duration: 369.073995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:32:59.600688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:32:59.231128Z","time spent":"369.460324ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:33:43.245706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.241775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wcdh2\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-11T07:33:43.245833Z","caller":"traceutil/trace.go:171","msg":"trace[2110774494] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wcdh2; range_end:; response_count:1; response_revision:3818; }","duration":"187.401128ms","start":"2026-05-11T07:33:43.058404Z","end":"2026-05-11T07:33:43.245806Z","steps":["trace[2110774494] 'range keys from in-memory index tree' (duration: 186.983189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:33:43.245814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.274706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T07:33:43.245946Z","caller":"traceutil/trace.go:171","msg":"trace[96569561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3818; }","duration":"264.496961ms","start":"2026-05-11T07:33:42.981413Z","end":"2026-05-11T07:33:43.24591Z","steps":["trace[96569561] 'range keys from in-memory index tree' (duration: 263.958939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:33:43.246093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.554725ms","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-05-11T07:33:43.246106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.962743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T07:33:43.246178Z","caller":"traceutil/trace.go:171","msg":"trace[2109634056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3818; }","duration":"267.719819ms","start":"2026-05-11T07:33:42.978438Z","end":"2026-05-11T07:33:43.246158Z","steps":["trace[2109634056] 'range keys from in-memory index tree' (duration: 267.29337ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:33:43.246211Z","caller":"traceutil/trace.go:171","msg":"trace[1392361564] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:3818; }","duration":"148.147217ms","start":"2026-05-11T07:33:43.098035Z","end":"2026-05-11T07:33:43.246182Z","steps":["trace[1392361564] 'count revisions from in-memory index tree' (duration: 147.801539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:34:12.704119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.583809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:34:12.704382Z","caller":"traceutil/trace.go:171","msg":"trace[1819529715] transaction","detail":"{read_only:false; response_revision:4266; number_of_response:1; }","duration":"204.216144ms","start":"2026-05-11T07:34:12.500144Z","end":"2026-05-11T07:34:12.70436Z","steps":["trace[1819529715] 'process raft request' (duration: 204.137363ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:34:12.704548Z","caller":"traceutil/trace.go:171","msg":"trace[476462956] transaction","detail":"{read_only:false; response_revision:4265; number_of_response:1; }","duration":"373.255933ms","start":"2026-05-11T07:34:12.33128Z","end":"2026-05-11T07:34:12.704536Z","steps":["trace[476462956] 'process raft request' (duration: 205.127873ms)","trace[476462956] 'compare' (duration: 167.424116ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:34:12.704615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:34:12.331256Z","time spent":"373.323515ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:34:12.704743Z","caller":"traceutil/trace.go:171","msg":"trace[295843483] linearizableReadLoop","detail":"{readStateIndex:4429; appliedIndex:4428; }","duration":"304.829425ms","start":"2026-05-11T07:34:12.399901Z","end":"2026-05-11T07:34:12.704731Z","steps":["trace[295843483] 'read index received' (duration: 136.529191ms)","trace[295843483] 'applied index is now lower than readState.Index' (duration: 168.298834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:34:12.70499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.09676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d6860b6-ab97-48ac-aed9-52e9ce9aa9e8\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-05-11T07:34:12.705029Z","caller":"traceutil/trace.go:171","msg":"trace[28893772] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d6860b6-ab97-48ac-aed9-52e9ce9aa9e8; range_end:; response_count:1; response_revision:4266; }","duration":"305.181032ms","start":"2026-05-11T07:34:12.399836Z","end":"2026-05-11T07:34:12.705017Z","steps":["trace[28893772] 'agreement among raft nodes before linearized reading' (duration: 305.039369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:34:12.705061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:34:12.399815Z","time spent":"305.238903ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3639,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d6860b6-ab97-48ac-aed9-52e9ce9aa9e8\" "} {"level":"info","ts":"2026-05-11T07:34:12.705526Z","caller":"traceutil/trace.go:171","msg":"trace[85787347] transaction","detail":"{read_only:false; response_revision:4267; number_of_response:1; }","duration":"153.820407ms","start":"2026-05-11T07:34:12.55167Z","end":"2026-05-11T07:34:12.70549Z","steps":["trace[85787347] 'process raft request' (duration: 153.503079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:34:12.705573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.380444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T07:34:12.705668Z","caller":"traceutil/trace.go:171","msg":"trace[1628044782] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4268; }","duration":"124.529907ms","start":"2026-05-11T07:34:12.581115Z","end":"2026-05-11T07:34:12.705645Z","steps":["trace[1628044782] 'agreement among raft nodes before linearized reading' (duration: 124.287112ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:36:21.159882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3111} {"level":"info","ts":"2026-05-11T07:36:21.274545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3111,"took":"113.202398ms","hash":2810790033,"current-db-size-bytes":41963520,"current-db-size":"42 MB","current-db-size-in-use-bytes":14446592,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-05-11T07:36:21.274887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2810790033,"revision":3111,"compact-revision":-1} {"level":"info","ts":"2026-05-11T07:41:21.165448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5318} {"level":"info","ts":"2026-05-11T07:41:21.225013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5318,"took":"57.159075ms","hash":4145265728,"current-db-size-bytes":41963520,"current-db-size":"42 MB","current-db-size-in-use-bytes":22274048,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-11T07:41:21.22508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4145265728,"revision":5318,"compact-revision":3111} {"level":"info","ts":"2026-05-11T07:42:08.458081Z","caller":"traceutil/trace.go:171","msg":"trace[1511861946] transaction","detail":"{read_only:false; response_revision:9338; number_of_response:1; }","duration":"100.429043ms","start":"2026-05-11T07:42:08.357615Z","end":"2026-05-11T07:42:08.458044Z","steps":["trace[1511861946] 'process raft request' (duration: 100.122787ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:42:08.479335Z","caller":"traceutil/trace.go:171","msg":"trace[1625045439] transaction","detail":"{read_only:false; response_revision:9339; number_of_response:1; }","duration":"109.644459ms","start":"2026-05-11T07:42:08.369661Z","end":"2026-05-11T07:42:08.479305Z","steps":["trace[1625045439] 'process raft request' (duration: 109.384073ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:42:25.342518Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000026f1.wal"} {"level":"info","ts":"2026-05-11T07:42:26.817531Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T07:42:26.83897Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-05-11T07:42:26.839101Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"warn","ts":"2026-05-11T07:43:49.538628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.95599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T07:43:49.539169Z","caller":"traceutil/trace.go:171","msg":"trace[1594008065] transaction","detail":"{read_only:false; response_revision:11359; number_of_response:1; }","duration":"385.296433ms","start":"2026-05-11T07:43:49.153829Z","end":"2026-05-11T07:43:49.539126Z","steps":["trace[1594008065] 'process raft request' (duration: 186.70644ms)","trace[1594008065] 'compare' (duration: 197.658264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:43:49.539393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:43:49.15381Z","time spent":"385.473817ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:43:49.539648Z","caller":"traceutil/trace.go:171","msg":"trace[2117758348] linearizableReadLoop","detail":"{readStateIndex:11710; appliedIndex:11709; }","duration":"384.923504ms","start":"2026-05-11T07:43:49.154669Z","end":"2026-05-11T07:43:49.539593Z","steps":["trace[2117758348] 'read index received' (duration: 185.939253ms)","trace[2117758348] 'applied index is now lower than readState.Index' (duration: 198.979241ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:43:49.539971Z","caller":"traceutil/trace.go:171","msg":"trace[1399644561] transaction","detail":"{read_only:false; response_revision:11361; number_of_response:1; }","duration":"341.633881ms","start":"2026-05-11T07:43:49.198312Z","end":"2026-05-11T07:43:49.539946Z","steps":["trace[1399644561] 'process raft request' (duration: 341.299744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.540099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:43:49.198292Z","time spent":"341.733164ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T07:43:49.540121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.385614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:1 size:6971"} {"level":"info","ts":"2026-05-11T07:43:49.540232Z","caller":"traceutil/trace.go:171","msg":"trace[1489848952] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:1; response_revision:11361; }","duration":"385.555977ms","start":"2026-05-11T07:43:49.154646Z","end":"2026-05-11T07:43:49.540202Z","steps":["trace[1489848952] 'agreement among raft nodes before linearized reading' (duration: 385.098497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.540302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:43:49.154636Z","time spent":"385.65501ms","remote":"127.0.0.1:49404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":6993,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"info","ts":"2026-05-11T07:43:49.539682Z","caller":"traceutil/trace.go:171","msg":"trace[394868805] transaction","detail":"{read_only:false; response_revision:11360; number_of_response:1; }","duration":"381.394939ms","start":"2026-05-11T07:43:49.158263Z","end":"2026-05-11T07:43:49.539658Z","steps":["trace[394868805] 'process raft request' (duration: 380.855158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.540599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:43:49.158249Z","time spent":"382.303428ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T07:43:49.542746Z","caller":"traceutil/trace.go:171","msg":"trace[240237391] transaction","detail":"{read_only:false; response_revision:11363; number_of_response:1; }","duration":"218.030877ms","start":"2026-05-11T07:43:49.324683Z","end":"2026-05-11T07:43:49.542714Z","steps":["trace[240237391] 'process raft request' (duration: 217.488976ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:43:49.542706Z","caller":"traceutil/trace.go:171","msg":"trace[1639384837] transaction","detail":"{read_only:false; response_revision:11362; number_of_response:1; }","duration":"264.937556ms","start":"2026-05-11T07:43:49.277719Z","end":"2026-05-11T07:43:49.542657Z","steps":["trace[1639384837] 'process raft request' (duration: 262.144387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.549344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.247058ms","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-05-11T07:43:49.54941Z","caller":"traceutil/trace.go:171","msg":"trace[878267331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11364; }","duration":"109.378913ms","start":"2026-05-11T07:43:49.44002Z","end":"2026-05-11T07:43:49.549399Z","steps":["trace[878267331] 'agreement among raft nodes before linearized reading' (duration: 109.203848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.549438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.277201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:10356"} {"level":"warn","ts":"2026-05-11T07:43:49.549436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.379047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-05-11T07:43:49.549529Z","caller":"traceutil/trace.go:171","msg":"trace[1812477347] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:11364; }","duration":"227.48833ms","start":"2026-05-11T07:43:49.322026Z","end":"2026-05-11T07:43:49.549514Z","steps":["trace[1812477347] 'agreement among raft nodes before linearized reading' (duration: 227.289525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.549565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.44437ms","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-05-11T07:43:49.5496Z","caller":"traceutil/trace.go:171","msg":"trace[1539957661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11364; }","duration":"365.503452ms","start":"2026-05-11T07:43:49.184083Z","end":"2026-05-11T07:43:49.549586Z","steps":["trace[1539957661] 'agreement among raft nodes before linearized reading' (duration: 365.243236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.549616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.359679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T07:43:49.549667Z","caller":"traceutil/trace.go:171","msg":"trace[553378642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11364; }","duration":"389.42899ms","start":"2026-05-11T07:43:49.160231Z","end":"2026-05-11T07:43:49.54966Z","steps":["trace[553378642] 'agreement among raft nodes before linearized reading' (duration: 388.99527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.549697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:43:49.160223Z","time spent":"389.467881ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T07:43:49.549764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.64956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fs7c5\" ","response":"range_response_count:1 size:20675"} {"level":"info","ts":"2026-05-11T07:43:49.549796Z","caller":"traceutil/trace.go:171","msg":"trace[1157941977] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fs7c5; range_end:; response_count:1; response_revision:11364; }","duration":"202.740202ms","start":"2026-05-11T07:43:49.347049Z","end":"2026-05-11T07:43:49.549789Z","steps":["trace[1157941977] 'agreement among raft nodes before linearized reading' (duration: 202.301933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:43:49.549632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T07:43:49.184066Z","time spent":"365.558322ms","remote":"127.0.0.1:49432","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-05-11T07:43:49.549493Z","caller":"traceutil/trace.go:171","msg":"trace[1102538949] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:11364; }","duration":"232.366853ms","start":"2026-05-11T07:43:49.317114Z","end":"2026-05-11T07:43:49.549481Z","steps":["trace[1102538949] 'agreement among raft nodes before linearized reading' (duration: 232.188379ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:43:49.549901Z","caller":"traceutil/trace.go:171","msg":"trace[514887628] transaction","detail":"{read_only:false; response_revision:11364; number_of_response:1; }","duration":"113.884218ms","start":"2026-05-11T07:43:49.436006Z","end":"2026-05-11T07:43:49.54989Z","steps":["trace[514887628] 'process raft request' (duration: 112.993468ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:46:01.138343Z","caller":"traceutil/trace.go:171","msg":"trace[1202006974] transaction","detail":"{read_only:false; response_revision:13889; number_of_response:1; }","duration":"114.418878ms","start":"2026-05-11T07:46:01.023874Z","end":"2026-05-11T07:46:01.138292Z","steps":["trace[1202006974] 'process raft request' (duration: 113.965597ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:46:21.171945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8393} {"level":"info","ts":"2026-05-11T07:46:21.293493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8393,"took":"118.107476ms","hash":3887690652,"current-db-size-bytes":71012352,"current-db-size":"71 MB","current-db-size-in-use-bytes":63635456,"current-db-size-in-use":"64 MB"} {"level":"info","ts":"2026-05-11T07:46:21.293591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3887690652,"revision":8393,"compact-revision":5318} {"level":"info","ts":"2026-05-11T07:51:21.179982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14250} {"level":"info","ts":"2026-05-11T07:51:21.369225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14250,"took":"182.607141ms","hash":3946053758,"current-db-size-bytes":75849728,"current-db-size":"76 MB","current-db-size-in-use-bytes":37351424,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-11T07:51:21.369302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3946053758,"revision":14250,"compact-revision":8393} {"level":"info","ts":"2026-05-11T07:52:07.773479Z","caller":"traceutil/trace.go:171","msg":"trace[1862411019] transaction","detail":"{read_only:false; response_revision:18248; number_of_response:1; }","duration":"199.973983ms","start":"2026-05-11T07:52:07.573441Z","end":"2026-05-11T07:52:07.773415Z","steps":["trace[1862411019] 'process raft request' (duration: 199.594615ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:52:07.774046Z","caller":"traceutil/trace.go:171","msg":"trace[400660801] transaction","detail":"{read_only:false; response_revision:18249; number_of_response:1; }","duration":"189.928798ms","start":"2026-05-11T07:52:07.584103Z","end":"2026-05-11T07:52:07.774031Z","steps":["trace[400660801] 'process raft request' (duration: 189.202452ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:52:07.774336Z","caller":"traceutil/trace.go:171","msg":"trace[687593410] linearizableReadLoop","detail":"{readStateIndex:18760; appliedIndex:18758; }","duration":"143.333155ms","start":"2026-05-11T07:52:07.630987Z","end":"2026-05-11T07:52:07.77432Z","steps":["trace[687593410] 'read index received' (duration: 142.13888ms)","trace[687593410] 'applied index is now lower than readState.Index' (duration: 1.192615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T07:52:07.774598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.58932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-octavia-default-user\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-05-11T07:52:07.774643Z","caller":"traceutil/trace.go:171","msg":"trace[731810014] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-octavia-default-user; range_end:; response_count:1; response_revision:18249; }","duration":"143.686012ms","start":"2026-05-11T07:52:07.630945Z","end":"2026-05-11T07:52:07.774631Z","steps":["trace[731810014] 'agreement among raft nodes before linearized reading' (duration: 143.492348ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:52:07.776867Z","caller":"traceutil/trace.go:171","msg":"trace[2064284386] transaction","detail":"{read_only:false; response_revision:18250; number_of_response:1; }","duration":"137.47849ms","start":"2026-05-11T07:52:07.639366Z","end":"2026-05-11T07:52:07.776845Z","steps":["trace[2064284386] 'process raft request' (duration: 137.210444ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:52:07.776932Z","caller":"traceutil/trace.go:171","msg":"trace[1246112654] transaction","detail":"{read_only:false; response_revision:18251; number_of_response:1; }","duration":"124.690247ms","start":"2026-05-11T07:52:07.652189Z","end":"2026-05-11T07:52:07.776879Z","steps":["trace[1246112654] 'process raft request' (duration: 124.546734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:52:07.777246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.168302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cmpmz\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-05-11T07:52:07.777348Z","caller":"traceutil/trace.go:171","msg":"trace[1040173938] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cmpmz; range_end:; response_count:1; response_revision:18253; }","duration":"144.325735ms","start":"2026-05-11T07:52:07.633001Z","end":"2026-05-11T07:52:07.777326Z","steps":["trace[1040173938] 'agreement among raft nodes before linearized reading' (duration: 144.037279ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:44.353085Z","caller":"traceutil/trace.go:171","msg":"trace[176258480] transaction","detail":"{read_only:false; response_revision:19220; number_of_response:1; }","duration":"136.302734ms","start":"2026-05-11T07:53:44.216761Z","end":"2026-05-11T07:53:44.353063Z","steps":["trace[176258480] 'process raft request' (duration: 136.245552ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:44.353693Z","caller":"traceutil/trace.go:171","msg":"trace[1971154008] transaction","detail":"{read_only:false; response_revision:19218; number_of_response:1; }","duration":"138.738707ms","start":"2026-05-11T07:53:44.214944Z","end":"2026-05-11T07:53:44.353683Z","steps":["trace[1971154008] 'process raft request' (duration: 137.9329ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:44.353845Z","caller":"traceutil/trace.go:171","msg":"trace[1313194284] transaction","detail":"{read_only:false; response_revision:19219; number_of_response:1; }","duration":"137.944769ms","start":"2026-05-11T07:53:44.215893Z","end":"2026-05-11T07:53:44.353838Z","steps":["trace[1313194284] 'process raft request' (duration: 137.07269ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:44.353222Z","caller":"traceutil/trace.go:171","msg":"trace[473599190] transaction","detail":"{read_only:false; response_revision:19217; number_of_response:1; }","duration":"138.321317ms","start":"2026-05-11T07:53:44.214873Z","end":"2026-05-11T07:53:44.353194Z","steps":["trace[473599190] 'process raft request' (duration: 111.293639ms)","trace[473599190] 'compare' (duration: 26.401694ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:53:51.554292Z","caller":"traceutil/trace.go:171","msg":"trace[626202584] transaction","detail":"{read_only:false; response_revision:19304; number_of_response:1; }","duration":"199.654477ms","start":"2026-05-11T07:53:51.35461Z","end":"2026-05-11T07:53:51.554264Z","steps":["trace[626202584] 'process raft request' (duration: 199.464284ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:52.530749Z","caller":"traceutil/trace.go:171","msg":"trace[1193427208] transaction","detail":"{read_only:false; response_revision:19309; number_of_response:1; }","duration":"130.884568ms","start":"2026-05-11T07:53:52.39983Z","end":"2026-05-11T07:53:52.530714Z","steps":["trace[1193427208] 'process raft request' (duration: 130.661313ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:52.61422Z","caller":"traceutil/trace.go:171","msg":"trace[735021060] transaction","detail":"{read_only:false; response_revision:19310; number_of_response:1; }","duration":"210.507381ms","start":"2026-05-11T07:53:52.403681Z","end":"2026-05-11T07:53:52.614188Z","steps":["trace[735021060] 'process raft request' (duration: 203.846128ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:52.614689Z","caller":"traceutil/trace.go:171","msg":"trace[882284959] transaction","detail":"{read_only:false; response_revision:19311; number_of_response:1; }","duration":"209.456988ms","start":"2026-05-11T07:53:52.405209Z","end":"2026-05-11T07:53:52.614666Z","steps":["trace[882284959] 'process raft request' (duration: 208.931457ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:52.614758Z","caller":"traceutil/trace.go:171","msg":"trace[939354708] transaction","detail":"{read_only:false; response_revision:19312; number_of_response:1; }","duration":"209.135381ms","start":"2026-05-11T07:53:52.405592Z","end":"2026-05-11T07:53:52.614728Z","steps":["trace[939354708] 'process raft request' (duration: 208.914126ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:53:52.614826Z","caller":"traceutil/trace.go:171","msg":"trace[1869725746] linearizableReadLoop","detail":"{readStateIndex:19848; appliedIndex:19843; }","duration":"147.465973ms","start":"2026-05-11T07:53:52.467339Z","end":"2026-05-11T07:53:52.614805Z","steps":["trace[1869725746] 'read index received' (duration: 63.038568ms)","trace[1869725746] 'applied index is now lower than readState.Index' (duration: 84.426115ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:53:52.614922Z","caller":"traceutil/trace.go:171","msg":"trace[172844093] transaction","detail":"{read_only:false; response_revision:19313; number_of_response:1; }","duration":"208.934257ms","start":"2026-05-11T07:53:52.405924Z","end":"2026-05-11T07:53:52.614858Z","steps":["trace[172844093] 'process raft request' (duration: 208.730433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T07:53:52.61512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.77352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-05-11T07:53:52.615185Z","caller":"traceutil/trace.go:171","msg":"trace[225804028] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:19313; }","duration":"147.903103ms","start":"2026-05-11T07:53:52.46727Z","end":"2026-05-11T07:53:52.615173Z","steps":["trace[225804028] 'agreement among raft nodes before linearized reading' (duration: 147.666868ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T07:54:09.752942Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T07:54:09.758455Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-05-11T07:54:09.758566Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-05-11T07:54:34.966717Z","caller":"traceutil/trace.go:171","msg":"trace[941934553] transaction","detail":"{read_only:false; response_revision:19675; number_of_response:1; }","duration":"101.53517ms","start":"2026-05-11T07:54:34.865161Z","end":"2026-05-11T07:54:34.966696Z","steps":["trace[941934553] 'process raft request' (duration: 86.809956ms)","trace[941934553] 'compare' (duration: 14.191923ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T07:56:21.185356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17548} {"level":"info","ts":"2026-05-11T07:56:21.329956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17548,"took":"139.956628ms","hash":3358726626,"current-db-size-bytes":75849728,"current-db-size":"76 MB","current-db-size-in-use-bytes":38522880,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-11T07:56:21.330026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3358726626,"revision":17548,"compact-revision":14250} {"level":"info","ts":"2026-05-11T08:01:21.200366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20884} {"level":"info","ts":"2026-05-11T08:01:21.34694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20884,"took":"142.095598ms","hash":1306462,"current-db-size-bytes":75849728,"current-db-size":"76 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T08:01:21.347015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1306462,"revision":20884,"compact-revision":17548} {"level":"info","ts":"2026-05-11T08:06:21.206532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23373} {"level":"info","ts":"2026-05-11T08:06:21.345062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23373,"took":"132.926973ms","hash":3082572035,"current-db-size-bytes":75849728,"current-db-size":"76 MB","current-db-size-in-use-bytes":32313344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T08:06:21.345133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3082572035,"revision":23373,"compact-revision":20884} {"level":"info","ts":"2026-05-11T08:09:28.311576Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c74.wal"}