{"level":"warn","ts":"2026-10-06T10:04:58.266963Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T10:04:58.267046Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.223:2380","--initial-cluster=instance=https://162.253.55.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-06T10:04:58.267106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T10:04:58.267114Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-10-06T10:04:58.267247Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-06T10:04:58.267819Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"]} {"level":"info","ts":"2026-10-06T10:04:58.267902Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-06T10:04:58.281487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.388578ms"} {"level":"info","ts":"2026-10-06T10:04:58.314415Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0f50f8a6c5f7d06","cluster-id":"62f129f77cf8e487"} {"level":"info","ts":"2026-10-06T10:04:58.314559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T10:04:58.314607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 0"} {"level":"info","ts":"2026-10-06T10:04:58.314644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0f50f8a6c5f7d06 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T10:04:58.314676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 1"} {"level":"info","ts":"2026-10-06T10:04:58.314754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"warn","ts":"2026-10-06T10:04:58.32349Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T10:04:58.325543Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T10:04:58.327041Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-06T10:04:58.33028Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0f50f8a6c5f7d06","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T10:04:58.330622Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0f50f8a6c5f7d06","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T10:04:58.331577Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-06T10:04:58.331799Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-06T10:04:58.331825Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-06T10:04:58.332164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"info","ts":"2026-10-06T10:04:58.333073Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","added-peer-id":"a0f50f8a6c5f7d06","added-peer-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-10-06T10:04:58.336055Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-06T10:04:58.336203Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-10-06T10:04:58.336231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-10-06T10:04:58.336488Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0f50f8a6c5f7d06","initial-advertise-peer-urls":["https://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T10:04:58.336535Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T10:04:58.615449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T10:04:58.615544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T10:04:58.615591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgPreVoteResp from a0f50f8a6c5f7d06 at term 1"} {"level":"info","ts":"2026-10-06T10:04:58.615619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became candidate at term 2"} {"level":"info","ts":"2026-10-06T10:04:58.615634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgVoteResp from a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-10-06T10:04:58.615655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became leader at term 2"} {"level":"info","ts":"2026-10-06T10:04:58.615674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0f50f8a6c5f7d06 elected leader a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-10-06T10:04:58.836017Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:58.836352Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0f50f8a6c5f7d06","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.223:2379]}","request-path":"/0/members/a0f50f8a6c5f7d06/attributes","cluster-id":"62f129f77cf8e487","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T10:04:58.836491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T10:04:58.836529Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T10:04:58.836913Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T10:04:58.836985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T10:04:58.83734Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:58.837469Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:58.837513Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T10:04:58.83901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.223:2379"} {"level":"info","ts":"2026-10-06T10:04:58.840612Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-06T10:05:10.354521Z","caller":"traceutil/trace.go:171","msg":"trace[1201972563] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"119.903634ms","start":"2026-10-06T10:05:10.234584Z","end":"2026-10-06T10:05:10.354488Z","steps":["trace[1201972563] 'process raft request' (duration: 119.732491ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:10.669347Z","caller":"traceutil/trace.go:171","msg":"trace[1925675382] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"123.606095ms","start":"2026-10-06T10:05:10.545716Z","end":"2026-10-06T10:05:10.669322Z","steps":["trace[1925675382] 'process raft request' (duration: 123.37233ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:10.818679Z","caller":"traceutil/trace.go:171","msg":"trace[1044013675] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"144.788532ms","start":"2026-10-06T10:05:10.673864Z","end":"2026-10-06T10:05:10.818653Z","steps":["trace[1044013675] 'process raft request' (duration: 144.637849ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:10.818662Z","caller":"traceutil/trace.go:171","msg":"trace[1097450672] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"144.875133ms","start":"2026-10-06T10:05:10.673757Z","end":"2026-10-06T10:05:10.818632Z","steps":["trace[1097450672] 'process raft request' (duration: 66.12685ms)","trace[1097450672] 'compare' (duration: 78.457568ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:35.495425Z","caller":"traceutil/trace.go:171","msg":"trace[163189132] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"135.018044ms","start":"2026-10-06T10:05:35.360384Z","end":"2026-10-06T10:05:35.495402Z","steps":["trace[163189132] 'process raft request' (duration: 134.863232ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.511978Z","caller":"traceutil/trace.go:171","msg":"trace[159602537] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"151.45545ms","start":"2026-10-06T10:05:35.360486Z","end":"2026-10-06T10:05:35.511941Z","steps":["trace[159602537] 'process raft request' (duration: 151.186215ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512069Z","caller":"traceutil/trace.go:171","msg":"trace[915282971] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"146.19765ms","start":"2026-10-06T10:05:35.365854Z","end":"2026-10-06T10:05:35.512052Z","steps":["trace[915282971] 'process raft request' (duration: 145.966415ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512077Z","caller":"traceutil/trace.go:171","msg":"trace[117011452] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"146.272131ms","start":"2026-10-06T10:05:35.365746Z","end":"2026-10-06T10:05:35.512018Z","steps":["trace[117011452] 'process raft request' (duration: 146.039456ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512329Z","caller":"traceutil/trace.go:171","msg":"trace[1486323803] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"133.120848ms","start":"2026-10-06T10:05:35.379195Z","end":"2026-10-06T10:05:35.512316Z","steps":["trace[1486323803] 'process raft request' (duration: 132.812163ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512358Z","caller":"traceutil/trace.go:171","msg":"trace[771713033] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"126.178264ms","start":"2026-10-06T10:05:35.386162Z","end":"2026-10-06T10:05:35.51234Z","steps":["trace[771713033] 'process raft request' (duration: 125.882209ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512423Z","caller":"traceutil/trace.go:171","msg":"trace[461222042] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"126.090143ms","start":"2026-10-06T10:05:35.386324Z","end":"2026-10-06T10:05:35.512414Z","steps":["trace[461222042] 'process raft request' (duration: 125.888719ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512471Z","caller":"traceutil/trace.go:171","msg":"trace[210663635] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"121.037706ms","start":"2026-10-06T10:05:35.391424Z","end":"2026-10-06T10:05:35.512462Z","steps":["trace[210663635] 'process raft request' (duration: 120.842112ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512649Z","caller":"traceutil/trace.go:171","msg":"trace[1774915602] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"107.972785ms","start":"2026-10-06T10:05:35.404645Z","end":"2026-10-06T10:05:35.512618Z","steps":["trace[1774915602] 'process raft request' (duration: 107.685659ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:35.512686Z","caller":"traceutil/trace.go:171","msg":"trace[1855942369] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"100.601533ms","start":"2026-10-06T10:05:35.412048Z","end":"2026-10-06T10:05:35.512649Z","steps":["trace[1855942369] 'process raft request' (duration: 100.382209ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:42.262665Z","caller":"traceutil/trace.go:171","msg":"trace[47526259] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"182.40984ms","start":"2026-10-06T10:05:42.080195Z","end":"2026-10-06T10:05:42.262605Z","steps":["trace[47526259] 'read index received' (duration: 180.897532ms)","trace[47526259] 'applied index is now lower than readState.Index' (duration: 1.511128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:05:42.262987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.762557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-06T10:05:42.263115Z","caller":"traceutil/trace.go:171","msg":"trace[759048225] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"204.274995ms","start":"2026-10-06T10:05:42.058758Z","end":"2026-10-06T10:05:42.263033Z","steps":["trace[759048225] 'process raft request' (duration: 202.798017ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:42.263176Z","caller":"traceutil/trace.go:171","msg":"trace[1928516964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"183.023962ms","start":"2026-10-06T10:05:42.080133Z","end":"2026-10-06T10:05:42.263157Z","steps":["trace[1928516964] 'agreement among raft nodes before linearized reading' (duration: 182.649115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:43.062457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.797978ms","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-10-06T10:05:43.062655Z","caller":"traceutil/trace.go:171","msg":"trace[33256478] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:635; }","duration":"192.006592ms","start":"2026-10-06T10:05:42.870626Z","end":"2026-10-06T10:05:43.062633Z","steps":["trace[33256478] 'range keys from in-memory index tree' (duration: 191.780137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:43.063981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.668136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:05:43.06418Z","caller":"traceutil/trace.go:171","msg":"trace[1796336423] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"737.782874ms","start":"2026-10-06T10:05:42.326373Z","end":"2026-10-06T10:05:43.064156Z","steps":["trace[1796336423] 'process raft request' (duration: 149.730151ms)","trace[1796336423] 'compare' (duration: 586.174018ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:05:43.064286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:42.326352Z","time spent":"737.892026ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:05:43.064275Z","caller":"traceutil/trace.go:171","msg":"trace[1969152964] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"706.234855ms","start":"2026-10-06T10:05:42.358026Z","end":"2026-10-06T10:05:43.064261Z","steps":["trace[1969152964] 'read index received' (duration: 118.08516ms)","trace[1969152964] 'applied index is now lower than readState.Index' (duration: 588.148315ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:43.064436Z","caller":"traceutil/trace.go:171","msg":"trace[378693934] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"723.249288ms","start":"2026-10-06T10:05:42.341137Z","end":"2026-10-06T10:05:43.064386Z","steps":["trace[378693934] 'process raft request' (duration: 722.959702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:43.064581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.52777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-10-06T10:05:43.064632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:42.341116Z","time spent":"723.426951ms","remote":"127.0.0.1:33202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-06T10:05:43.064668Z","caller":"traceutil/trace.go:171","msg":"trace[1182927526] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:637; }","duration":"706.682844ms","start":"2026-10-06T10:05:42.357964Z","end":"2026-10-06T10:05:43.064647Z","steps":["trace[1182927526] 'agreement among raft nodes before linearized reading' (duration: 706.470569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:43.064724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:05:42.35794Z","time spent":"706.768596ms","remote":"127.0.0.1:32870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-06T10:05:58.575529Z","caller":"traceutil/trace.go:171","msg":"trace[614614602] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"115.008071ms","start":"2026-10-06T10:05:58.460492Z","end":"2026-10-06T10:05:58.5755Z","steps":["trace[614614602] 'process raft request' (duration: 114.836678ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:28.987375Z","caller":"traceutil/trace.go:171","msg":"trace[1013409825] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"138.519817ms","start":"2026-10-06T10:06:28.848796Z","end":"2026-10-06T10:06:28.987316Z","steps":["trace[1013409825] 'process raft request' (duration: 138.341774ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:20.78398Z","caller":"traceutil/trace.go:171","msg":"trace[83087327] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"286.961704ms","start":"2026-10-06T10:07:20.496984Z","end":"2026-10-06T10:07:20.783945Z","steps":["trace[83087327] 'process raft request' (duration: 254.983363ms)","trace[83087327] 'compare' (duration: 31.821028ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:07:21.132192Z","caller":"traceutil/trace.go:171","msg":"trace[1701113500] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"249.135594ms","start":"2026-10-06T10:07:20.883009Z","end":"2026-10-06T10:07:21.132144Z","steps":["trace[1701113500] 'process raft request' (duration: 248.750737ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:33.72377Z","caller":"traceutil/trace.go:171","msg":"trace[868659997] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"105.338681ms","start":"2026-10-06T10:07:33.6184Z","end":"2026-10-06T10:07:33.723738Z","steps":["trace[868659997] 'process raft request' (duration: 105.126217ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:34.230868Z","caller":"traceutil/trace.go:171","msg":"trace[1622636024] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"141.649623ms","start":"2026-10-06T10:07:34.089198Z","end":"2026-10-06T10:07:34.230848Z","steps":["trace[1622636024] 'process raft request' (duration: 141.53269ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:34.235039Z","caller":"traceutil/trace.go:171","msg":"trace[1051997426] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"144.658939ms","start":"2026-10-06T10:07:34.090337Z","end":"2026-10-06T10:07:34.234996Z","steps":["trace[1051997426] 'process raft request' (duration: 144.525746ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:12.259217Z","caller":"traceutil/trace.go:171","msg":"trace[419017130] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"162.864519ms","start":"2026-10-06T10:08:12.096316Z","end":"2026-10-06T10:08:12.259181Z","steps":["trace[419017130] 'process raft request' (duration: 162.594184ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:12.259482Z","caller":"traceutil/trace.go:171","msg":"trace[1229764150] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"163.094484ms","start":"2026-10-06T10:08:12.096335Z","end":"2026-10-06T10:08:12.259429Z","steps":["trace[1229764150] 'process raft request' (duration: 162.795608ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:09:07.972578Z","caller":"traceutil/trace.go:171","msg":"trace[1560530381] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"119.123786ms","start":"2026-10-06T10:09:07.853415Z","end":"2026-10-06T10:09:07.972538Z","steps":["trace[1560530381] 'process raft request' (duration: 118.661998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:09:40.968682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.921879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:09:40.968841Z","caller":"traceutil/trace.go:171","msg":"trace[1584502908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2893; }","duration":"162.146503ms","start":"2026-10-06T10:09:40.806669Z","end":"2026-10-06T10:09:40.968815Z","steps":["trace[1584502908] 'range keys from in-memory index tree' (duration: 161.818017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:09:41.438591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.865049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-06T10:09:41.438742Z","caller":"traceutil/trace.go:171","msg":"trace[1424524404] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2894; }","duration":"127.079733ms","start":"2026-10-06T10:09:41.311626Z","end":"2026-10-06T10:09:41.438706Z","steps":["trace[1424524404] 'range keys from in-memory index tree' (duration: 126.602224ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:09:52.429886Z","caller":"traceutil/trace.go:171","msg":"trace[1443494433] linearizableReadLoop","detail":"{readStateIndex:3068; appliedIndex:3067; }","duration":"135.068269ms","start":"2026-10-06T10:09:52.294787Z","end":"2026-10-06T10:09:52.429855Z","steps":["trace[1443494433] 'read index received' (duration: 62.3528ms)","trace[1443494433] 'applied index is now lower than readState.Index' (duration: 72.713439ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:09:52.43006Z","caller":"traceutil/trace.go:171","msg":"trace[2056070244] transaction","detail":"{read_only:false; response_revision:2954; number_of_response:1; }","duration":"137.023875ms","start":"2026-10-06T10:09:52.293018Z","end":"2026-10-06T10:09:52.430042Z","steps":["trace[2056070244] 'process raft request' (duration: 64.194183ms)","trace[2056070244] 'compare' (duration: 72.482525ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:09:52.430364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.340334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-06T10:09:52.430471Z","caller":"traceutil/trace.go:171","msg":"trace[481040591] range","detail":"{range_begin:/registry/masterleases/162.253.55.223; range_end:; response_count:1; response_revision:2954; }","duration":"135.488227ms","start":"2026-10-06T10:09:52.294962Z","end":"2026-10-06T10:09:52.43045Z","steps":["trace[481040591] 'agreement among raft nodes before linearized reading' (duration: 135.254673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:09:52.430782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.969485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-06T10:09:52.430847Z","caller":"traceutil/trace.go:171","msg":"trace[1162199939] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2954; }","duration":"136.109848ms","start":"2026-10-06T10:09:52.294721Z","end":"2026-10-06T10:09:52.430831Z","steps":["trace[1162199939] 'agreement among raft nodes before linearized reading' (duration: 135.831063ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:13:09.496429Z","caller":"traceutil/trace.go:171","msg":"trace[1749593289] transaction","detail":"{read_only:false; response_revision:4185; number_of_response:1; }","duration":"108.402873ms","start":"2026-10-06T10:13:09.387993Z","end":"2026-10-06T10:13:09.496396Z","steps":["trace[1749593289] 'process raft request' (duration: 108.169209ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:13:09.621149Z","caller":"traceutil/trace.go:171","msg":"trace[453919741] linearizableReadLoop","detail":"{readStateIndex:4347; appliedIndex:4346; }","duration":"101.305881ms","start":"2026-10-06T10:13:09.519802Z","end":"2026-10-06T10:13:09.621108Z","steps":["trace[453919741] 'read index received' (duration: 52.161299ms)","trace[453919741] 'applied index is now lower than readState.Index' (duration: 49.143222ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:13:09.621263Z","caller":"traceutil/trace.go:171","msg":"trace[1300810073] transaction","detail":"{read_only:false; response_revision:4186; number_of_response:1; }","duration":"231.594317ms","start":"2026-10-06T10:13:09.389603Z","end":"2026-10-06T10:13:09.621197Z","steps":["trace[1300810073] 'process raft request' (duration: 182.369043ms)","trace[1300810073] 'compare' (duration: 48.946768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:13:09.621648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.815591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-10-06T10:13:09.621712Z","caller":"traceutil/trace.go:171","msg":"trace[736814943] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:4186; }","duration":"101.952013ms","start":"2026-10-06T10:13:09.519741Z","end":"2026-10-06T10:13:09.621693Z","steps":["trace[736814943] 'agreement among raft nodes before linearized reading' (duration: 101.604726ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:43.861033Z","caller":"traceutil/trace.go:171","msg":"trace[327753513] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"157.660749ms","start":"2026-10-06T10:14:43.703334Z","end":"2026-10-06T10:14:43.860995Z","steps":["trace[327753513] 'process raft request' (duration: 67.613349ms)","trace[327753513] 'compare' (duration: 89.905247ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:14:44.053221Z","caller":"traceutil/trace.go:171","msg":"trace[2100147305] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"173.343132ms","start":"2026-10-06T10:14:43.879821Z","end":"2026-10-06T10:14:44.053165Z","steps":["trace[2100147305] 'process raft request' (duration: 129.667333ms)","trace[2100147305] 'compare' (duration: 43.392745ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:14:58.872484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-10-06T10:14:58.959156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"85.122428ms","hash":1343851338,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":30867456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-06T10:14:58.959232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1343851338,"revision":2988,"compact-revision":-1} {"level":"warn","ts":"2026-10-06T10:15:02.633619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.183533ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-06T10:15:02.633797Z","caller":"traceutil/trace.go:171","msg":"trace[915333397] linearizableReadLoop","detail":"{readStateIndex:5912; appliedIndex:5911; }","duration":"321.124066ms","start":"2026-10-06T10:15:02.312646Z","end":"2026-10-06T10:15:02.63377Z","steps":["trace[915333397] 'read index received' (duration: 199.688588ms)","trace[915333397] 'applied index is now lower than readState.Index' (duration: 121.432798ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:15:02.633905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:15:02.310497Z","time spent":"323.399598ms","remote":"127.0.0.1:60968","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-06T10:15:02.634099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.308552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-txb56\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-06T10:15:02.634162Z","caller":"traceutil/trace.go:171","msg":"trace[2043195496] range","detail":"{range_begin:/registry/pods/openstack/keepalived-txb56; range_end:; response_count:1; response_revision:5695; }","duration":"237.425265ms","start":"2026-10-06T10:15:02.396721Z","end":"2026-10-06T10:15:02.634146Z","steps":["trace[2043195496] 'agreement among raft nodes before linearized reading' (duration: 237.222051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:15:02.634234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.574634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-06T10:15:02.634349Z","caller":"traceutil/trace.go:171","msg":"trace[326345865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5695; }","duration":"321.715016ms","start":"2026-10-06T10:15:02.312614Z","end":"2026-10-06T10:15:02.634329Z","steps":["trace[326345865] 'agreement among raft nodes before linearized reading' (duration: 321.342729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:15:02.634354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.852154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-10-06T10:15:02.634403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:15:02.312601Z","time spent":"321.787358ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-06T10:15:02.634484Z","caller":"traceutil/trace.go:171","msg":"trace[649739093] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5695; }","duration":"148.050858ms","start":"2026-10-06T10:15:02.486406Z","end":"2026-10-06T10:15:02.634457Z","steps":["trace[649739093] 'agreement among raft nodes before linearized reading' (duration: 147.576149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:15:02.634272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.029033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-06T10:15:02.634784Z","caller":"traceutil/trace.go:171","msg":"trace[1443735170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5695; }","duration":"208.535442ms","start":"2026-10-06T10:15:02.426146Z","end":"2026-10-06T10:15:02.634681Z","steps":["trace[1443735170] 'agreement among raft nodes before linearized reading' (duration: 207.870009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:15:16.505367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.06813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:15:16.505529Z","caller":"traceutil/trace.go:171","msg":"trace[420570266] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5956; }","duration":"114.257443ms","start":"2026-10-06T10:15:16.391243Z","end":"2026-10-06T10:15:16.5055Z","steps":["trace[420570266] 'count revisions from in-memory index tree' (duration: 113.904426ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:15:28.04907Z","caller":"traceutil/trace.go:171","msg":"trace[528236764] linearizableReadLoop","detail":"{readStateIndex:6267; appliedIndex:6266; }","duration":"137.003982ms","start":"2026-10-06T10:15:27.912032Z","end":"2026-10-06T10:15:28.049036Z","steps":["trace[528236764] 'read index received' (duration: 136.792658ms)","trace[528236764] 'applied index is now lower than readState.Index' (duration: 210.184µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:15:28.049175Z","caller":"traceutil/trace.go:171","msg":"trace[2123791435] transaction","detail":"{read_only:false; response_revision:6042; number_of_response:1; }","duration":"152.315823ms","start":"2026-10-06T10:15:27.896819Z","end":"2026-10-06T10:15:28.049135Z","steps":["trace[2123791435] 'process raft request' (duration: 152.018978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:15:28.049256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.195647ms","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:7"} {"level":"info","ts":"2026-10-06T10:15:28.049316Z","caller":"traceutil/trace.go:171","msg":"trace[1188551785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6042; }","duration":"137.312229ms","start":"2026-10-06T10:15:27.911988Z","end":"2026-10-06T10:15:28.0493Z","steps":["trace[1188551785] 'agreement among raft nodes before linearized reading' (duration: 137.172856ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:15:28.267638Z","caller":"traceutil/trace.go:171","msg":"trace[1888278584] transaction","detail":"{read_only:false; response_revision:6052; number_of_response:1; }","duration":"110.588403ms","start":"2026-10-06T10:15:28.157012Z","end":"2026-10-06T10:15:28.267601Z","steps":["trace[1888278584] 'process raft request' (duration: 110.335298ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:19:58.880617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5637} {"level":"info","ts":"2026-10-06T10:19:58.967661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5637,"took":"85.253525ms","hash":448907689,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":28823552,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-06T10:19:58.967743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":448907689,"revision":5637,"compact-revision":2988} {"level":"warn","ts":"2026-10-06T10:21:35.335757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.657923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-txb56\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-06T10:21:35.335826Z","caller":"traceutil/trace.go:171","msg":"trace[1303645016] range","detail":"{range_begin:/registry/pods/openstack/keepalived-txb56; range_end:; response_count:1; response_revision:8780; }","duration":"186.776375ms","start":"2026-10-06T10:21:35.149033Z","end":"2026-10-06T10:21:35.33581Z","steps":["trace[1303645016] 'range keys from in-memory index tree' (duration: 186.53036ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:47.722877Z","caller":"traceutil/trace.go:171","msg":"trace[842356787] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"143.136629ms","start":"2026-10-06T10:21:47.579713Z","end":"2026-10-06T10:21:47.72285Z","steps":["trace[842356787] 'process raft request' (duration: 142.954315ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:49.253549Z","caller":"traceutil/trace.go:171","msg":"trace[1779816420] linearizableReadLoop","detail":"{readStateIndex:9166; appliedIndex:9164; }","duration":"180.89041ms","start":"2026-10-06T10:21:49.072635Z","end":"2026-10-06T10:21:49.253525Z","steps":["trace[1779816420] 'read index received' (duration: 79.948557ms)","trace[1779816420] 'applied index is now lower than readState.Index' (duration: 100.940843ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:21:49.253654Z","caller":"traceutil/trace.go:171","msg":"trace[2054413172] transaction","detail":"{read_only:false; response_revision:8850; number_of_response:1; }","duration":"184.730984ms","start":"2026-10-06T10:21:49.068853Z","end":"2026-10-06T10:21:49.253584Z","steps":["trace[2054413172] 'process raft request' (duration: 159.123991ms)","trace[2054413172] 'compare' (duration: 25.420909ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:21:49.253791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.133325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-06T10:21:49.253844Z","caller":"traceutil/trace.go:171","msg":"trace[1904348384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8850; }","duration":"181.244237ms","start":"2026-10-06T10:21:49.072585Z","end":"2026-10-06T10:21:49.253829Z","steps":["trace[1904348384] 'agreement among raft nodes before linearized reading' (duration: 181.086324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:21:49.253856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.271959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-06T10:21:49.253856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.569819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-06T10:21:49.253918Z","caller":"traceutil/trace.go:171","msg":"trace[1704173933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8850; }","duration":"110.374762ms","start":"2026-10-06T10:21:49.143526Z","end":"2026-10-06T10:21:49.253901Z","steps":["trace[1704173933] 'agreement among raft nodes before linearized reading' (duration: 110.214389ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:21:49.253962Z","caller":"traceutil/trace.go:171","msg":"trace[1089155801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8850; }","duration":"175.658632ms","start":"2026-10-06T10:21:49.07825Z","end":"2026-10-06T10:21:49.253909Z","steps":["trace[1089155801] 'agreement among raft nodes before linearized reading' (duration: 175.492839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:22:16.664015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.407831ms","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-10-06T10:22:16.664116Z","caller":"traceutil/trace.go:171","msg":"trace[1042789229] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:9055; }","duration":"242.556423ms","start":"2026-10-06T10:22:16.421537Z","end":"2026-10-06T10:22:16.664094Z","steps":["trace[1042789229] 'count revisions from in-memory index tree' (duration: 242.325649ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:22:19.932649Z","caller":"traceutil/trace.go:171","msg":"trace[123071567] transaction","detail":"{read_only:false; response_revision:9071; number_of_response:1; }","duration":"159.243392ms","start":"2026-10-06T10:22:19.773347Z","end":"2026-10-06T10:22:19.932591Z","steps":["trace[123071567] 'process raft request' (duration: 159.070959ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:22:27.557417Z","caller":"traceutil/trace.go:171","msg":"trace[1822150079] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"118.632777ms","start":"2026-10-06T10:22:27.438754Z","end":"2026-10-06T10:22:27.557386Z","steps":["trace[1822150079] 'process raft request' (duration: 100.582347ms)","trace[1822150079] 'compare' (duration: 17.899967ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:22:29.977782Z","caller":"traceutil/trace.go:171","msg":"trace[1552987549] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9137; }","duration":"148.209375ms","start":"2026-10-06T10:22:29.829545Z","end":"2026-10-06T10:22:29.977754Z","steps":["trace[1552987549] 'process raft request' (duration: 120.978772ms)","trace[1552987549] 'compare' (duration: 27.129141ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:22:30.013692Z","caller":"traceutil/trace.go:171","msg":"trace[218000554] transaction","detail":"{read_only:false; response_revision:9138; number_of_response:1; }","duration":"181.957261ms","start":"2026-10-06T10:22:29.831713Z","end":"2026-10-06T10:22:30.013671Z","steps":["trace[218000554] 'process raft request' (duration: 181.846109ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:22:33.100299Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002538.wal"} {"level":"info","ts":"2026-10-06T10:23:29.038163Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":10004,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T10:23:29.044341Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10004} {"level":"info","ts":"2026-10-06T10:23:29.044432Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5004} {"level":"info","ts":"2026-10-06T10:23:56.61424Z","caller":"traceutil/trace.go:171","msg":"trace[88003317] transaction","detail":"{read_only:false; response_revision:9967; number_of_response:1; }","duration":"102.93331ms","start":"2026-10-06T10:23:56.511271Z","end":"2026-10-06T10:23:56.614204Z","steps":["trace[88003317] 'process raft request' (duration: 102.36246ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:23:56.90621Z","caller":"traceutil/trace.go:171","msg":"trace[1504942171] transaction","detail":"{read_only:false; response_revision:9968; number_of_response:1; }","duration":"200.00791ms","start":"2026-10-06T10:23:56.70617Z","end":"2026-10-06T10:23:56.906178Z","steps":["trace[1504942171] 'process raft request' (duration: 199.749185ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:24:58.886546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7994} {"level":"info","ts":"2026-10-06T10:24:58.97501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7994,"took":"86.384969ms","hash":1605504054,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":24977408,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-06T10:24:58.975129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1605504054,"revision":7994,"compact-revision":5637} {"level":"warn","ts":"2026-10-06T10:27:21.59367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.14739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:27:21.594057Z","caller":"traceutil/trace.go:171","msg":"trace[2082585514] transaction","detail":"{read_only:false; response_revision:11713; number_of_response:1; }","duration":"176.705293ms","start":"2026-10-06T10:27:21.417295Z","end":"2026-10-06T10:27:21.594Z","steps":["trace[2082585514] 'process raft request' (duration: 30.109635ms)","trace[2082585514] 'compare' (duration: 145.812095ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:27:21.594308Z","caller":"traceutil/trace.go:171","msg":"trace[299447506] transaction","detail":"{read_only:false; response_revision:11714; number_of_response:1; }","duration":"160.808225ms","start":"2026-10-06T10:27:21.433423Z","end":"2026-10-06T10:27:21.594231Z","steps":["trace[299447506] 'process raft request' (duration: 160.419267ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:29:58.893936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10412} {"level":"info","ts":"2026-10-06T10:29:58.981981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10412,"took":"85.321763ms","hash":2330176374,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":27865088,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-06T10:29:58.982049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2330176374,"revision":10412,"compact-revision":7994} {"level":"info","ts":"2026-10-06T10:33:31.149942Z","caller":"traceutil/trace.go:171","msg":"trace[1076112232] transaction","detail":"{read_only:false; response_revision:14211; number_of_response:1; }","duration":"106.724731ms","start":"2026-10-06T10:33:31.043174Z","end":"2026-10-06T10:33:31.149898Z","steps":["trace[1076112232] 'process raft request' (duration: 106.511677ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:33:51.418627Z","caller":"traceutil/trace.go:171","msg":"trace[1465023084] transaction","detail":"{read_only:false; response_revision:14321; number_of_response:1; }","duration":"104.840485ms","start":"2026-10-06T10:33:51.313753Z","end":"2026-10-06T10:33:51.418594Z","steps":["trace[1465023084] 'process raft request' (duration: 104.652762ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:33:51.419325Z","caller":"traceutil/trace.go:171","msg":"trace[1272741541] transaction","detail":"{read_only:false; response_revision:14322; number_of_response:1; }","duration":"105.333095ms","start":"2026-10-06T10:33:51.313969Z","end":"2026-10-06T10:33:51.419302Z","steps":["trace[1272741541] 'process raft request' (duration: 105.180812ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:33:51.419557Z","caller":"traceutil/trace.go:171","msg":"trace[990351891] transaction","detail":"{read_only:false; response_revision:14323; number_of_response:1; }","duration":"105.529129ms","start":"2026-10-06T10:33:51.313986Z","end":"2026-10-06T10:33:51.419515Z","steps":["trace[990351891] 'process raft request' (duration: 105.288644ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:33:51.41973Z","caller":"traceutil/trace.go:171","msg":"trace[870751469] transaction","detail":"{read_only:false; response_revision:14324; number_of_response:1; }","duration":"105.628791ms","start":"2026-10-06T10:33:51.314057Z","end":"2026-10-06T10:33:51.419685Z","steps":["trace[870751469] 'process raft request' (duration: 105.380116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:34:07.993178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.202313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:34:07.993267Z","caller":"traceutil/trace.go:171","msg":"trace[1307115321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14426; }","duration":"184.345945ms","start":"2026-10-06T10:34:07.808899Z","end":"2026-10-06T10:34:07.993245Z","steps":["trace[1307115321] 'range keys from in-memory index tree' (duration: 184.06585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:34:31.594756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.335545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-73eb6a38-2075-49e0-99b1-56de431e435f\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-06T10:34:31.594925Z","caller":"traceutil/trace.go:171","msg":"trace[1044266344] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-73eb6a38-2075-49e0-99b1-56de431e435f; range_end:; response_count:1; response_revision:14738; }","duration":"314.57101ms","start":"2026-10-06T10:34:31.280321Z","end":"2026-10-06T10:34:31.594892Z","steps":["trace[1044266344] 'range keys from in-memory index tree' (duration: 314.093741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:34:31.594975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:34:31.280301Z","time spent":"314.663221ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-73eb6a38-2075-49e0-99b1-56de431e435f\" "} {"level":"info","ts":"2026-10-06T10:34:31.595019Z","caller":"traceutil/trace.go:171","msg":"trace[1567356489] transaction","detail":"{read_only:false; response_revision:14739; number_of_response:1; }","duration":"101.488204ms","start":"2026-10-06T10:34:31.493504Z","end":"2026-10-06T10:34:31.594992Z","steps":["trace[1567356489] 'process raft request' (duration: 95.416359ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:34:58.900198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12772} {"level":"info","ts":"2026-10-06T10:34:58.990969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12772,"took":"88.372875ms","hash":792030443,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":28950528,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-06T10:34:58.991037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":792030443,"revision":12772,"compact-revision":10412} {"level":"info","ts":"2026-10-06T10:36:05.215154Z","caller":"traceutil/trace.go:171","msg":"trace[101848271] linearizableReadLoop","detail":"{readStateIndex:16132; appliedIndex:16131; }","duration":"113.052801ms","start":"2026-10-06T10:36:05.10208Z","end":"2026-10-06T10:36:05.215132Z","steps":["trace[101848271] 'read index received' (duration: 112.817817ms)","trace[101848271] 'applied index is now lower than readState.Index' (duration: 233.944µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:36:05.215218Z","caller":"traceutil/trace.go:171","msg":"trace[1266790004] transaction","detail":"{read_only:false; response_revision:15593; number_of_response:1; }","duration":"117.598666ms","start":"2026-10-06T10:36:05.097589Z","end":"2026-10-06T10:36:05.215187Z","steps":["trace[1266790004] 'process raft request' (duration: 117.398122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:36:05.216229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.134321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737369"} {"level":"info","ts":"2026-10-06T10:36:05.2163Z","caller":"traceutil/trace.go:171","msg":"trace[1176020792] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15593; }","duration":"114.218552ms","start":"2026-10-06T10:36:05.102065Z","end":"2026-10-06T10:36:05.216283Z","steps":["trace[1176020792] 'agreement among raft nodes before linearized reading' (duration: 113.208564ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:36:06.089767Z","caller":"traceutil/trace.go:171","msg":"trace[321139449] transaction","detail":"{read_only:false; response_revision:15595; number_of_response:1; }","duration":"125.995305ms","start":"2026-10-06T10:36:05.963746Z","end":"2026-10-06T10:36:06.089742Z","steps":["trace[321139449] 'process raft request' (duration: 125.841742ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:36:06.220349Z","caller":"traceutil/trace.go:171","msg":"trace[1770012864] transaction","detail":"{read_only:false; response_revision:15596; number_of_response:1; }","duration":"243.874937ms","start":"2026-10-06T10:36:05.976436Z","end":"2026-10-06T10:36:06.220311Z","steps":["trace[1770012864] 'process raft request' (duration: 189.304318ms)","trace[1770012864] 'compare' (duration: 54.423666ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:39:58.911253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14931} {"level":"info","ts":"2026-10-06T10:39:59.020204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14931,"took":"105.917728ms","hash":451206814,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-06T10:39:59.020302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":451206814,"revision":14931,"compact-revision":12772} {"level":"info","ts":"2026-10-06T10:42:03.787697Z","caller":"traceutil/trace.go:171","msg":"trace[97723527] linearizableReadLoop","detail":"{readStateIndex:18854; appliedIndex:18853; }","duration":"230.619072ms","start":"2026-10-06T10:42:03.557036Z","end":"2026-10-06T10:42:03.787655Z","steps":["trace[97723527] 'read index received' (duration: 230.380597ms)","trace[97723527] 'applied index is now lower than readState.Index' (duration: 237.625µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:42:03.788081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.025189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7d7hc\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-10-06T10:42:03.788172Z","caller":"traceutil/trace.go:171","msg":"trace[190426370] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7d7hc; range_end:; response_count:1; response_revision:18227; }","duration":"231.125261ms","start":"2026-10-06T10:42:03.557024Z","end":"2026-10-06T10:42:03.788149Z","steps":["trace[190426370] 'agreement among raft nodes before linearized reading' (duration: 230.877296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:42:03.788251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.095535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-06T10:42:03.788358Z","caller":"traceutil/trace.go:171","msg":"trace[347215209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18227; }","duration":"121.36601ms","start":"2026-10-06T10:42:03.666978Z","end":"2026-10-06T10:42:03.788344Z","steps":["trace[347215209] 'agreement among raft nodes before linearized reading' (duration: 121.012673ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:42:03.788661Z","caller":"traceutil/trace.go:171","msg":"trace[1672662178] transaction","detail":"{read_only:false; response_revision:18227; number_of_response:1; }","duration":"255.94119ms","start":"2026-10-06T10:42:03.532704Z","end":"2026-10-06T10:42:03.788645Z","steps":["trace[1672662178] 'process raft request' (duration: 254.785697ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:42:04.238121Z","caller":"traceutil/trace.go:171","msg":"trace[737045512] transaction","detail":"{read_only:false; response_revision:18229; number_of_response:1; }","duration":"429.316558ms","start":"2026-10-06T10:42:03.808778Z","end":"2026-10-06T10:42:04.238094Z","steps":["trace[737045512] 'process raft request' (duration: 429.156955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:42:04.240235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:42:03.808764Z","time spent":"431.376477ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:43:57.637432Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":20005,"local-member-snapshot-index":10004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T10:43:57.642865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20005} {"level":"info","ts":"2026-10-06T10:43:57.642944Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15005} {"level":"info","ts":"2026-10-06T10:44:58.917835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17385} {"level":"info","ts":"2026-10-06T10:44:59.02651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17385,"took":"105.134262ms","hash":1878119481,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":34676736,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-06T10:44:59.026587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1878119481,"revision":17385,"compact-revision":14931} {"level":"warn","ts":"2026-10-06T10:47:47.915707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.111082ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-06T10:47:47.915878Z","caller":"traceutil/trace.go:171","msg":"trace[628801853] linearizableReadLoop","detail":"{readStateIndex:21610; appliedIndex:21609; }","duration":"282.330076ms","start":"2026-10-06T10:47:47.633536Z","end":"2026-10-06T10:47:47.915866Z","steps":["trace[628801853] 'read index received' (duration: 22.97µs)","trace[628801853] 'applied index is now lower than readState.Index' (duration: 282.306396ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:47:47.915946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.399987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:47:47.915961Z","caller":"traceutil/trace.go:171","msg":"trace[2094357548] transaction","detail":"{read_only:false; response_revision:20898; number_of_response:1; }","duration":"214.68654ms","start":"2026-10-06T10:47:47.701256Z","end":"2026-10-06T10:47:47.915943Z","steps":["trace[2094357548] 'process raft request' (duration: 214.521446ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:47:47.915969Z","caller":"traceutil/trace.go:171","msg":"trace[1659952921] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20897; }","duration":"282.455679ms","start":"2026-10-06T10:47:47.633505Z","end":"2026-10-06T10:47:47.915961Z","steps":["trace[1659952921] 'agreement among raft nodes before linearized reading' (duration: 282.408898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:47.921273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.120459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-06T10:47:47.921323Z","caller":"traceutil/trace.go:171","msg":"trace[1615622948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20899; }","duration":"258.18346ms","start":"2026-10-06T10:47:47.663127Z","end":"2026-10-06T10:47:47.921311Z","steps":["trace[1615622948] 'agreement among raft nodes before linearized reading' (duration: 258.048698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:47.921568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.22252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-06T10:47:47.921563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.383807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:47:47.921605Z","caller":"traceutil/trace.go:171","msg":"trace[1373534260] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20899; }","duration":"118.259141ms","start":"2026-10-06T10:47:47.803337Z","end":"2026-10-06T10:47:47.921596Z","steps":["trace[1373534260] 'agreement among raft nodes before linearized reading' (duration: 118.157509ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:47:47.92165Z","caller":"traceutil/trace.go:171","msg":"trace[275658532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20899; }","duration":"115.507569ms","start":"2026-10-06T10:47:47.806118Z","end":"2026-10-06T10:47:47.921626Z","steps":["trace[275658532] 'agreement among raft nodes before linearized reading' (duration: 115.325116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.254421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.634478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-06T10:47:48.254495Z","caller":"traceutil/trace.go:171","msg":"trace[1670209833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20900; }","duration":"127.73721ms","start":"2026-10-06T10:47:48.126741Z","end":"2026-10-06T10:47:48.254478Z","steps":["trace[1670209833] 'range keys from in-memory index tree' (duration: 127.513905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.254734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.22573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-06T10:47:48.254808Z","caller":"traceutil/trace.go:171","msg":"trace[1510261285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20900; }","duration":"127.327022ms","start":"2026-10-06T10:47:48.127462Z","end":"2026-10-06T10:47:48.254789Z","steps":["trace[1510261285] 'range keys from in-memory index tree' (duration: 127.142159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.254957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.544926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:47:48.255Z","caller":"traceutil/trace.go:171","msg":"trace[1572548347] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20900; }","duration":"205.619948ms","start":"2026-10-06T10:47:48.04937Z","end":"2026-10-06T10:47:48.25499Z","steps":["trace[1572548347] 'range keys from in-memory index tree' (duration: 205.487415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.255103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.483605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-06T10:47:48.255145Z","caller":"traceutil/trace.go:171","msg":"trace[617394407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20900; }","duration":"126.538966ms","start":"2026-10-06T10:47:48.128593Z","end":"2026-10-06T10:47:48.255132Z","steps":["trace[617394407] 'range keys from in-memory index tree' (duration: 126.311262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.255362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.139871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-06T10:47:48.255401Z","caller":"traceutil/trace.go:171","msg":"trace[264440439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20900; }","duration":"133.214373ms","start":"2026-10-06T10:47:48.122177Z","end":"2026-10-06T10:47:48.255392Z","steps":["trace[264440439] 'range keys from in-memory index tree' (duration: 133.040839ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:47:48.610346Z","caller":"traceutil/trace.go:171","msg":"trace[1703656537] linearizableReadLoop","detail":"{readStateIndex:21617; appliedIndex:21614; }","duration":"314.704687ms","start":"2026-10-06T10:47:48.295612Z","end":"2026-10-06T10:47:48.610317Z","steps":["trace[1703656537] 'read index received' (duration: 314.325519ms)","trace[1703656537] 'applied index is now lower than readState.Index' (duration: 378.168µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:47:48.61059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.96611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-06T10:47:48.610624Z","caller":"traceutil/trace.go:171","msg":"trace[1544446715] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20904; }","duration":"315.039512ms","start":"2026-10-06T10:47:48.295572Z","end":"2026-10-06T10:47:48.610612Z","steps":["trace[1544446715] 'agreement among raft nodes before linearized reading' (duration: 314.85097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.610657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:47:48.295562Z","time spent":"315.087413ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-06T10:47:48.611057Z","caller":"traceutil/trace.go:171","msg":"trace[1207593317] transaction","detail":"{read_only:false; response_revision:20902; number_of_response:1; }","duration":"350.311819ms","start":"2026-10-06T10:47:48.260728Z","end":"2026-10-06T10:47:48.61104Z","steps":["trace[1207593317] 'process raft request' (duration: 349.2782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.611194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:47:48.260715Z","time spent":"350.40716ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:47:48.611395Z","caller":"traceutil/trace.go:171","msg":"trace[1680321923] transaction","detail":"{read_only:false; response_revision:20903; number_of_response:1; }","duration":"350.608654ms","start":"2026-10-06T10:47:48.260773Z","end":"2026-10-06T10:47:48.611381Z","steps":["trace[1680321923] 'process raft request' (duration: 349.441573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.611558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:47:48.260754Z","time spent":"350.699786ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:47:48.611734Z","caller":"traceutil/trace.go:171","msg":"trace[1582201836] transaction","detail":"{read_only:false; response_revision:20904; number_of_response:1; }","duration":"344.973438ms","start":"2026-10-06T10:47:48.26675Z","end":"2026-10-06T10:47:48.611723Z","steps":["trace[1582201836] 'process raft request' (duration: 343.519991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:47:48.611788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:47:48.266738Z","time spent":"345.022239ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:47:49.022926Z","caller":"traceutil/trace.go:171","msg":"trace[1299381572] transaction","detail":"{read_only:false; response_revision:20905; number_of_response:1; }","duration":"137.316841ms","start":"2026-10-06T10:47:48.885583Z","end":"2026-10-06T10:47:49.0229Z","steps":["trace[1299381572] 'process raft request' (duration: 137.139457ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:49:58.925475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19718} {"level":"info","ts":"2026-10-06T10:49:59.04576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19718,"took":"117.028089ms","hash":3377484020,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-06T10:49:59.045836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3377484020,"revision":19718,"compact-revision":17385} {"level":"info","ts":"2026-10-06T10:51:44.23875Z","caller":"traceutil/trace.go:171","msg":"trace[84869262] transaction","detail":"{read_only:false; response_revision:23376; number_of_response:1; }","duration":"293.194944ms","start":"2026-10-06T10:51:43.945504Z","end":"2026-10-06T10:51:44.238698Z","steps":["trace[84869262] 'process raft request' (duration: 281.965252ms)","trace[84869262] 'compare' (duration: 10.679072ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:51:44.238887Z","caller":"traceutil/trace.go:171","msg":"trace[1202284461] linearizableReadLoop","detail":"{readStateIndex:24165; appliedIndex:24164; }","duration":"208.330383ms","start":"2026-10-06T10:51:44.030487Z","end":"2026-10-06T10:51:44.238818Z","steps":["trace[1202284461] 'read index received' (duration: 196.917607ms)","trace[1202284461] 'applied index is now lower than readState.Index' (duration: 11.410976ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:51:44.239238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.730839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:51:44.239292Z","caller":"traceutil/trace.go:171","msg":"trace[2142940314] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23377; }","duration":"208.862421ms","start":"2026-10-06T10:51:44.030412Z","end":"2026-10-06T10:51:44.239275Z","steps":["trace[2142940314] 'agreement among raft nodes before linearized reading' (duration: 208.707998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:51:44.239388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.639625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:51:44.239477Z","caller":"traceutil/trace.go:171","msg":"trace[1970692902] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:23377; }","duration":"104.803638ms","start":"2026-10-06T10:51:44.134647Z","end":"2026-10-06T10:51:44.23945Z","steps":["trace[1970692902] 'agreement among raft nodes before linearized reading' (duration: 104.615364ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:53:56.840024Z","caller":"traceutil/trace.go:171","msg":"trace[1813656320] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"136.852333ms","start":"2026-10-06T10:53:56.703129Z","end":"2026-10-06T10:53:56.839982Z","steps":["trace[1813656320] 'process raft request' (duration: 134.461948ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:54:58.936982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21955} {"level":"info","ts":"2026-10-06T10:54:59.050508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21955,"took":"109.679399ms","hash":884109920,"current-db-size-bytes":55259136,"current-db-size":"55 MB","current-db-size-in-use-bytes":44834816,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-06T10:54:59.050578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":884109920,"revision":21955,"compact-revision":19718} {"level":"info","ts":"2026-10-06T10:55:03.491707Z","caller":"traceutil/trace.go:171","msg":"trace[1262670370] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"119.22306ms","start":"2026-10-06T10:55:03.372446Z","end":"2026-10-06T10:55:03.49167Z","steps":["trace[1262670370] 'process raft request' (duration: 118.789061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:24.027172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.618674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:24.027256Z","caller":"traceutil/trace.go:171","msg":"trace[1617859144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25554; }","duration":"220.777217ms","start":"2026-10-06T10:55:23.806462Z","end":"2026-10-06T10:55:24.027239Z","steps":["trace[1617859144] 'range keys from in-memory index tree' (duration: 220.460962ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:24.027739Z","caller":"traceutil/trace.go:171","msg":"trace[1155859281] linearizableReadLoop","detail":"{readStateIndex:26401; appliedIndex:26400; }","duration":"198.286903ms","start":"2026-10-06T10:55:23.829419Z","end":"2026-10-06T10:55:24.027706Z","steps":["trace[1155859281] 'read index received' (duration: 191.761509ms)","trace[1155859281] 'applied index is now lower than readState.Index' (duration: 6.524334ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:24.027752Z","caller":"traceutil/trace.go:171","msg":"trace[203338438] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"220.357749ms","start":"2026-10-06T10:55:23.807359Z","end":"2026-10-06T10:55:24.027717Z","steps":["trace[203338438] 'process raft request' (duration: 213.876836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:24.02802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.580709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:24.028063Z","caller":"traceutil/trace.go:171","msg":"trace[1405713502] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25555; }","duration":"198.67677ms","start":"2026-10-06T10:55:23.829375Z","end":"2026-10-06T10:55:24.028051Z","steps":["trace[1405713502] 'agreement among raft nodes before linearized reading' (duration: 198.506907ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:24.039923Z","caller":"traceutil/trace.go:171","msg":"trace[2098178548] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"170.697062ms","start":"2026-10-06T10:55:23.869175Z","end":"2026-10-06T10:55:24.039872Z","steps":["trace[2098178548] 'process raft request' (duration: 170.06702ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:24.675888Z","caller":"traceutil/trace.go:171","msg":"trace[598389486] linearizableReadLoop","detail":"{readStateIndex:26405; appliedIndex:26404; }","duration":"225.745891ms","start":"2026-10-06T10:55:24.450057Z","end":"2026-10-06T10:55:24.675803Z","steps":["trace[598389486] 'read index received' (duration: 225.489226ms)","trace[598389486] 'applied index is now lower than readState.Index' (duration: 255.255µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:24.675986Z","caller":"traceutil/trace.go:171","msg":"trace[1987203757] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"261.865932ms","start":"2026-10-06T10:55:24.414099Z","end":"2026-10-06T10:55:24.675965Z","steps":["trace[1987203757] 'process raft request' (duration: 261.478995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:24.676103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.021756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:24.676159Z","caller":"traceutil/trace.go:171","msg":"trace[845995894] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25559; }","duration":"226.128558ms","start":"2026-10-06T10:55:24.450014Z","end":"2026-10-06T10:55:24.676142Z","steps":["trace[845995894] 'agreement among raft nodes before linearized reading' (duration: 226.016486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:24.677469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.546212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-06T10:55:24.677528Z","caller":"traceutil/trace.go:171","msg":"trace[280974407] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25560; }","duration":"132.678904ms","start":"2026-10-06T10:55:24.544836Z","end":"2026-10-06T10:55:24.677515Z","steps":["trace[280974407] 'agreement among raft nodes before linearized reading' (duration: 132.49626ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:28.913166Z","caller":"traceutil/trace.go:171","msg":"trace[546733947] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26440; }","duration":"107.06963ms","start":"2026-10-06T10:55:28.806064Z","end":"2026-10-06T10:55:28.913134Z","steps":["trace[546733947] 'read index received' (duration: 90.175021ms)","trace[546733947] 'applied index is now lower than readState.Index' (duration: 16.891209ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:28.913317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.227373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:28.913389Z","caller":"traceutil/trace.go:171","msg":"trace[2029918161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25595; }","duration":"107.352126ms","start":"2026-10-06T10:55:28.806015Z","end":"2026-10-06T10:55:28.913368Z","steps":["trace[2029918161] 'agreement among raft nodes before linearized reading' (duration: 107.212533ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:28.912903Z","caller":"traceutil/trace.go:171","msg":"trace[1675577492] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"108.103341ms","start":"2026-10-06T10:55:28.804736Z","end":"2026-10-06T10:55:28.91284Z","steps":["trace[1675577492] 'process raft request' (duration: 91.471367ms)","trace[1675577492] 'compare' (duration: 15.837929ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:29.449101Z","caller":"traceutil/trace.go:171","msg":"trace[1062464917] linearizableReadLoop","detail":"{readStateIndex:26445; appliedIndex:26444; }","duration":"158.501631ms","start":"2026-10-06T10:55:29.290565Z","end":"2026-10-06T10:55:29.449066Z","steps":["trace[1062464917] 'read index received' (duration: 124.316985ms)","trace[1062464917] 'applied index is now lower than readState.Index' (duration: 34.183506ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:29.449168Z","caller":"traceutil/trace.go:171","msg":"trace[1796703087] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"200.006236ms","start":"2026-10-06T10:55:29.249101Z","end":"2026-10-06T10:55:29.449108Z","steps":["trace[1796703087] 'process raft request' (duration: 165.743919ms)","trace[1796703087] 'compare' (duration: 34.028172ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:29.449468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.864759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-06T10:55:29.449583Z","caller":"traceutil/trace.go:171","msg":"trace[995156867] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25598; }","duration":"159.019471ms","start":"2026-10-06T10:55:29.290532Z","end":"2026-10-06T10:55:29.449552Z","steps":["trace[995156867] 'agreement among raft nodes before linearized reading' (duration: 158.688675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:29.449681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.670294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:55:29.449756Z","caller":"traceutil/trace.go:171","msg":"trace[1715385902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25598; }","duration":"106.803516ms","start":"2026-10-06T10:55:29.342939Z","end":"2026-10-06T10:55:29.449743Z","steps":["trace[1715385902] 'agreement among raft nodes before linearized reading' (duration: 106.294576ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:29.588695Z","caller":"traceutil/trace.go:171","msg":"trace[1009350387] linearizableReadLoop","detail":"{readStateIndex:26446; appliedIndex:26445; }","duration":"130.243187ms","start":"2026-10-06T10:55:29.458428Z","end":"2026-10-06T10:55:29.588671Z","steps":["trace[1009350387] 'read index received' (duration: 120.31445ms)","trace[1009350387] 'applied index is now lower than readState.Index' (duration: 9.927747ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:29.588831Z","caller":"traceutil/trace.go:171","msg":"trace[280205093] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"134.120582ms","start":"2026-10-06T10:55:29.454674Z","end":"2026-10-06T10:55:29.588795Z","steps":["trace[280205093] 'process raft request' (duration: 124.285827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:29.588876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.425151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-06T10:55:29.588944Z","caller":"traceutil/trace.go:171","msg":"trace[1160658542] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25599; }","duration":"130.548383ms","start":"2026-10-06T10:55:29.45838Z","end":"2026-10-06T10:55:29.588929Z","steps":["trace[1160658542] 'agreement among raft nodes before linearized reading' (duration: 130.38157ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:29.821387Z","caller":"traceutil/trace.go:171","msg":"trace[2007310847] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"171.960866ms","start":"2026-10-06T10:55:29.649399Z","end":"2026-10-06T10:55:29.82136Z","steps":["trace[2007310847] 'process raft request' (duration: 73.383326ms)","trace[2007310847] 'compare' (duration: 98.364216ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:29.82155Z","caller":"traceutil/trace.go:171","msg":"trace[228599955] linearizableReadLoop","detail":"{readStateIndex:26448; appliedIndex:26446; }","duration":"112.952952ms","start":"2026-10-06T10:55:29.708571Z","end":"2026-10-06T10:55:29.821524Z","steps":["trace[228599955] 'read index received' (duration: 14.198788ms)","trace[228599955] 'applied index is now lower than readState.Index' (duration: 98.752484ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:29.821651Z","caller":"traceutil/trace.go:171","msg":"trace[1339671540] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"137.296792ms","start":"2026-10-06T10:55:29.684285Z","end":"2026-10-06T10:55:29.821582Z","steps":["trace[1339671540] 'process raft request' (duration: 137.025276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:29.821876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.284599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-06T10:55:29.821983Z","caller":"traceutil/trace.go:171","msg":"trace[1504549344] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25601; }","duration":"113.446322ms","start":"2026-10-06T10:55:29.708517Z","end":"2026-10-06T10:55:29.821963Z","steps":["trace[1504549344] 'agreement among raft nodes before linearized reading' (duration: 113.202177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:30.199095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.522786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:30.199217Z","caller":"traceutil/trace.go:171","msg":"trace[877534301] linearizableReadLoop","detail":"{readStateIndex:26449; appliedIndex:26448; }","duration":"306.394053ms","start":"2026-10-06T10:55:29.892802Z","end":"2026-10-06T10:55:30.199196Z","steps":["trace[877534301] 'read index received' (duration: 107.55618ms)","trace[877534301] 'applied index is now lower than readState.Index' (duration: 198.836463ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:30.199299Z","caller":"traceutil/trace.go:171","msg":"trace[329908942] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"372.010791ms","start":"2026-10-06T10:55:29.827276Z","end":"2026-10-06T10:55:30.199287Z","steps":["trace[329908942] 'process raft request' (duration: 173.183019ms)","trace[329908942] 'compare' (duration: 198.212641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:30.199366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:29.827261Z","time spent":"372.073752ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:30.199811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.995094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:30.200429Z","caller":"traceutil/trace.go:171","msg":"trace[317118891] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25602; }","duration":"307.192038ms","start":"2026-10-06T10:55:29.892729Z","end":"2026-10-06T10:55:30.199921Z","steps":["trace[317118891] 'agreement among raft nodes before linearized reading' (duration: 306.570126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:30.200504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:29.892703Z","time spent":"307.783689ms","remote":"127.0.0.1:33136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-06T10:55:30.383061Z","caller":"traceutil/trace.go:171","msg":"trace[1563396790] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"341.852581ms","start":"2026-10-06T10:55:30.041167Z","end":"2026-10-06T10:55:30.383019Z","steps":["trace[1563396790] 'process raft request' (duration: 265.774156ms)","trace[1563396790] 'compare' (duration: 75.81585ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:30.38308Z","caller":"traceutil/trace.go:171","msg":"trace[2146894121] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"321.550659ms","start":"2026-10-06T10:55:30.06151Z","end":"2026-10-06T10:55:30.38306Z","steps":["trace[2146894121] 'process raft request' (duration: 321.472627ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:30.383078Z","caller":"traceutil/trace.go:171","msg":"trace[2107859872] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"333.611655ms","start":"2026-10-06T10:55:30.049429Z","end":"2026-10-06T10:55:30.38304Z","steps":["trace[2107859872] 'process raft request' (duration: 333.503153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:30.383234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:30.041137Z","time spent":"342.008004ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:30.383242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:30.061491Z","time spent":"321.66567ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:30.383286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:30.049408Z","time spent":"333.764689ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:30.383497Z","caller":"traceutil/trace.go:171","msg":"trace[1850360510] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"321.562769ms","start":"2026-10-06T10:55:30.061872Z","end":"2026-10-06T10:55:30.383435Z","steps":["trace[1850360510] 'process raft request' (duration: 321.150951ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:30.383644Z","caller":"traceutil/trace.go:171","msg":"trace[672205050] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"140.328328ms","start":"2026-10-06T10:55:30.243293Z","end":"2026-10-06T10:55:30.383622Z","steps":["trace[672205050] 'process raft request' (duration: 139.859829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:30.383707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:30.061862Z","time spent":"321.734722ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:30.383537Z","caller":"traceutil/trace.go:171","msg":"trace[1812088893] linearizableReadLoop","detail":"{readStateIndex:26453; appliedIndex:26449; }","duration":"184.270527ms","start":"2026-10-06T10:55:30.199246Z","end":"2026-10-06T10:55:30.383516Z","steps":["trace[1812088893] 'read index received' (duration: 107.587241ms)","trace[1812088893] 'applied index is now lower than readState.Index' (duration: 76.678756ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:30.383677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.056255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:55:30.384023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.067371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:30.384038Z","caller":"traceutil/trace.go:171","msg":"trace[1407535575] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25607; }","duration":"334.397621ms","start":"2026-10-06T10:55:30.049576Z","end":"2026-10-06T10:55:30.383974Z","steps":["trace[1407535575] 'agreement among raft nodes before linearized reading' (duration: 333.997613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:30.38415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:30.04957Z","time spent":"334.558194ms","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-10-06T10:55:30.384064Z","caller":"traceutil/trace.go:171","msg":"trace[2076327503] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25607; }","duration":"178.175633ms","start":"2026-10-06T10:55:30.205876Z","end":"2026-10-06T10:55:30.384052Z","steps":["trace[2076327503] 'agreement among raft nodes before linearized reading' (duration: 178.087671ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:30.896832Z","caller":"traceutil/trace.go:171","msg":"trace[777859704] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"127.241012ms","start":"2026-10-06T10:55:30.769531Z","end":"2026-10-06T10:55:30.896772Z","steps":["trace[777859704] 'process raft request' (duration: 126.970367ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:31.084637Z","caller":"traceutil/trace.go:171","msg":"trace[571836502] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"166.002163ms","start":"2026-10-06T10:55:30.918609Z","end":"2026-10-06T10:55:31.084611Z","steps":["trace[571836502] 'process raft request' (duration: 165.81007ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:31.123422Z","caller":"traceutil/trace.go:171","msg":"trace[1810825182] linearizableReadLoop","detail":"{readStateIndex:26457; appliedIndex:26456; }","duration":"177.846058ms","start":"2026-10-06T10:55:30.945556Z","end":"2026-10-06T10:55:31.123402Z","steps":["trace[1810825182] 'read index received' (duration: 139.112876ms)","trace[1810825182] 'applied index is now lower than readState.Index' (duration: 38.732271ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:31.123573Z","caller":"traceutil/trace.go:171","msg":"trace[1789212788] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"201.492263ms","start":"2026-10-06T10:55:30.922038Z","end":"2026-10-06T10:55:31.123531Z","steps":["trace[1789212788] 'process raft request' (duration: 201.228768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:31.12368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.066401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:31.123725Z","caller":"traceutil/trace.go:171","msg":"trace[711737388] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25610; }","duration":"178.235374ms","start":"2026-10-06T10:55:30.945476Z","end":"2026-10-06T10:55:31.123711Z","steps":["trace[711737388] 'agreement among raft nodes before linearized reading' (duration: 178.029971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:31.423231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.923052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:31.423421Z","caller":"traceutil/trace.go:171","msg":"trace[491637033] linearizableReadLoop","detail":"{readStateIndex:26459; appliedIndex:26458; }","duration":"182.816741ms","start":"2026-10-06T10:55:31.240579Z","end":"2026-10-06T10:55:31.423396Z","steps":["trace[491637033] 'read index received' (duration: 61.556552ms)","trace[491637033] 'applied index is now lower than readState.Index' (duration: 121.257829ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:31.423538Z","caller":"traceutil/trace.go:171","msg":"trace[1847118364] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"182.902532ms","start":"2026-10-06T10:55:31.240486Z","end":"2026-10-06T10:55:31.423389Z","steps":["trace[1847118364] 'process raft request' (duration: 61.717035ms)","trace[1847118364] 'compare' (duration: 120.7913ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:31.423862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.247399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-10-06T10:55:31.423817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.846194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:55:31.424053Z","caller":"traceutil/trace.go:171","msg":"trace[354630329] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25612; }","duration":"128.15995ms","start":"2026-10-06T10:55:31.295863Z","end":"2026-10-06T10:55:31.424023Z","steps":["trace[354630329] 'agreement among raft nodes before linearized reading' (duration: 127.799333ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:31.423984Z","caller":"traceutil/trace.go:171","msg":"trace[352622776] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25612; }","duration":"183.400532ms","start":"2026-10-06T10:55:31.240526Z","end":"2026-10-06T10:55:31.423926Z","steps":["trace[352622776] 'agreement among raft nodes before linearized reading' (duration: 183.071675ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:31.936369Z","caller":"traceutil/trace.go:171","msg":"trace[1538848858] linearizableReadLoop","detail":"{readStateIndex:26461; appliedIndex:26460; }","duration":"127.296823ms","start":"2026-10-06T10:55:31.809047Z","end":"2026-10-06T10:55:31.936344Z","steps":["trace[1538848858] 'read index received' (duration: 127.11115ms)","trace[1538848858] 'applied index is now lower than readState.Index' (duration: 184.563µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:31.936478Z","caller":"traceutil/trace.go:171","msg":"trace[1062088767] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"252.453835ms","start":"2026-10-06T10:55:31.683989Z","end":"2026-10-06T10:55:31.936443Z","steps":["trace[1062088767] 'process raft request' (duration: 252.17222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:31.936563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.330347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:31.936601Z","caller":"traceutil/trace.go:171","msg":"trace[557670121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25614; }","duration":"123.434659ms","start":"2026-10-06T10:55:31.813151Z","end":"2026-10-06T10:55:31.936586Z","steps":["trace[557670121] 'agreement among raft nodes before linearized reading' (duration: 123.340007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:31.936606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.511516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:31.936727Z","caller":"traceutil/trace.go:171","msg":"trace[611699118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25614; }","duration":"127.69856ms","start":"2026-10-06T10:55:31.809002Z","end":"2026-10-06T10:55:31.936701Z","steps":["trace[611699118] 'agreement among raft nodes before linearized reading' (duration: 127.467157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:31.936724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.384813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-06T10:55:31.936787Z","caller":"traceutil/trace.go:171","msg":"trace[2076520968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25614; }","duration":"110.492704ms","start":"2026-10-06T10:55:31.826278Z","end":"2026-10-06T10:55:31.936771Z","steps":["trace[2076520968] 'agreement among raft nodes before linearized reading' (duration: 110.25087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:32.208834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.675804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:32.209031Z","caller":"traceutil/trace.go:171","msg":"trace[1777999361] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"267.021739ms","start":"2026-10-06T10:55:31.941975Z","end":"2026-10-06T10:55:32.208996Z","steps":["trace[1777999361] 'process raft request' (duration: 161.070759ms)","trace[1777999361] 'compare' (duration: 105.371948ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:32.747335Z","caller":"traceutil/trace.go:171","msg":"trace[353523772] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"356.697763ms","start":"2026-10-06T10:55:32.390611Z","end":"2026-10-06T10:55:32.747309Z","steps":["trace[353523772] 'process raft request' (duration: 356.468589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:32.747504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:32.390562Z","time spent":"356.850966ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:32.747743Z","caller":"traceutil/trace.go:171","msg":"trace[505741272] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"357.012879ms","start":"2026-10-06T10:55:32.3907Z","end":"2026-10-06T10:55:32.747713Z","steps":["trace[505741272] 'process raft request' (duration: 356.55997ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:32.747784Z","caller":"traceutil/trace.go:171","msg":"trace[717230401] linearizableReadLoop","detail":"{readStateIndex:26467; appliedIndex:26464; }","duration":"293.610532ms","start":"2026-10-06T10:55:32.454142Z","end":"2026-10-06T10:55:32.747753Z","steps":["trace[717230401] 'read index received' (duration: 292.962639ms)","trace[717230401] 'applied index is now lower than readState.Index' (duration: 646.773µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:32.747951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:32.390686Z","time spent":"357.123481ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:32.747959Z","caller":"traceutil/trace.go:171","msg":"trace[1421310136] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"357.175252ms","start":"2026-10-06T10:55:32.390701Z","end":"2026-10-06T10:55:32.747876Z","steps":["trace[1421310136] 'process raft request' (duration: 356.873516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:32.74813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.953678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.223\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-06T10:55:32.748198Z","caller":"traceutil/trace.go:171","msg":"trace[1877719469] range","detail":"{range_begin:/registry/masterleases/162.253.55.223; range_end:; response_count:1; response_revision:25620; }","duration":"294.077481ms","start":"2026-10-06T10:55:32.454101Z","end":"2026-10-06T10:55:32.748178Z","steps":["trace[1877719469] 'agreement among raft nodes before linearized reading' (duration: 293.829286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:32.749118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:32.390675Z","time spent":"357.405126ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:33.003544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.813973ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-10-06T10:55:33.003462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.861868ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:33.003633Z","caller":"traceutil/trace.go:171","msg":"trace[1141468006] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25620; }","duration":"133.052571ms","start":"2026-10-06T10:55:32.870562Z","end":"2026-10-06T10:55:33.003615Z","steps":["trace[1141468006] 'range keys from in-memory index tree' (duration: 132.843137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.003771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.226309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-06T10:55:33.003802Z","caller":"traceutil/trace.go:171","msg":"trace[1346509556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25620; }","duration":"102.292961ms","start":"2026-10-06T10:55:32.901498Z","end":"2026-10-06T10:55:33.003791Z","steps":["trace[1346509556] 'agreement among raft nodes before linearized reading' (duration: 102.156338ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:33.003633Z","caller":"traceutil/trace.go:171","msg":"trace[368498937] linearizableReadLoop","detail":"{readStateIndex:26468; appliedIndex:26467; }","duration":"102.072066ms","start":"2026-10-06T10:55:32.901543Z","end":"2026-10-06T10:55:33.003615Z","steps":["trace[368498937] 'read index received' (duration: 43.971µs)","trace[368498937] 'applied index is now lower than readState.Index' (duration: 102.026605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:33.327107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.622282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:33.327625Z","caller":"traceutil/trace.go:171","msg":"trace[1066963742] linearizableReadLoop","detail":"{readStateIndex:26471; appliedIndex:26468; }","duration":"267.070951ms","start":"2026-10-06T10:55:33.060536Z","end":"2026-10-06T10:55:33.327607Z","steps":["trace[1066963742] 'read index received' (duration: 8.039491ms)","trace[1066963742] 'applied index is now lower than readState.Index' (duration: 259.0306ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:33.327804Z","caller":"traceutil/trace.go:171","msg":"trace[743870980] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"319.97879ms","start":"2026-10-06T10:55:33.007811Z","end":"2026-10-06T10:55:33.32779Z","steps":["trace[743870980] 'process raft request' (duration: 319.725675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.327956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.007788Z","time spent":"320.128121ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:33.32822Z","caller":"traceutil/trace.go:171","msg":"trace[418959332] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"323.250501ms","start":"2026-10-06T10:55:33.004926Z","end":"2026-10-06T10:55:33.328176Z","steps":["trace[418959332] 'process raft request' (duration: 215.443137ms)","trace[418959332] 'compare' (duration: 106.455879ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:33.328326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.764313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:33.328384Z","caller":"traceutil/trace.go:171","msg":"trace[627020260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25623; }","duration":"267.862726ms","start":"2026-10-06T10:55:33.060505Z","end":"2026-10-06T10:55:33.328368Z","steps":["trace[627020260] 'agreement among raft nodes before linearized reading' (duration: 267.643572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.328382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.004872Z","time spent":"323.428125ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:33.589049Z","caller":"traceutil/trace.go:171","msg":"trace[1197673130] linearizableReadLoop","detail":"{readStateIndex:26473; appliedIndex:26471; }","duration":"261.26805ms","start":"2026-10-06T10:55:33.327756Z","end":"2026-10-06T10:55:33.589024Z","steps":["trace[1197673130] 'read index received' (duration: 178.186823ms)","trace[1197673130] 'applied index is now lower than readState.Index' (duration: 83.080237ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:33.589326Z","caller":"traceutil/trace.go:171","msg":"trace[1654472081] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"499.363406ms","start":"2026-10-06T10:55:33.089946Z","end":"2026-10-06T10:55:33.58931Z","steps":["trace[1654472081] 'process raft request' (duration: 416.017653ms)","trace[1654472081] 'compare' (duration: 82.718201ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:33.589442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.089906Z","time spent":"499.457587ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:33.589629Z","caller":"traceutil/trace.go:171","msg":"trace[91221416] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"459.151596ms","start":"2026-10-06T10:55:33.130468Z","end":"2026-10-06T10:55:33.589619Z","steps":["trace[91221416] 'process raft request' (duration: 458.498233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.589692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.130449Z","time spent":"459.210838ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:33.589824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.851668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:33.589863Z","caller":"traceutil/trace.go:171","msg":"trace[1047097913] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25625; }","duration":"461.903748ms","start":"2026-10-06T10:55:33.127948Z","end":"2026-10-06T10:55:33.589852Z","steps":["trace[1047097913] 'agreement among raft nodes before linearized reading' (duration: 461.835517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.589925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.127943Z","time spent":"461.970439ms","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-06T10:55:33.590184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.413358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-06T10:55:33.590224Z","caller":"traceutil/trace.go:171","msg":"trace[785603281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25625; }","duration":"462.453598ms","start":"2026-10-06T10:55:33.127761Z","end":"2026-10-06T10:55:33.590214Z","steps":["trace[785603281] 'agreement among raft nodes before linearized reading' (duration: 462.319356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.590247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.127711Z","time spent":"462.5309ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-06T10:55:33.590428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.868283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-10-06T10:55:33.590463Z","caller":"traceutil/trace.go:171","msg":"trace[1495648421] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25625; }","duration":"518.943275ms","start":"2026-10-06T10:55:33.071512Z","end":"2026-10-06T10:55:33.590455Z","steps":["trace[1495648421] 'agreement among raft nodes before linearized reading' (duration: 518.836013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.590487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:33.071495Z","time spent":"518.986615ms","remote":"127.0.0.1:32870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-06T10:55:33.601209Z","caller":"traceutil/trace.go:171","msg":"trace[1548517022] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"171.186971ms","start":"2026-10-06T10:55:33.429997Z","end":"2026-10-06T10:55:33.601184Z","steps":["trace[1548517022] 'process raft request' (duration: 171.038178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.601547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.109241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-06T10:55:33.601629Z","caller":"traceutil/trace.go:171","msg":"trace[1144407656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25626; }","duration":"126.210872ms","start":"2026-10-06T10:55:33.475399Z","end":"2026-10-06T10:55:33.60161Z","steps":["trace[1144407656] 'agreement among raft nodes before linearized reading' (duration: 125.949237ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:33.810198Z","caller":"traceutil/trace.go:171","msg":"trace[1916701874] linearizableReadLoop","detail":"{readStateIndex:26476; appliedIndex:26475; }","duration":"131.982641ms","start":"2026-10-06T10:55:33.678192Z","end":"2026-10-06T10:55:33.810175Z","steps":["trace[1916701874] 'read index received' (duration: 130.435233ms)","trace[1916701874] 'applied index is now lower than readState.Index' (duration: 1.546458ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:33.810648Z","caller":"traceutil/trace.go:171","msg":"trace[101483254] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"201.592985ms","start":"2026-10-06T10:55:33.608923Z","end":"2026-10-06T10:55:33.810516Z","steps":["trace[101483254] 'process raft request' (duration: 199.764051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:33.811095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.879089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:55:33.811139Z","caller":"traceutil/trace.go:171","msg":"trace[378132490] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25628; }","duration":"132.96129ms","start":"2026-10-06T10:55:33.678166Z","end":"2026-10-06T10:55:33.811128Z","steps":["trace[378132490] 'agreement among raft nodes before linearized reading' (duration: 132.809897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:34.121944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.364973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:55:34.122022Z","caller":"traceutil/trace.go:171","msg":"trace[841421667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25628; }","duration":"181.562906ms","start":"2026-10-06T10:55:33.940441Z","end":"2026-10-06T10:55:34.122004Z","steps":["trace[841421667] 'range keys from in-memory index tree' (duration: 181.133968ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:34.434046Z","caller":"traceutil/trace.go:171","msg":"trace[1562812194] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"114.800406ms","start":"2026-10-06T10:55:34.319199Z","end":"2026-10-06T10:55:34.433999Z","steps":["trace[1562812194] 'process raft request' (duration: 112.285119ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:44.497872Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067bc.wal"} {"level":"info","ts":"2026-10-06T10:55:44.918872Z","caller":"traceutil/trace.go:171","msg":"trace[784471182] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"131.699306ms","start":"2026-10-06T10:55:44.787137Z","end":"2026-10-06T10:55:44.918837Z","steps":["trace[784471182] 'process raft request' (duration: 131.416041ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.024301Z","caller":"traceutil/trace.go:171","msg":"trace[1080297980] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"222.939649ms","start":"2026-10-06T10:55:44.801332Z","end":"2026-10-06T10:55:45.024271Z","steps":["trace[1080297980] 'process raft request' (duration: 222.702484ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.024656Z","caller":"traceutil/trace.go:171","msg":"trace[1975326045] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"220.730646ms","start":"2026-10-06T10:55:44.803881Z","end":"2026-10-06T10:55:45.024612Z","steps":["trace[1975326045] 'process raft request' (duration: 220.34592ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.024754Z","caller":"traceutil/trace.go:171","msg":"trace[858550499] linearizableReadLoop","detail":"{readStateIndex:26559; appliedIndex:26557; }","duration":"218.126986ms","start":"2026-10-06T10:55:44.806593Z","end":"2026-10-06T10:55:45.02472Z","steps":["trace[858550499] 'read index received' (duration: 112.362511ms)","trace[858550499] 'applied index is now lower than readState.Index' (duration: 105.762875ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:45.024936Z","caller":"traceutil/trace.go:171","msg":"trace[595871838] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"177.680663ms","start":"2026-10-06T10:55:44.847194Z","end":"2026-10-06T10:55:45.024874Z","steps":["trace[595871838] 'process raft request' (duration: 177.342557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:45.025016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.392131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:55:45.025039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.54629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:55:45.025084Z","caller":"traceutil/trace.go:171","msg":"trace[2022830637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25710; }","duration":"218.509894ms","start":"2026-10-06T10:55:44.806561Z","end":"2026-10-06T10:55:45.025071Z","steps":["trace[2022830637] 'agreement among raft nodes before linearized reading' (duration: 218.375842ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.025097Z","caller":"traceutil/trace.go:171","msg":"trace[1704725285] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25710; }","duration":"187.662382ms","start":"2026-10-06T10:55:44.837419Z","end":"2026-10-06T10:55:45.025082Z","steps":["trace[1704725285] 'agreement among raft nodes before linearized reading' (duration: 187.54865ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.648632Z","caller":"traceutil/trace.go:171","msg":"trace[1902320702] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"256.877609ms","start":"2026-10-06T10:55:45.391717Z","end":"2026-10-06T10:55:45.648595Z","steps":["trace[1902320702] 'process raft request' (duration: 256.667315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:45.871958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.368166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:45.87227Z","caller":"traceutil/trace.go:171","msg":"trace[412874971] linearizableReadLoop","detail":"{readStateIndex:26562; appliedIndex:26560; }","duration":"429.681ms","start":"2026-10-06T10:55:45.442542Z","end":"2026-10-06T10:55:45.872223Z","steps":["trace[412874971] 'read index received' (duration: 205.885756ms)","trace[412874971] 'applied index is now lower than readState.Index' (duration: 223.792554ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:45.872345Z","caller":"traceutil/trace.go:171","msg":"trace[903226215] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"216.415355ms","start":"2026-10-06T10:55:45.65591Z","end":"2026-10-06T10:55:45.872326Z","steps":["trace[903226215] 'process raft request' (duration: 216.350674ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.872411Z","caller":"traceutil/trace.go:171","msg":"trace[1636330846] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"222.607122ms","start":"2026-10-06T10:55:45.649772Z","end":"2026-10-06T10:55:45.872379Z","steps":["trace[1636330846] 'process raft request' (duration: 222.330357ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.87264Z","caller":"traceutil/trace.go:171","msg":"trace[1419376775] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"220.995882ms","start":"2026-10-06T10:55:45.651605Z","end":"2026-10-06T10:55:45.872601Z","steps":["trace[1419376775] 'process raft request' (duration: 220.592665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:45.872737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.179709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:55:45.873025Z","caller":"traceutil/trace.go:171","msg":"trace[1378746418] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25715; }","duration":"430.368653ms","start":"2026-10-06T10:55:45.442465Z","end":"2026-10-06T10:55:45.872834Z","steps":["trace[1378746418] 'agreement among raft nodes before linearized reading' (duration: 430.127608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:45.873118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.115185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-06T10:55:45.873205Z","caller":"traceutil/trace.go:171","msg":"trace[1872764692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25715; }","duration":"140.257348ms","start":"2026-10-06T10:55:45.732925Z","end":"2026-10-06T10:55:45.873182Z","steps":["trace[1872764692] 'agreement among raft nodes before linearized reading' (duration: 140.001293ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:45.873333Z","caller":"traceutil/trace.go:171","msg":"trace[669452926] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"464.869674ms","start":"2026-10-06T10:55:45.407464Z","end":"2026-10-06T10:55:45.872334Z","steps":["trace[669452926] 'process raft request' (duration: 284.948998ms)","trace[669452926] 'compare' (duration: 179.241543ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:45.87343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:45.40743Z","time spent":"465.955944ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:45.873689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.632202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-06T10:55:45.873744Z","caller":"traceutil/trace.go:171","msg":"trace[681525233] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25715; }","duration":"142.732134ms","start":"2026-10-06T10:55:45.730996Z","end":"2026-10-06T10:55:45.873729Z","steps":["trace[681525233] 'agreement among raft nodes before linearized reading' (duration: 141.839557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:45.873093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:45.442444Z","time spent":"430.633538ms","remote":"127.0.0.1:58694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-06T10:55:46.206391Z","caller":"traceutil/trace.go:171","msg":"trace[1923174898] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"326.199996ms","start":"2026-10-06T10:55:45.880161Z","end":"2026-10-06T10:55:46.206361Z","steps":["trace[1923174898] 'process raft request' (duration: 326.083204ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:46.206458Z","caller":"traceutil/trace.go:171","msg":"trace[251517537] linearizableReadLoop","detail":"{readStateIndex:26566; appliedIndex:26565; }","duration":"328.048592ms","start":"2026-10-06T10:55:45.878375Z","end":"2026-10-06T10:55:46.206424Z","steps":["trace[251517537] 'read index received' (duration: 305.638569ms)","trace[251517537] 'applied index is now lower than readState.Index' (duration: 22.406753ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:46.206581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:45.880144Z","time spent":"326.332679ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:46.206752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.347528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-06T10:55:46.206815Z","caller":"traceutil/trace.go:171","msg":"trace[445993368] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25717; }","duration":"328.448599ms","start":"2026-10-06T10:55:45.878348Z","end":"2026-10-06T10:55:46.206796Z","steps":["trace[445993368] 'agreement among raft nodes before linearized reading' (duration: 328.225455ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:46.206427Z","caller":"traceutil/trace.go:171","msg":"trace[1729549981] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"328.52929ms","start":"2026-10-06T10:55:45.877873Z","end":"2026-10-06T10:55:46.206402Z","steps":["trace[1729549981] 'process raft request' (duration: 306.142167ms)","trace[1729549981] 'compare' (duration: 21.630409ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:46.206857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:45.878332Z","time spent":"328.513571ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-06T10:55:46.206957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:45.877852Z","time spent":"329.0269ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:46.207073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.881185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:46.207222Z","caller":"traceutil/trace.go:171","msg":"trace[1714463224] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25717; }","duration":"224.065809ms","start":"2026-10-06T10:55:45.983105Z","end":"2026-10-06T10:55:46.20717Z","steps":["trace[1714463224] 'agreement among raft nodes before linearized reading' (duration: 223.890815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:46.763487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.700902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:46.763559Z","caller":"traceutil/trace.go:171","msg":"trace[1749258666] linearizableReadLoop","detail":"{readStateIndex:26568; appliedIndex:26567; }","duration":"486.027173ms","start":"2026-10-06T10:55:46.27752Z","end":"2026-10-06T10:55:46.763547Z","steps":["trace[1749258666] 'read index received' (duration: 191.126027ms)","trace[1749258666] 'applied index is now lower than readState.Index' (duration: 294.900426ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:46.763606Z","caller":"traceutil/trace.go:171","msg":"trace[362059037] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"548.88392ms","start":"2026-10-06T10:55:46.214714Z","end":"2026-10-06T10:55:46.763598Z","steps":["trace[362059037] 'process raft request' (duration: 253.999794ms)","trace[362059037] 'compare' (duration: 294.432497ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:46.763639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.214692Z","time spent":"548.928031ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:46.764161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.221657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:46.764276Z","caller":"traceutil/trace.go:171","msg":"trace[909967852] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25718; }","duration":"486.725477ms","start":"2026-10-06T10:55:46.27751Z","end":"2026-10-06T10:55:46.764236Z","steps":["trace[909967852] 'agreement among raft nodes before linearized reading' (duration: 486.157316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:46.764321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.277457Z","time spent":"486.855479ms","remote":"127.0.0.1:33136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-06T10:55:46.766832Z","caller":"traceutil/trace.go:171","msg":"trace[769220049] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"260.860534ms","start":"2026-10-06T10:55:46.505726Z","end":"2026-10-06T10:55:46.766586Z","steps":["trace[769220049] 'process raft request' (duration: 260.738352ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:46.768526Z","caller":"traceutil/trace.go:171","msg":"trace[1123707877] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"229.366869ms","start":"2026-10-06T10:55:46.539138Z","end":"2026-10-06T10:55:46.768505Z","steps":["trace[1123707877] 'process raft request' (duration: 229.211687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:46.768692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.370268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-06T10:55:46.768792Z","caller":"traceutil/trace.go:171","msg":"trace[1134501197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25720; }","duration":"398.512861ms","start":"2026-10-06T10:55:46.370265Z","end":"2026-10-06T10:55:46.768778Z","steps":["trace[1134501197] 'agreement among raft nodes before linearized reading' (duration: 398.303667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:46.768831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.370244Z","time spent":"398.577592ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-06T10:55:46.768672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.011332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-06T10:55:46.768934Z","caller":"traceutil/trace.go:171","msg":"trace[1547190803] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25720; }","duration":"264.321079ms","start":"2026-10-06T10:55:46.50459Z","end":"2026-10-06T10:55:46.768911Z","steps":["trace[1547190803] 'agreement among raft nodes before linearized reading' (duration: 263.921941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.088322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.425818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:47.088672Z","caller":"traceutil/trace.go:171","msg":"trace[825084623] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"316.068475ms","start":"2026-10-06T10:55:46.77258Z","end":"2026-10-06T10:55:47.088649Z","steps":["trace[825084623] 'process raft request' (duration: 119.195179ms)","trace[825084623] 'compare' (duration: 195.99743ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:47.088757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.772563Z","time spent":"316.154197ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:55:47.169934Z","caller":"traceutil/trace.go:171","msg":"trace[635790822] linearizableReadLoop","detail":"{readStateIndex:26572; appliedIndex:26570; }","duration":"390.201345ms","start":"2026-10-06T10:55:46.77967Z","end":"2026-10-06T10:55:47.169871Z","steps":["trace[635790822] 'read index received' (duration: 112.119326ms)","trace[635790822] 'applied index is now lower than readState.Index' (duration: 278.080859ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:47.170278Z","caller":"traceutil/trace.go:171","msg":"trace[26388669] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"396.235829ms","start":"2026-10-06T10:55:46.774027Z","end":"2026-10-06T10:55:47.170263Z","steps":["trace[26388669] 'process raft request' (duration: 395.653248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.17041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.773993Z","time spent":"396.32154ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:47.170509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.808027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-06T10:55:47.170596Z","caller":"traceutil/trace.go:171","msg":"trace[983436513] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25722; }","duration":"390.940649ms","start":"2026-10-06T10:55:46.779634Z","end":"2026-10-06T10:55:47.170575Z","steps":["trace[983436513] 'agreement among raft nodes before linearized reading' (duration: 390.668524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.170646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.779623Z","time spent":"391.01469ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-06T10:55:47.456327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.814847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:55:47.45655Z","caller":"traceutil/trace.go:171","msg":"trace[637836635] linearizableReadLoop","detail":"{readStateIndex:26575; appliedIndex:26572; }","duration":"286.494727ms","start":"2026-10-06T10:55:47.170034Z","end":"2026-10-06T10:55:47.456529Z","steps":["trace[637836635] 'read index received' (duration: 38.377784ms)","trace[637836635] 'applied index is now lower than readState.Index' (duration: 248.115353ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:55:47.456708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.982087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:47.456789Z","caller":"traceutil/trace.go:171","msg":"trace[1462952518] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25725; }","duration":"393.062729ms","start":"2026-10-06T10:55:47.063707Z","end":"2026-10-06T10:55:47.45677Z","steps":["trace[1462952518] 'agreement among raft nodes before linearized reading' (duration: 392.940867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.456865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:47.063649Z","time spent":"393.205142ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-10-06T10:55:47.456908Z","caller":"traceutil/trace.go:171","msg":"trace[23315860] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"424.808709ms","start":"2026-10-06T10:55:47.032061Z","end":"2026-10-06T10:55:47.45687Z","steps":["trace[23315860] 'process raft request' (duration: 424.421161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.456932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.525861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-06T10:55:47.456904Z","caller":"traceutil/trace.go:171","msg":"trace[1215995355] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"425.817157ms","start":"2026-10-06T10:55:47.031018Z","end":"2026-10-06T10:55:47.456835Z","steps":["trace[1215995355] 'process raft request' (duration: 425.405709ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:47.456968Z","caller":"traceutil/trace.go:171","msg":"trace[139494679] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"531.768947ms","start":"2026-10-06T10:55:46.925149Z","end":"2026-10-06T10:55:47.456918Z","steps":["trace[139494679] 'process raft request' (duration: 283.263696ms)","trace[139494679] 'compare' (duration: 247.688405ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:47.457024Z","caller":"traceutil/trace.go:171","msg":"trace[1638238926] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25725; }","duration":"284.714154ms","start":"2026-10-06T10:55:47.17229Z","end":"2026-10-06T10:55:47.457004Z","steps":["trace[1638238926] 'agreement among raft nodes before linearized reading' (duration: 284.393938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.457163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.842405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:47.457336Z","caller":"traceutil/trace.go:171","msg":"trace[206847338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25725; }","duration":"651.100138ms","start":"2026-10-06T10:55:46.806204Z","end":"2026-10-06T10:55:47.457305Z","steps":["trace[206847338] 'agreement among raft nodes before linearized reading' (duration: 650.765573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.456989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:47.032049Z","time spent":"424.90087ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:47.457533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:47.03099Z","time spent":"426.050591ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:47.457581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.925123Z","time spent":"532.298255ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:55:47.457458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:46.806187Z","time spent":"651.252142ms","remote":"127.0.0.1:60926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-06T10:55:47.891739Z","caller":"traceutil/trace.go:171","msg":"trace[882347685] linearizableReadLoop","detail":"{readStateIndex:26576; appliedIndex:26575; }","duration":"420.655869ms","start":"2026-10-06T10:55:47.47106Z","end":"2026-10-06T10:55:47.891716Z","steps":["trace[882347685] 'read index received' (duration: 420.479145ms)","trace[882347685] 'applied index is now lower than readState.Index' (duration: 175.644µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:55:47.892089Z","caller":"traceutil/trace.go:171","msg":"trace[953893734] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"423.045865ms","start":"2026-10-06T10:55:47.469029Z","end":"2026-10-06T10:55:47.892075Z","steps":["trace[953893734] 'process raft request' (duration: 422.542995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.89219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:47.469006Z","time spent":"423.122267ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-06T10:55:47.892476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.399213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-06T10:55:47.892548Z","caller":"traceutil/trace.go:171","msg":"trace[1212453410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25726; }","duration":"421.506295ms","start":"2026-10-06T10:55:47.471022Z","end":"2026-10-06T10:55:47.892528Z","steps":["trace[1212453410] 'agreement among raft nodes before linearized reading' (duration: 421.285761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.892597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:55:47.471Z","time spent":"421.586057ms","remote":"127.0.0.1:58774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-06T10:55:47.892906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.101653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-06T10:55:47.892933Z","caller":"traceutil/trace.go:171","msg":"trace[433061518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25729; }","duration":"239.215676ms","start":"2026-10-06T10:55:47.653711Z","end":"2026-10-06T10:55:47.892926Z","steps":["trace[433061518] 'agreement among raft nodes before linearized reading' (duration: 239.136154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.893077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.879893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:47.893115Z","caller":"traceutil/trace.go:171","msg":"trace[1985753435] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25729; }","duration":"119.923063ms","start":"2026-10-06T10:55:47.773181Z","end":"2026-10-06T10:55:47.893105Z","steps":["trace[1985753435] 'agreement among raft nodes before linearized reading' (duration: 119.858102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:55:47.893125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.637958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:55:47.893171Z","caller":"traceutil/trace.go:171","msg":"trace[362470139] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25729; }","duration":"146.689139ms","start":"2026-10-06T10:55:47.74647Z","end":"2026-10-06T10:55:47.893159Z","steps":["trace[362470139] 'agreement among raft nodes before linearized reading' (duration: 146.612408ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:55:49.348867Z","caller":"traceutil/trace.go:171","msg":"trace[2093173001] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"168.897838ms","start":"2026-10-06T10:55:49.179938Z","end":"2026-10-06T10:55:49.348836Z","steps":["trace[2093173001] 'process raft request' (duration: 168.677644ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:05.413398Z","caller":"traceutil/trace.go:171","msg":"trace[1915853787] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"128.24745ms","start":"2026-10-06T10:56:05.285108Z","end":"2026-10-06T10:56:05.413355Z","steps":["trace[1915853787] 'process raft request' (duration: 128.036867ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:05.41334Z","caller":"traceutil/trace.go:171","msg":"trace[792985400] linearizableReadLoop","detail":"{readStateIndex:26798; appliedIndex:26797; }","duration":"125.968898ms","start":"2026-10-06T10:56:05.287347Z","end":"2026-10-06T10:56:05.413316Z","steps":["trace[792985400] 'read index received' (duration: 125.768994ms)","trace[792985400] 'applied index is now lower than readState.Index' (duration: 198.764µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:05.413575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.197751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18dbeb7132a9c744\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-10-06T10:56:05.413649Z","caller":"traceutil/trace.go:171","msg":"trace[227592369] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18dbeb7132a9c744; range_end:; response_count:1; response_revision:25943; }","duration":"126.312993ms","start":"2026-10-06T10:56:05.287318Z","end":"2026-10-06T10:56:05.413631Z","steps":["trace[227592369] 'agreement among raft nodes before linearized reading' (duration: 126.104339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:05.413867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.45463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1448"} {"level":"info","ts":"2026-10-06T10:56:05.413934Z","caller":"traceutil/trace.go:171","msg":"trace[1400485394] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25943; }","duration":"123.538391ms","start":"2026-10-06T10:56:05.290383Z","end":"2026-10-06T10:56:05.413922Z","steps":["trace[1400485394] 'agreement among raft nodes before linearized reading' (duration: 123.350448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:05.687906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.13146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:56:05.688239Z","caller":"traceutil/trace.go:171","msg":"trace[66377211] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"271.760479ms","start":"2026-10-06T10:56:05.416448Z","end":"2026-10-06T10:56:05.688208Z","steps":["trace[66377211] 'process raft request' (duration: 171.181951ms)","trace[66377211] 'compare' (duration: 99.716963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:05.688421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.359643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-10-06T10:56:05.688534Z","caller":"traceutil/trace.go:171","msg":"trace[105688701] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25944; }","duration":"270.503075ms","start":"2026-10-06T10:56:05.418009Z","end":"2026-10-06T10:56:05.688512Z","steps":["trace[105688701] 'agreement among raft nodes before linearized reading' (duration: 270.267871ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:05.688785Z","caller":"traceutil/trace.go:171","msg":"trace[1765282467] linearizableReadLoop","detail":"{readStateIndex:26799; appliedIndex:26798; }","duration":"269.961175ms","start":"2026-10-06T10:56:05.418024Z","end":"2026-10-06T10:56:05.687985Z","steps":["trace[1765282467] 'read index received' (duration: 169.621351ms)","trace[1765282467] 'applied index is now lower than readState.Index' (duration: 100.338504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:05.96077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.187929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:56:05.961195Z","caller":"traceutil/trace.go:171","msg":"trace[1057566357] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"429.273913ms","start":"2026-10-06T10:56:05.531901Z","end":"2026-10-06T10:56:05.961175Z","steps":["trace[1057566357] 'process raft request' (duration: 429.13737ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:05.961177Z","caller":"traceutil/trace.go:171","msg":"trace[28125748] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"444.852976ms","start":"2026-10-06T10:56:05.516267Z","end":"2026-10-06T10:56:05.96112Z","steps":["trace[28125748] 'process raft request' (duration: 307.204488ms)","trace[28125748] 'compare' (duration: 137.059076ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:05.961283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:05.53182Z","time spent":"429.426185ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:56:05.961361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:05.516227Z","time spent":"445.035989ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:56:05.961431Z","caller":"traceutil/trace.go:171","msg":"trace[1621187404] linearizableReadLoop","detail":"{readStateIndex:26802; appliedIndex:26799; }","duration":"272.601366ms","start":"2026-10-06T10:56:05.68882Z","end":"2026-10-06T10:56:05.961421Z","steps":["trace[1621187404] 'read index received' (duration: 134.507609ms)","trace[1621187404] 'applied index is now lower than readState.Index' (duration: 138.092627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:05.96158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.039684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-06T10:56:05.96161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.374492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-06T10:56:05.961619Z","caller":"traceutil/trace.go:171","msg":"trace[50829637] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25947; }","duration":"326.119116ms","start":"2026-10-06T10:56:05.635488Z","end":"2026-10-06T10:56:05.961607Z","steps":["trace[50829637] 'agreement among raft nodes before linearized reading' (duration: 326.003954ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:05.961641Z","caller":"traceutil/trace.go:171","msg":"trace[2144064239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25947; }","duration":"538.411012ms","start":"2026-10-06T10:56:05.423217Z","end":"2026-10-06T10:56:05.961628Z","steps":["trace[2144064239] 'agreement among raft nodes before linearized reading' (duration: 538.27685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:05.961648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:05.635466Z","time spent":"326.173117ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-06T10:56:05.961666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:05.423212Z","time spent":"538.448123ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-06T10:56:05.961803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.846474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18dbeb7132f60ffe\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-10-06T10:56:05.961835Z","caller":"traceutil/trace.go:171","msg":"trace[519801887] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18dbeb7132f60ffe; range_end:; response_count:1; response_revision:25947; }","duration":"269.916455ms","start":"2026-10-06T10:56:05.69191Z","end":"2026-10-06T10:56:05.961827Z","steps":["trace[519801887] 'agreement among raft nodes before linearized reading' (duration: 269.825414ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:05.961906Z","caller":"traceutil/trace.go:171","msg":"trace[747223741] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"426.624012ms","start":"2026-10-06T10:56:05.535244Z","end":"2026-10-06T10:56:05.961868Z","steps":["trace[747223741] 'process raft request' (duration: 425.874938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:05.961974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:05.535215Z","time spent":"426.723654ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:56:05.962237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.782587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:56:05.962275Z","caller":"traceutil/trace.go:171","msg":"trace[321863670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25947; }","duration":"264.850679ms","start":"2026-10-06T10:56:05.697415Z","end":"2026-10-06T10:56:05.962265Z","steps":["trace[321863670] 'agreement among raft nodes before linearized reading' (duration: 264.793478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:05.962495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.516934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:56:05.962529Z","caller":"traceutil/trace.go:171","msg":"trace[1870457104] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25947; }","duration":"182.590836ms","start":"2026-10-06T10:56:05.77993Z","end":"2026-10-06T10:56:05.962521Z","steps":["trace[1870457104] 'agreement among raft nodes before linearized reading' (duration: 182.473533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:05.962669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.919464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-06T10:56:05.962775Z","caller":"traceutil/trace.go:171","msg":"trace[271006707] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25947; }","duration":"270.080337ms","start":"2026-10-06T10:56:05.692672Z","end":"2026-10-06T10:56:05.962752Z","steps":["trace[271006707] 'agreement among raft nodes before linearized reading' (duration: 269.796582ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.235688Z","caller":"traceutil/trace.go:171","msg":"trace[1705328483] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"269.891174ms","start":"2026-10-06T10:56:05.965771Z","end":"2026-10-06T10:56:06.235662Z","steps":["trace[1705328483] 'process raft request' (duration: 269.799993ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.235698Z","caller":"traceutil/trace.go:171","msg":"trace[927689714] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"270.325982ms","start":"2026-10-06T10:56:05.965341Z","end":"2026-10-06T10:56:06.235667Z","steps":["trace[927689714] 'process raft request' (duration: 270.060107ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.236031Z","caller":"traceutil/trace.go:171","msg":"trace[1518106842] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"269.15579ms","start":"2026-10-06T10:56:05.966862Z","end":"2026-10-06T10:56:06.236018Z","steps":["trace[1518106842] 'process raft request' (duration: 268.758753ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.236016Z","caller":"traceutil/trace.go:171","msg":"trace[1668331980] linearizableReadLoop","detail":"{readStateIndex:26807; appliedIndex:26802; }","duration":"261.338143ms","start":"2026-10-06T10:56:05.974658Z","end":"2026-10-06T10:56:06.235996Z","steps":["trace[1668331980] 'read index received' (duration: 61.323107ms)","trace[1668331980] 'applied index is now lower than readState.Index' (duration: 200.013776ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:06.236209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.537626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-10-06T10:56:06.236254Z","caller":"traceutil/trace.go:171","msg":"trace[1106348803] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25952; }","duration":"261.659808ms","start":"2026-10-06T10:56:05.974581Z","end":"2026-10-06T10:56:06.236241Z","steps":["trace[1106348803] 'agreement among raft nodes before linearized reading' (duration: 261.525346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:06.236262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.173293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-06T10:56:06.236249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.709757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:56:06.236292Z","caller":"traceutil/trace.go:171","msg":"trace[2041523430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25952; }","duration":"258.275245ms","start":"2026-10-06T10:56:05.978008Z","end":"2026-10-06T10:56:06.236283Z","steps":["trace[2041523430] 'agreement among raft nodes before linearized reading' (duration: 258.116112ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.236329Z","caller":"traceutil/trace.go:171","msg":"trace[1778072461] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25952; }","duration":"246.808929ms","start":"2026-10-06T10:56:05.989501Z","end":"2026-10-06T10:56:06.23631Z","steps":["trace[1778072461] 'agreement among raft nodes before linearized reading' (duration: 246.663786ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.236092Z","caller":"traceutil/trace.go:171","msg":"trace[2101385219] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"268.226694ms","start":"2026-10-06T10:56:05.967821Z","end":"2026-10-06T10:56:06.236048Z","steps":["trace[2101385219] 'process raft request' (duration: 268.04567ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:06.662487Z","caller":"traceutil/trace.go:171","msg":"trace[1492888627] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"337.00008ms","start":"2026-10-06T10:56:06.325442Z","end":"2026-10-06T10:56:06.662442Z","steps":["trace[1492888627] 'process raft request' (duration: 336.713564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:06.662689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:06.325405Z","time spent":"337.179513ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:56:10.390052Z","caller":"traceutil/trace.go:171","msg":"trace[270073166] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"138.741149ms","start":"2026-10-06T10:56:10.251283Z","end":"2026-10-06T10:56:10.390024Z","steps":["trace[270073166] 'process raft request' (duration: 138.546056ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:10.390251Z","caller":"traceutil/trace.go:171","msg":"trace[1002025807] linearizableReadLoop","detail":"{readStateIndex:26839; appliedIndex:26836; }","duration":"132.47781ms","start":"2026-10-06T10:56:10.25776Z","end":"2026-10-06T10:56:10.390238Z","steps":["trace[1002025807] 'read index received' (duration: 41.89056ms)","trace[1002025807] 'applied index is now lower than readState.Index' (duration: 90.58612ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:10.3904Z","caller":"traceutil/trace.go:171","msg":"trace[418734453] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"134.117561ms","start":"2026-10-06T10:56:10.256274Z","end":"2026-10-06T10:56:10.390392Z","steps":["trace[418734453] 'process raft request' (duration: 133.706304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:10.390719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.932479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-06T10:56:10.390776Z","caller":"traceutil/trace.go:171","msg":"trace[1030809351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25983; }","duration":"133.036582ms","start":"2026-10-06T10:56:10.257726Z","end":"2026-10-06T10:56:10.390762Z","steps":["trace[1030809351] 'agreement among raft nodes before linearized reading' (duration: 132.857978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:10.39086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.522312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-06T10:56:10.390972Z","caller":"traceutil/trace.go:171","msg":"trace[455177785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25983; }","duration":"123.664273ms","start":"2026-10-06T10:56:10.267287Z","end":"2026-10-06T10:56:10.390951Z","steps":["trace[455177785] 'agreement among raft nodes before linearized reading' (duration: 123.421249ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:10.53502Z","caller":"traceutil/trace.go:171","msg":"trace[1579439518] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"136.20602ms","start":"2026-10-06T10:56:10.398784Z","end":"2026-10-06T10:56:10.534991Z","steps":["trace[1579439518] 'process raft request' (duration: 78.331108ms)","trace[1579439518] 'compare' (duration: 57.74626ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:10.567917Z","caller":"traceutil/trace.go:171","msg":"trace[1446277073] linearizableReadLoop","detail":"{readStateIndex:26841; appliedIndex:26839; }","duration":"143.422806ms","start":"2026-10-06T10:56:10.424466Z","end":"2026-10-06T10:56:10.567889Z","steps":["trace[1446277073] 'read index received' (duration: 52.607032ms)","trace[1446277073] 'applied index is now lower than readState.Index' (duration: 90.814624ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:10.568125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.63609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-06T10:56:10.568166Z","caller":"traceutil/trace.go:171","msg":"trace[879088085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25985; }","duration":"143.693451ms","start":"2026-10-06T10:56:10.424459Z","end":"2026-10-06T10:56:10.568153Z","steps":["trace[879088085] 'agreement among raft nodes before linearized reading' (duration: 143.523088ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:10.568472Z","caller":"traceutil/trace.go:171","msg":"trace[1198662585] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"160.116583ms","start":"2026-10-06T10:56:10.408341Z","end":"2026-10-06T10:56:10.568458Z","steps":["trace[1198662585] 'process raft request' (duration: 159.380028ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:10.762791Z","caller":"traceutil/trace.go:171","msg":"trace[754599433] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"190.431594ms","start":"2026-10-06T10:56:10.572323Z","end":"2026-10-06T10:56:10.762755Z","steps":["trace[754599433] 'process raft request' (duration: 102.911802ms)","trace[754599433] 'compare' (duration: 87.219346ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:11.043528Z","caller":"traceutil/trace.go:171","msg":"trace[2073704043] linearizableReadLoop","detail":"{readStateIndex:26844; appliedIndex:26843; }","duration":"135.600808ms","start":"2026-10-06T10:56:10.907905Z","end":"2026-10-06T10:56:11.043506Z","steps":["trace[2073704043] 'read index received' (duration: 135.402235ms)","trace[2073704043] 'applied index is now lower than readState.Index' (duration: 197.733µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:11.043853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.902394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:56:11.043912Z","caller":"traceutil/trace.go:171","msg":"trace[2072097373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25988; }","duration":"136.106998ms","start":"2026-10-06T10:56:10.907795Z","end":"2026-10-06T10:56:11.043902Z","steps":["trace[2072097373] 'agreement among raft nodes before linearized reading' (duration: 135.797662ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:11.044304Z","caller":"traceutil/trace.go:171","msg":"trace[121857273] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"169.107032ms","start":"2026-10-06T10:56:10.875185Z","end":"2026-10-06T10:56:11.044292Z","steps":["trace[121857273] 'process raft request' (duration: 168.185364ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:16.511816Z","caller":"traceutil/trace.go:171","msg":"trace[84415456] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"101.735391ms","start":"2026-10-06T10:56:16.410049Z","end":"2026-10-06T10:56:16.511785Z","steps":["trace[84415456] 'process raft request' (duration: 21.123399ms)","trace[84415456] 'compare' (duration: 80.305476ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:16.545601Z","caller":"traceutil/trace.go:171","msg":"trace[694150216] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"134.422408ms","start":"2026-10-06T10:56:16.411152Z","end":"2026-10-06T10:56:16.545574Z","steps":["trace[694150216] 'process raft request' (duration: 134.279345ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:26.200389Z","caller":"traceutil/trace.go:171","msg":"trace[2117500088] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"120.123008ms","start":"2026-10-06T10:56:26.080244Z","end":"2026-10-06T10:56:26.200367Z","steps":["trace[2117500088] 'process raft request' (duration: 119.961584ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:26.407806Z","caller":"traceutil/trace.go:171","msg":"trace[1824587943] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"321.59467ms","start":"2026-10-06T10:56:26.086184Z","end":"2026-10-06T10:56:26.407779Z","steps":["trace[1824587943] 'process raft request' (duration: 321.263754ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:26.407912Z","caller":"traceutil/trace.go:171","msg":"trace[804747309] linearizableReadLoop","detail":"{readStateIndex:26967; appliedIndex:26965; }","duration":"220.571982ms","start":"2026-10-06T10:56:26.187317Z","end":"2026-10-06T10:56:26.407889Z","steps":["trace[804747309] 'read index received' (duration: 13.062896ms)","trace[804747309] 'applied index is now lower than readState.Index' (duration: 207.507406ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:26.407943Z","caller":"traceutil/trace.go:171","msg":"trace[1922564114] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"321.715652ms","start":"2026-10-06T10:56:26.086202Z","end":"2026-10-06T10:56:26.407917Z","steps":["trace[1922564114] 'process raft request' (duration: 321.530038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:26.408145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:26.086162Z","time spent":"321.862105ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:56:26.408093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.758957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-06T10:56:26.408432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:26.086175Z","time spent":"322.07972ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:56:26.408474Z","caller":"traceutil/trace.go:171","msg":"trace[1869372399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26108; }","duration":"221.171244ms","start":"2026-10-06T10:56:26.187266Z","end":"2026-10-06T10:56:26.408438Z","steps":["trace[1869372399] 'agreement among raft nodes before linearized reading' (duration: 220.686595ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:28.005504Z","caller":"traceutil/trace.go:171","msg":"trace[1292010052] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"346.736725ms","start":"2026-10-06T10:56:27.658741Z","end":"2026-10-06T10:56:28.005478Z","steps":["trace[1292010052] 'process raft request' (duration: 346.554001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.005675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:27.658726Z","time spent":"346.856547ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:56:28.244529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.632562ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-06T10:56:28.244822Z","caller":"traceutil/trace.go:171","msg":"trace[101315462] linearizableReadLoop","detail":"{readStateIndex:26979; appliedIndex:26977; }","duration":"438.238511ms","start":"2026-10-06T10:56:27.806367Z","end":"2026-10-06T10:56:28.244606Z","steps":["trace[101315462] 'read index received' (duration: 198.842483ms)","trace[101315462] 'applied index is now lower than readState.Index' (duration: 239.393718ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:28.24496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.567057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:56:28.245039Z","caller":"traceutil/trace.go:171","msg":"trace[1098393424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26119; }","duration":"438.70179ms","start":"2026-10-06T10:56:27.806315Z","end":"2026-10-06T10:56:28.245017Z","steps":["trace[1098393424] 'agreement among raft nodes before linearized reading' (duration: 438.544807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.245093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:27.806289Z","time spent":"438.796502ms","remote":"127.0.0.1:60926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-06T10:56:28.363714Z","caller":"traceutil/trace.go:171","msg":"trace[1417659688] linearizableReadLoop","detail":"{readStateIndex:26980; appliedIndex:26979; }","duration":"118.839294ms","start":"2026-10-06T10:56:28.244845Z","end":"2026-10-06T10:56:28.363684Z","steps":["trace[1417659688] 'read index received' (duration: 118.572098ms)","trace[1417659688] 'applied index is now lower than readState.Index' (duration: 266.076µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:28.363949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.714711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:56:28.363987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.567925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:56:28.364029Z","caller":"traceutil/trace.go:171","msg":"trace[1197733543] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26120; }","duration":"315.683878ms","start":"2026-10-06T10:56:28.048331Z","end":"2026-10-06T10:56:28.364015Z","steps":["trace[1197733543] 'agreement among raft nodes before linearized reading' (duration: 315.562247ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:28.364079Z","caller":"traceutil/trace.go:171","msg":"trace[723617204] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26120; }","duration":"162.873653ms","start":"2026-10-06T10:56:28.201136Z","end":"2026-10-06T10:56:28.36401Z","steps":["trace[723617204] 'agreement among raft nodes before linearized reading' (duration: 162.67143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.364091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.000966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:56:28.364146Z","caller":"traceutil/trace.go:171","msg":"trace[955156701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26120; }","duration":"118.092388ms","start":"2026-10-06T10:56:28.24604Z","end":"2026-10-06T10:56:28.364132Z","steps":["trace[955156701] 'agreement among raft nodes before linearized reading' (duration: 117.994106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.364086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:28.048312Z","time spent":"315.760069ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"info","ts":"2026-10-06T10:56:28.364286Z","caller":"traceutil/trace.go:171","msg":"trace[1693472759] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"158.718485ms","start":"2026-10-06T10:56:28.20537Z","end":"2026-10-06T10:56:28.364088Z","steps":["trace[1693472759] 'process raft request' (duration: 158.091894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.364236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.801776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:56:28.364457Z","caller":"traceutil/trace.go:171","msg":"trace[1782045479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26120; }","duration":"142.387737ms","start":"2026-10-06T10:56:28.221973Z","end":"2026-10-06T10:56:28.364361Z","steps":["trace[1782045479] 'agreement among raft nodes before linearized reading' (duration: 141.756525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.365822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.137035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-06T10:56:28.365936Z","caller":"traceutil/trace.go:171","msg":"trace[1571671697] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26120; }","duration":"469.316329ms","start":"2026-10-06T10:56:27.896601Z","end":"2026-10-06T10:56:28.365918Z","steps":["trace[1571671697] 'agreement among raft nodes before linearized reading' (duration: 467.530154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:28.36598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:27.896581Z","time spent":"469.38891ms","remote":"127.0.0.1:33218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2666,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-10-06T10:56:29.082963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.392278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:56:29.082977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.18932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-06T10:56:29.083002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.044095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-06T10:56:29.083053Z","caller":"traceutil/trace.go:171","msg":"trace[803614878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26127; }","duration":"276.53967ms","start":"2026-10-06T10:56:28.806494Z","end":"2026-10-06T10:56:29.083033Z","steps":["trace[803614878] 'range keys from in-memory index tree' (duration: 276.289485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:29.082944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.785045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-06T10:56:29.08307Z","caller":"traceutil/trace.go:171","msg":"trace[1277976323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26127; }","duration":"165.134956ms","start":"2026-10-06T10:56:28.917916Z","end":"2026-10-06T10:56:29.083051Z","steps":["trace[1277976323] 'range keys from in-memory index tree' (duration: 164.887061ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:29.083092Z","caller":"traceutil/trace.go:171","msg":"trace[673526844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26127; }","duration":"169.99682ms","start":"2026-10-06T10:56:28.913085Z","end":"2026-10-06T10:56:29.083082Z","steps":["trace[673526844] 'range keys from in-memory index tree' (duration: 169.585832ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:29.083055Z","caller":"traceutil/trace.go:171","msg":"trace[1702970252] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26127; }","duration":"261.296872ms","start":"2026-10-06T10:56:28.82174Z","end":"2026-10-06T10:56:29.083037Z","steps":["trace[1702970252] 'range keys from in-memory index tree' (duration: 260.969246ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:29.389646Z","caller":"traceutil/trace.go:171","msg":"trace[1828945943] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"300.785566ms","start":"2026-10-06T10:56:29.088835Z","end":"2026-10-06T10:56:29.389621Z","steps":["trace[1828945943] 'process raft request' (duration: 300.581542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:29.3898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:56:29.088808Z","time spent":"300.908829ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:56:29.389845Z","caller":"traceutil/trace.go:171","msg":"trace[1766729758] linearizableReadLoop","detail":"{readStateIndex:26990; appliedIndex:26988; }","duration":"234.147608ms","start":"2026-10-06T10:56:29.155667Z","end":"2026-10-06T10:56:29.389815Z","steps":["trace[1766729758] 'read index received' (duration: 233.68254ms)","trace[1766729758] 'applied index is now lower than readState.Index' (duration: 461.268µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:29.389981Z","caller":"traceutil/trace.go:171","msg":"trace[463141645] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"283.063052ms","start":"2026-10-06T10:56:29.106905Z","end":"2026-10-06T10:56:29.389968Z","steps":["trace[463141645] 'process raft request' (duration: 282.673555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:29.39014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.027136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-06T10:56:29.390172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.210433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-06T10:56:29.390215Z","caller":"traceutil/trace.go:171","msg":"trace[1255550961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26130; }","duration":"249.267045ms","start":"2026-10-06T10:56:29.140934Z","end":"2026-10-06T10:56:29.390201Z","steps":["trace[1255550961] 'agreement among raft nodes before linearized reading' (duration: 249.067291ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:29.390214Z","caller":"traceutil/trace.go:171","msg":"trace[1463448032] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26130; }","duration":"155.155689ms","start":"2026-10-06T10:56:29.235039Z","end":"2026-10-06T10:56:29.390195Z","steps":["trace[1463448032] 'agreement among raft nodes before linearized reading' (duration: 155.025526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:29.390194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.826685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-06T10:56:29.390287Z","caller":"traceutil/trace.go:171","msg":"trace[961579936] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26130; }","duration":"169.966708ms","start":"2026-10-06T10:56:29.220292Z","end":"2026-10-06T10:56:29.390258Z","steps":["trace[961579936] 'agreement among raft nodes before linearized reading' (duration: 169.697093ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:30.189644Z","caller":"traceutil/trace.go:171","msg":"trace[1974218569] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"100.539848ms","start":"2026-10-06T10:56:30.089077Z","end":"2026-10-06T10:56:30.189617Z","steps":["trace[1974218569] 'process raft request' (duration: 97.144135ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:32.479968Z","caller":"traceutil/trace.go:171","msg":"trace[819900991] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"102.77785ms","start":"2026-10-06T10:56:32.377169Z","end":"2026-10-06T10:56:32.479947Z","steps":["trace[819900991] 'process raft request' (duration: 102.584246ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:32.573104Z","caller":"traceutil/trace.go:171","msg":"trace[77075047] linearizableReadLoop","detail":"{readStateIndex:27010; appliedIndex:27007; }","duration":"115.600562ms","start":"2026-10-06T10:56:32.457486Z","end":"2026-10-06T10:56:32.573086Z","steps":["trace[77075047] 'read index received' (duration: 22.27102ms)","trace[77075047] 'applied index is now lower than readState.Index' (duration: 93.328982ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:32.573259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.764226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.223\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-06T10:56:32.57328Z","caller":"traceutil/trace.go:171","msg":"trace[1988845629] range","detail":"{range_begin:/registry/masterleases/162.253.55.223; range_end:; response_count:1; response_revision:26151; }","duration":"115.851007ms","start":"2026-10-06T10:56:32.457424Z","end":"2026-10-06T10:56:32.573275Z","steps":["trace[1988845629] 'agreement among raft nodes before linearized reading' (duration: 115.731214ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:32.57357Z","caller":"traceutil/trace.go:171","msg":"trace[938678193] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"138.495444ms","start":"2026-10-06T10:56:32.435067Z","end":"2026-10-06T10:56:32.573563Z","steps":["trace[938678193] 'process raft request' (duration: 137.854092ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:32.573667Z","caller":"traceutil/trace.go:171","msg":"trace[226877539] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"136.327764ms","start":"2026-10-06T10:56:32.437329Z","end":"2026-10-06T10:56:32.573657Z","steps":["trace[226877539] 'process raft request' (duration: 135.712382ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:32.573743Z","caller":"traceutil/trace.go:171","msg":"trace[1350397122] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"106.150104ms","start":"2026-10-06T10:56:32.467587Z","end":"2026-10-06T10:56:32.573737Z","steps":["trace[1350397122] 'process raft request' (duration: 105.477761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:50.420843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.461252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:56:50.421002Z","caller":"traceutil/trace.go:171","msg":"trace[1738061293] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"153.300594ms","start":"2026-10-06T10:56:50.267672Z","end":"2026-10-06T10:56:50.420973Z","steps":["trace[1738061293] 'process raft request' (duration: 12.614578ms)","trace[1738061293] 'compare' (duration: 140.129475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:50.651553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.833163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:56:50.651675Z","caller":"traceutil/trace.go:171","msg":"trace[1447010898] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:26282; }","duration":"110.006335ms","start":"2026-10-06T10:56:50.541643Z","end":"2026-10-06T10:56:50.651649Z","steps":["trace[1447010898] 'count revisions from in-memory index tree' (duration: 109.74177ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:50.73839Z","caller":"traceutil/trace.go:171","msg":"trace[1482007169] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"187.917947ms","start":"2026-10-06T10:56:50.550444Z","end":"2026-10-06T10:56:50.738362Z","steps":["trace[1482007169] 'process raft request' (duration: 187.719823ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:50.925016Z","caller":"traceutil/trace.go:171","msg":"trace[375251352] linearizableReadLoop","detail":"{readStateIndex:27153; appliedIndex:27148; }","duration":"171.155911ms","start":"2026-10-06T10:56:50.753826Z","end":"2026-10-06T10:56:50.924982Z","steps":["trace[375251352] 'read index received' (duration: 170.5771ms)","trace[375251352] 'applied index is now lower than readState.Index' (duration: 577.371µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:50.925467Z","caller":"traceutil/trace.go:171","msg":"trace[17645026] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"245.299481ms","start":"2026-10-06T10:56:50.680092Z","end":"2026-10-06T10:56:50.925392Z","steps":["trace[17645026] 'process raft request' (duration: 244.840542ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:50.925534Z","caller":"traceutil/trace.go:171","msg":"trace[2143833136] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"249.793674ms","start":"2026-10-06T10:56:50.675721Z","end":"2026-10-06T10:56:50.925515Z","steps":["trace[2143833136] 'process raft request' (duration: 249.132352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:50.925549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.71346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-06T10:56:50.925596Z","caller":"traceutil/trace.go:171","msg":"trace[745183305] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"265.257457ms","start":"2026-10-06T10:56:50.660292Z","end":"2026-10-06T10:56:50.925549Z","steps":["trace[745183305] 'process raft request' (duration: 264.270139ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:50.925623Z","caller":"traceutil/trace.go:171","msg":"trace[833926069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26288; }","duration":"171.838533ms","start":"2026-10-06T10:56:50.753762Z","end":"2026-10-06T10:56:50.925601Z","steps":["trace[833926069] 'agreement among raft nodes before linearized reading' (duration: 171.622119ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:50.925456Z","caller":"traceutil/trace.go:171","msg":"trace[1036582377] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"260.085288ms","start":"2026-10-06T10:56:50.665339Z","end":"2026-10-06T10:56:50.925424Z","steps":["trace[1036582377] 'process raft request' (duration: 259.405036ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:50.92557Z","caller":"traceutil/trace.go:171","msg":"trace[1384653679] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"251.975216ms","start":"2026-10-06T10:56:50.673538Z","end":"2026-10-06T10:56:50.925514Z","steps":["trace[1384653679] 'process raft request' (duration: 251.264213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:51.163223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.746544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:56:51.163479Z","caller":"traceutil/trace.go:171","msg":"trace[265548396] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"229.628644ms","start":"2026-10-06T10:56:50.933833Z","end":"2026-10-06T10:56:51.163462Z","steps":["trace[265548396] 'process raft request' (duration: 229.569753ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:51.163627Z","caller":"traceutil/trace.go:171","msg":"trace[689707654] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"232.301224ms","start":"2026-10-06T10:56:50.931287Z","end":"2026-10-06T10:56:51.163589Z","steps":["trace[689707654] 'process raft request' (duration: 124.094983ms)","trace[689707654] 'compare' (duration: 107.55678ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:51.163648Z","caller":"traceutil/trace.go:171","msg":"trace[1293104057] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"231.927887ms","start":"2026-10-06T10:56:50.931679Z","end":"2026-10-06T10:56:51.163606Z","steps":["trace[1293104057] 'process raft request' (duration: 231.657342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:51.472851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.667708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-06T10:56:51.472948Z","caller":"traceutil/trace.go:171","msg":"trace[1053995834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26293; }","duration":"137.816182ms","start":"2026-10-06T10:56:51.335115Z","end":"2026-10-06T10:56:51.472931Z","steps":["trace[1053995834] 'range keys from in-memory index tree' (duration: 137.497256ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:51.668519Z","caller":"traceutil/trace.go:171","msg":"trace[114884749] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"192.223768ms","start":"2026-10-06T10:56:51.47627Z","end":"2026-10-06T10:56:51.668493Z","steps":["trace[114884749] 'process raft request' (duration: 192.070665ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:56:51.756387Z","caller":"traceutil/trace.go:171","msg":"trace[1070193771] linearizableReadLoop","detail":"{readStateIndex:27160; appliedIndex:27158; }","duration":"268.840634ms","start":"2026-10-06T10:56:51.487508Z","end":"2026-10-06T10:56:51.756349Z","steps":["trace[1070193771] 'read index received' (duration: 180.894544ms)","trace[1070193771] 'applied index is now lower than readState.Index' (duration: 87.94448ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:56:51.756568Z","caller":"traceutil/trace.go:171","msg":"trace[133138729] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"279.492516ms","start":"2026-10-06T10:56:51.477058Z","end":"2026-10-06T10:56:51.75655Z","steps":["trace[133138729] 'process raft request' (duration: 220.052454ms)","trace[133138729] 'compare' (duration: 59.060025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:56:51.756801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.261821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-06T10:56:51.756895Z","caller":"traceutil/trace.go:171","msg":"trace[819232398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26295; }","duration":"261.374263ms","start":"2026-10-06T10:56:51.495484Z","end":"2026-10-06T10:56:51.756858Z","steps":["trace[819232398] 'agreement among raft nodes before linearized reading' (duration: 261.18815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:51.757096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.044046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:56:51.757128Z","caller":"traceutil/trace.go:171","msg":"trace[1816527036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26295; }","duration":"172.121968ms","start":"2026-10-06T10:56:51.584997Z","end":"2026-10-06T10:56:51.757119Z","steps":["trace[1816527036] 'agreement among raft nodes before linearized reading' (duration: 172.034456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:56:51.757102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.557807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-06T10:56:51.757207Z","caller":"traceutil/trace.go:171","msg":"trace[1665659735] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26295; }","duration":"269.7069ms","start":"2026-10-06T10:56:51.487474Z","end":"2026-10-06T10:56:51.757181Z","steps":["trace[1665659735] 'agreement among raft nodes before linearized reading' (duration: 269.518036ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:57:05.964491Z","caller":"traceutil/trace.go:171","msg":"trace[142314262] linearizableReadLoop","detail":"{readStateIndex:27413; appliedIndex:27412; }","duration":"138.78196ms","start":"2026-10-06T10:57:05.825685Z","end":"2026-10-06T10:57:05.964467Z","steps":["trace[142314262] 'read index received' (duration: 138.534536ms)","trace[142314262] 'applied index is now lower than readState.Index' (duration: 246.344µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:57:05.964678Z","caller":"traceutil/trace.go:171","msg":"trace[1554401139] transaction","detail":"{read_only:false; response_revision:26544; number_of_response:1; }","duration":"240.217064ms","start":"2026-10-06T10:57:05.724384Z","end":"2026-10-06T10:57:05.964601Z","steps":["trace[1554401139] 'process raft request' (duration: 239.908609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:57:05.964754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.052204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-06T10:57:05.964797Z","caller":"traceutil/trace.go:171","msg":"trace[2006852032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26544; }","duration":"139.149307ms","start":"2026-10-06T10:57:05.825635Z","end":"2026-10-06T10:57:05.964784Z","steps":["trace[2006852032] 'agreement among raft nodes before linearized reading' (duration: 138.977343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:57:05.964928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.499879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-06T10:57:05.964968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.369057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-06T10:57:05.965042Z","caller":"traceutil/trace.go:171","msg":"trace[493087866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26544; }","duration":"125.643011ms","start":"2026-10-06T10:57:05.839368Z","end":"2026-10-06T10:57:05.965011Z","steps":["trace[493087866] 'agreement among raft nodes before linearized reading' (duration: 125.352435ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:57:05.965066Z","caller":"traceutil/trace.go:171","msg":"trace[1144894180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26544; }","duration":"133.54582ms","start":"2026-10-06T10:57:05.831494Z","end":"2026-10-06T10:57:05.96504Z","steps":["trace[1144894180] 'agreement among raft nodes before linearized reading' (duration: 133.163623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:57:06.203487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.397634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-06T10:57:06.203762Z","caller":"traceutil/trace.go:171","msg":"trace[237519857] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"234.333273ms","start":"2026-10-06T10:57:05.969395Z","end":"2026-10-06T10:57:06.203729Z","steps":["trace[237519857] 'process raft request' (duration: 132.563392ms)","trace[237519857] 'compare' (duration: 101.170759ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:57:06.203817Z","caller":"traceutil/trace.go:171","msg":"trace[1108263466] linearizableReadLoop","detail":"{readStateIndex:27416; appliedIndex:27413; }","duration":"191.970813ms","start":"2026-10-06T10:57:06.011824Z","end":"2026-10-06T10:57:06.203795Z","steps":["trace[1108263466] 'read index received' (duration: 90.149581ms)","trace[1108263466] 'applied index is now lower than readState.Index' (duration: 101.820132ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:57:06.203887Z","caller":"traceutil/trace.go:171","msg":"trace[1216908965] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"233.702961ms","start":"2026-10-06T10:57:05.970084Z","end":"2026-10-06T10:57:06.203787Z","steps":["trace[1216908965] 'process raft request' (duration: 233.515217ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:57:06.203925Z","caller":"traceutil/trace.go:171","msg":"trace[1463349415] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"233.626529ms","start":"2026-10-06T10:57:05.970255Z","end":"2026-10-06T10:57:06.203882Z","steps":["trace[1463349415] 'process raft request' (duration: 233.473357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:57:06.203995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.155746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-06T10:57:06.204066Z","caller":"traceutil/trace.go:171","msg":"trace[515893523] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26547; }","duration":"192.260908ms","start":"2026-10-06T10:57:06.011787Z","end":"2026-10-06T10:57:06.204048Z","steps":["trace[515893523] 'agreement among raft nodes before linearized reading' (duration: 192.137915ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:59:58.946253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25219} {"level":"info","ts":"2026-10-06T10:59:59.097765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25219,"took":"147.262165ms","hash":1519914988,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":38473728,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-06T10:59:59.097831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1519914988,"revision":25219,"compact-revision":21955} {"level":"info","ts":"2026-10-06T11:01:29.670691Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":30006,"local-member-snapshot-index":20005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T11:01:29.676975Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30006} {"level":"info","ts":"2026-10-06T11:01:29.677159Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25006} {"level":"info","ts":"2026-10-06T11:04:58.95343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28175} {"level":"info","ts":"2026-10-06T11:04:59.126928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28175,"took":"165.865628ms","hash":851840803,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":32612352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-06T11:04:59.12702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":851840803,"revision":28175,"compact-revision":25219} {"level":"info","ts":"2026-10-06T11:09:58.962022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30606} {"level":"info","ts":"2026-10-06T11:09:59.124629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30606,"took":"155.399154ms","hash":277330467,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":30613504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-06T11:09:59.124714Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":277330467,"revision":30606,"compact-revision":28175} {"level":"info","ts":"2026-10-06T11:14:58.969571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32892} {"level":"info","ts":"2026-10-06T11:14:59.125129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32892,"took":"151.330337ms","hash":2689837891,"current-db-size-bytes":58040320,"current-db-size":"58 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-06T11:14:59.125199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2689837891,"revision":32892,"compact-revision":30606} {"level":"info","ts":"2026-10-06T11:19:58.979174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35119} {"level":"info","ts":"2026-10-06T11:19:59.128011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35119,"took":"145.226002ms","hash":3259141566,"current-db-size-bytes":58040320,"current-db-size":"58 MB","current-db-size-in-use-bytes":32432128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-06T11:19:59.128055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3259141566,"revision":35119,"compact-revision":32892} {"level":"info","ts":"2026-10-06T11:22:55.824528Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0f50f8a6c5f7d06","local-member-applied-index":40007,"local-member-snapshot-index":30006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-06T11:22:55.830232Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40007} {"level":"info","ts":"2026-10-06T11:22:55.830615Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35007} {"level":"info","ts":"2026-10-06T11:24:58.985906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37418} {"level":"info","ts":"2026-10-06T11:24:59.129107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37418,"took":"138.328047ms","hash":72244322,"current-db-size-bytes":60170240,"current-db-size":"60 MB","current-db-size-in-use-bytes":33259520,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-06T11:24:59.129179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":72244322,"revision":37418,"compact-revision":35119} {"level":"info","ts":"2026-10-06T11:29:58.99283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39692} {"level":"info","ts":"2026-10-06T11:29:59.123131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39692,"took":"126.297579ms","hash":484628963,"current-db-size-bytes":60170240,"current-db-size":"60 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-06T11:29:59.1232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":484628963,"revision":39692,"compact-revision":37418}