{"level":"warn","ts":"2026-10-17T07:56:45.882482Z","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-17T07:56:45.882599Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.57: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.57:2380","--initial-cluster=instance=https://162.253.55.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.57: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-17T07:56:45.882694Z","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-17T07:56:45.88271Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-10-17T07:56:45.882741Z","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-17T07:56:45.883757Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"]} {"level":"info","ts":"2026-10-17T07:56:45.883883Z","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.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.57: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-17T07:56:45.887278Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.047297ms"} {"level":"info","ts":"2026-10-17T07:56:45.894553Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db28a37eabf8270","cluster-id":"33328f64ab44b15e"} {"level":"info","ts":"2026-10-17T07:56:45.894664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T07:56:45.894689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 0"} {"level":"info","ts":"2026-10-17T07:56:45.8947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db28a37eabf8270 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T07:56:45.894713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 1"} {"level":"info","ts":"2026-10-17T07:56:45.894765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"warn","ts":"2026-10-17T07:56:45.901266Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T07:56:45.903132Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T07:56:45.905034Z","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-17T07:56:45.907658Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db28a37eabf8270","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T07:56:45.90782Z","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-17T07:56:45.907927Z","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-17T07:56:45.907943Z","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-17T07:56:45.908037Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db28a37eabf8270","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T07:56:45.908692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"info","ts":"2026-10-17T07:56:45.908785Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","added-peer-id":"db28a37eabf8270","added-peer-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-10-17T07:56:45.913329Z","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-17T07:56:45.913526Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-10-17T07:56:45.913582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-10-17T07:56:45.913912Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db28a37eabf8270","initial-advertise-peer-urls":["https://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T07:56:45.913979Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T07:56:46.295497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T07:56:46.295563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T07:56:46.295589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgPreVoteResp from db28a37eabf8270 at term 1"} {"level":"info","ts":"2026-10-17T07:56:46.29561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became candidate at term 2"} {"level":"info","ts":"2026-10-17T07:56:46.295621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgVoteResp from db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-10-17T07:56:46.295636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became leader at term 2"} {"level":"info","ts":"2026-10-17T07:56:46.29565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db28a37eabf8270 elected leader db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-10-17T07:56:46.297131Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T07:56:46.297741Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db28a37eabf8270","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.57:2379]}","request-path":"/0/members/db28a37eabf8270/attributes","cluster-id":"33328f64ab44b15e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T07:56:46.297872Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T07:56:46.297945Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T07:56:46.298162Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T07:56:46.298254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T07:56:46.298437Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T07:56:46.298571Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T07:56:46.29862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T07:56:46.299751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.57:2379"} {"level":"info","ts":"2026-10-17T07:56:46.302745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T07:57:02.367607Z","caller":"traceutil/trace.go:171","msg":"trace[893790945] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"132.97088ms","start":"2026-10-17T07:57:02.234602Z","end":"2026-10-17T07:57:02.367573Z","steps":["trace[893790945] 'process raft request' (duration: 132.706434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:02.527333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.829415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T07:57:02.527452Z","caller":"traceutil/trace.go:171","msg":"trace[654228069] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"130.294413ms","start":"2026-10-17T07:57:02.397142Z","end":"2026-10-17T07:57:02.527437Z","steps":["trace[654228069] 'read index received' (duration: 24.494763ms)","trace[654228069] 'applied index is now lower than readState.Index' (duration: 105.79851ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T07:57:02.527517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.382026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T07:57:02.527549Z","caller":"traceutil/trace.go:171","msg":"trace[598809579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"130.416637ms","start":"2026-10-17T07:57:02.397124Z","end":"2026-10-17T07:57:02.527541Z","steps":["trace[598809579] 'agreement among raft nodes before linearized reading' (duration: 130.354165ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:02.527981Z","caller":"traceutil/trace.go:171","msg":"trace[1673866050] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"156.056888ms","start":"2026-10-17T07:57:02.37187Z","end":"2026-10-17T07:57:02.527927Z","steps":["trace[1673866050] 'process raft request' (duration: 49.836228ms)","trace[1673866050] 'compare' (duration: 104.724023ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T07:57:30.894277Z","caller":"traceutil/trace.go:171","msg":"trace[599004174] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"108.99362ms","start":"2026-10-17T07:57:30.785242Z","end":"2026-10-17T07:57:30.894236Z","steps":["trace[599004174] 'process raft request' (duration: 70.963228ms)","trace[599004174] 'compare' (duration: 37.770696ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T07:57:30.897896Z","caller":"traceutil/trace.go:171","msg":"trace[597066281] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.668812ms","start":"2026-10-17T07:57:30.789198Z","end":"2026-10-17T07:57:30.897867Z","steps":["trace[597066281] 'process raft request' (duration: 108.359524ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:30.903544Z","caller":"traceutil/trace.go:171","msg":"trace[1009596930] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"105.486591ms","start":"2026-10-17T07:57:30.798026Z","end":"2026-10-17T07:57:30.903513Z","steps":["trace[1009596930] 'process raft request' (duration: 99.911732ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:37.159477Z","caller":"traceutil/trace.go:171","msg":"trace[1192046166] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"160.373601ms","start":"2026-10-17T07:57:36.999074Z","end":"2026-10-17T07:57:37.159447Z","steps":["trace[1192046166] 'process raft request' (duration: 159.669784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:37.792837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.106938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T07:57:37.792964Z","caller":"traceutil/trace.go:171","msg":"trace[1548933630] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"145.152351ms","start":"2026-10-17T07:57:37.647794Z","end":"2026-10-17T07:57:37.792946Z","steps":["trace[1548933630] 'read index received' (duration: 36.381µs)","trace[1548933630] 'applied index is now lower than readState.Index' (duration: 145.11485ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T07:57:37.793135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.344225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8slqx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-17T07:57:37.793173Z","caller":"traceutil/trace.go:171","msg":"trace[1581451571] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8slqx; range_end:; response_count:1; response_revision:608; }","duration":"145.432567ms","start":"2026-10-17T07:57:37.647732Z","end":"2026-10-17T07:57:37.793164Z","steps":["trace[1581451571] 'agreement among raft nodes before linearized reading' (duration: 145.259713ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:37.793341Z","caller":"traceutil/trace.go:171","msg":"trace[1100444964] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"562.765346ms","start":"2026-10-17T07:57:37.230515Z","end":"2026-10-17T07:57:37.793281Z","steps":["trace[1100444964] 'process raft request' (duration: 110.141885ms)","trace[1100444964] 'compare' (duration: 451.959455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T07:57:37.79359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T07:57:37.230488Z","time spent":"563.007082ms","remote":"127.0.0.1:55302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-17T07:57:38.695949Z","caller":"traceutil/trace.go:171","msg":"trace[566721086] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"143.268083ms","start":"2026-10-17T07:57:38.552654Z","end":"2026-10-17T07:57:38.695922Z","steps":["trace[566721086] 'read index received' (duration: 142.464133ms)","trace[566721086] 'applied index is now lower than readState.Index' (duration: 802.83µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T07:57:38.696065Z","caller":"traceutil/trace.go:171","msg":"trace[176400840] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"416.033066ms","start":"2026-10-17T07:57:38.279987Z","end":"2026-10-17T07:57:38.69602Z","steps":["trace[176400840] 'process raft request' (duration: 415.316318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:38.696111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.449018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:38.69627Z","caller":"traceutil/trace.go:171","msg":"trace[1305689396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"143.684854ms","start":"2026-10-17T07:57:38.55256Z","end":"2026-10-17T07:57:38.696245Z","steps":["trace[1305689396] 'agreement among raft nodes before linearized reading' (duration: 143.495009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:38.696288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T07:57:38.279954Z","time spent":"416.2163ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T07:57:38.696296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.476654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-17T07:57:38.696399Z","caller":"traceutil/trace.go:171","msg":"trace[1096944663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"138.620787ms","start":"2026-10-17T07:57:38.557755Z","end":"2026-10-17T07:57:38.696375Z","steps":["trace[1096944663] 'agreement among raft nodes before linearized reading' (duration: 138.34161ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:55.157246Z","caller":"traceutil/trace.go:171","msg":"trace[1155667209] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"188.212717ms","start":"2026-10-17T07:57:54.969007Z","end":"2026-10-17T07:57:55.15722Z","steps":["trace[1155667209] 'read index received' (duration: 188.120925ms)","trace[1155667209] 'applied index is now lower than readState.Index' (duration: 90.562µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T07:57:55.157392Z","caller":"traceutil/trace.go:171","msg":"trace[975526092] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"196.904755ms","start":"2026-10-17T07:57:54.960472Z","end":"2026-10-17T07:57:55.157377Z","steps":["trace[975526092] 'process raft request' (duration: 196.578197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.157637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.626198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T07:57:55.157998Z","caller":"traceutil/trace.go:171","msg":"trace[450355767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"188.72683ms","start":"2026-10-17T07:57:54.968966Z","end":"2026-10-17T07:57:55.157693Z","steps":["trace[450355767] 'agreement among raft nodes before linearized reading' (duration: 188.535635ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:55.310717Z","caller":"traceutil/trace.go:171","msg":"trace[262383060] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"148.032412ms","start":"2026-10-17T07:57:55.162659Z","end":"2026-10-17T07:57:55.310692Z","steps":["trace[262383060] 'process raft request' (duration: 147.914569ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:55.31074Z","caller":"traceutil/trace.go:171","msg":"trace[1190147455] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"153.385026ms","start":"2026-10-17T07:57:55.157323Z","end":"2026-10-17T07:57:55.310708Z","steps":["trace[1190147455] 'read index received' (duration: 149.621882ms)","trace[1190147455] 'applied index is now lower than readState.Index' (duration: 3.759924ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T07:57:55.310985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.721997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:55.31111Z","caller":"traceutil/trace.go:171","msg":"trace[45925650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:781; }","duration":"313.84469ms","start":"2026-10-17T07:57:54.99719Z","end":"2026-10-17T07:57:55.311034Z","steps":["trace[45925650] 'agreement among raft nodes before linearized reading' (duration: 313.664025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.311253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T07:57:54.997175Z","time spent":"314.056035ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-10-17T07:57:55.311452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.747777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:55.311489Z","caller":"traceutil/trace.go:171","msg":"trace[651007537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:781; }","duration":"245.8522ms","start":"2026-10-17T07:57:55.065628Z","end":"2026-10-17T07:57:55.31148Z","steps":["trace[651007537] 'agreement among raft nodes before linearized reading' (duration: 245.694156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.311496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.373943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:55.311531Z","caller":"traceutil/trace.go:171","msg":"trace[816206426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:781; }","duration":"280.437324ms","start":"2026-10-17T07:57:55.031083Z","end":"2026-10-17T07:57:55.31152Z","steps":["trace[816206426] 'agreement among raft nodes before linearized reading' (duration: 280.372772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.311552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.88446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:55.31162Z","caller":"traceutil/trace.go:171","msg":"trace[571681461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:781; }","duration":"245.972662ms","start":"2026-10-17T07:57:55.06563Z","end":"2026-10-17T07:57:55.311602Z","steps":["trace[571681461] 'agreement among raft nodes before linearized reading' (duration: 245.870719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.31166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.601398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T07:57:55.311577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.233254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:55.311747Z","caller":"traceutil/trace.go:171","msg":"trace[1537103880] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:781; }","duration":"245.448779ms","start":"2026-10-17T07:57:55.066272Z","end":"2026-10-17T07:57:55.311721Z","steps":["trace[1537103880] 'agreement among raft nodes before linearized reading' (duration: 245.157591ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:55.311759Z","caller":"traceutil/trace.go:171","msg":"trace[1291518700] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:781; }","duration":"280.67475ms","start":"2026-10-17T07:57:55.03102Z","end":"2026-10-17T07:57:55.311695Z","steps":["trace[1291518700] 'agreement among raft nodes before linearized reading' (duration: 280.588358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.311695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.068195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T07:57:55.31185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.527327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:57:55.311913Z","caller":"traceutil/trace.go:171","msg":"trace[1803681799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:781; }","duration":"314.624259ms","start":"2026-10-17T07:57:54.997267Z","end":"2026-10-17T07:57:55.311892Z","steps":["trace[1803681799] 'agreement among raft nodes before linearized reading' (duration: 314.466225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.311869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.617288ms","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-17T07:57:55.312044Z","caller":"traceutil/trace.go:171","msg":"trace[51662771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"208.824923ms","start":"2026-10-17T07:57:55.1032Z","end":"2026-10-17T07:57:55.312025Z","steps":["trace[51662771] 'agreement among raft nodes before linearized reading' (duration: 208.436924ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:57:55.311919Z","caller":"traceutil/trace.go:171","msg":"trace[1170957159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:781; }","duration":"280.24821ms","start":"2026-10-17T07:57:55.031599Z","end":"2026-10-17T07:57:55.311848Z","steps":["trace[1170957159] 'agreement among raft nodes before linearized reading' (duration: 280.073856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:57:55.311953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T07:57:54.997246Z","time spent":"314.696771ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-17T07:59:11.366823Z","caller":"traceutil/trace.go:171","msg":"trace[1593977123] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"104.05169ms","start":"2026-10-17T07:59:11.262652Z","end":"2026-10-17T07:59:11.366703Z","steps":["trace[1593977123] 'process raft request' (duration: 103.890186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:59:11.780245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.962489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-17T07:59:11.780302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.278104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T07:59:11.780365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.629856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10af908f-ba25-42a4-95fe-752993f06d55\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-17T07:59:11.780386Z","caller":"traceutil/trace.go:171","msg":"trace[1065246149] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1850; }","duration":"190.158774ms","start":"2026-10-17T07:59:11.5902Z","end":"2026-10-17T07:59:11.780359Z","steps":["trace[1065246149] 'count revisions from in-memory index tree' (duration: 189.847237ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:59:11.780387Z","caller":"traceutil/trace.go:171","msg":"trace[1167462872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"229.404727ms","start":"2026-10-17T07:59:11.550968Z","end":"2026-10-17T07:59:11.780372Z","steps":["trace[1167462872] 'range keys from in-memory index tree' (duration: 229.188182ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:59:11.780444Z","caller":"traceutil/trace.go:171","msg":"trace[1624482678] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10af908f-ba25-42a4-95fe-752993f06d55; range_end:; response_count:1; response_revision:1850; }","duration":"284.78841ms","start":"2026-10-17T07:59:11.495635Z","end":"2026-10-17T07:59:11.780424Z","steps":["trace[1624482678] 'range keys from in-memory index tree' (duration: 284.500073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:59:11.780652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.167907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T07:59:11.780712Z","caller":"traceutil/trace.go:171","msg":"trace[264122672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"228.244998ms","start":"2026-10-17T07:59:11.552448Z","end":"2026-10-17T07:59:11.780693Z","steps":["trace[264122672] 'range keys from in-memory index tree' (duration: 227.955241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:59:12.099553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.757173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T07:59:12.099654Z","caller":"traceutil/trace.go:171","msg":"trace[771923091] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1853; }","duration":"172.910607ms","start":"2026-10-17T07:59:11.926716Z","end":"2026-10-17T07:59:12.099627Z","steps":["trace[771923091] 'count revisions from in-memory index tree' (duration: 172.614619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T07:59:12.099713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.33036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-17T07:59:12.099816Z","caller":"traceutil/trace.go:171","msg":"trace[1557559373] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"207.508074ms","start":"2026-10-17T07:59:11.892279Z","end":"2026-10-17T07:59:12.099787Z","steps":["trace[1557559373] 'range keys from in-memory index tree' (duration: 207.131375ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:59:24.9875Z","caller":"traceutil/trace.go:171","msg":"trace[493276261] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"255.565426ms","start":"2026-10-17T07:59:24.731894Z","end":"2026-10-17T07:59:24.987459Z","steps":["trace[493276261] 'process raft request' (duration: 255.281859ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:59:24.993509Z","caller":"traceutil/trace.go:171","msg":"trace[1949841460] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"155.338101ms","start":"2026-10-17T07:59:24.838146Z","end":"2026-10-17T07:59:24.993484Z","steps":["trace[1949841460] 'process raft request' (duration: 155.126256ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:59:24.993647Z","caller":"traceutil/trace.go:171","msg":"trace[734114464] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"118.16307ms","start":"2026-10-17T07:59:24.875462Z","end":"2026-10-17T07:59:24.993625Z","steps":["trace[734114464] 'process raft request' (duration: 117.968695ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T07:59:55.699905Z","caller":"traceutil/trace.go:171","msg":"trace[819022375] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"152.420251ms","start":"2026-10-17T07:59:55.547422Z","end":"2026-10-17T07:59:55.699843Z","steps":["trace[819022375] 'process raft request' (duration: 67.652259ms)","trace[819022375] 'compare' (duration: 84.570617ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:00:01.778329Z","caller":"traceutil/trace.go:171","msg":"trace[237903897] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"121.709005ms","start":"2026-10-17T08:00:01.656573Z","end":"2026-10-17T08:00:01.778282Z","steps":["trace[237903897] 'process raft request' (duration: 121.573652ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:00:01.878667Z","caller":"traceutil/trace.go:171","msg":"trace[229326216] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"158.786651ms","start":"2026-10-17T08:00:01.719855Z","end":"2026-10-17T08:00:01.878642Z","steps":["trace[229326216] 'process raft request' (duration: 142.388257ms)","trace[229326216] 'compare' (duration: 16.283802ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:00:02.205026Z","caller":"traceutil/trace.go:171","msg":"trace[1674110403] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"114.557004ms","start":"2026-10-17T08:00:02.090444Z","end":"2026-10-17T08:00:02.205001Z","steps":["trace[1674110403] 'read index received' (duration: 114.467882ms)","trace[1674110403] 'applied index is now lower than readState.Index' (duration: 88.042µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:00:02.205145Z","caller":"traceutil/trace.go:171","msg":"trace[1018263900] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"119.706134ms","start":"2026-10-17T08:00:02.085426Z","end":"2026-10-17T08:00:02.205132Z","steps":["trace[1018263900] 'process raft request' (duration: 119.444008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:00:02.205427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.975295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-17T08:00:02.205488Z","caller":"traceutil/trace.go:171","msg":"trace[26633237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2182; }","duration":"115.068467ms","start":"2026-10-17T08:00:02.090406Z","end":"2026-10-17T08:00:02.205474Z","steps":["trace[26633237] 'agreement among raft nodes before linearized reading' (duration: 114.969665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:00:02.559599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.451801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T08:00:02.559879Z","caller":"traceutil/trace.go:171","msg":"trace[1002834678] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"273.650574ms","start":"2026-10-17T08:00:02.286209Z","end":"2026-10-17T08:00:02.55986Z","steps":["trace[1002834678] 'read index received' (duration: 65.791923ms)","trace[1002834678] 'applied index is now lower than readState.Index' (duration: 207.857471ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:00:02.559979Z","caller":"traceutil/trace.go:171","msg":"trace[7058689] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"350.451284ms","start":"2026-10-17T08:00:02.209518Z","end":"2026-10-17T08:00:02.55997Z","steps":["trace[7058689] 'process raft request' (duration: 350.238839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:00:02.560067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:00:02.209496Z","time spent":"350.525916ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:00:02.560165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.930231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T08:00:02.560178Z","caller":"traceutil/trace.go:171","msg":"trace[122297130] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"351.196253ms","start":"2026-10-17T08:00:02.208931Z","end":"2026-10-17T08:00:02.560127Z","steps":["trace[122297130] 'process raft request' (duration: 143.107506ms)","trace[122297130] 'compare' (duration: 207.288337ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:00:02.56025Z","caller":"traceutil/trace.go:171","msg":"trace[2033047319] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2184; }","duration":"274.052544ms","start":"2026-10-17T08:00:02.286176Z","end":"2026-10-17T08:00:02.560229Z","steps":["trace[2033047319] 'agreement among raft nodes before linearized reading' (duration: 273.89617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:00:02.560343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.251672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-17T08:00:02.560419Z","caller":"traceutil/trace.go:171","msg":"trace[2102590999] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2184; }","duration":"115.387605ms","start":"2026-10-17T08:00:02.445006Z","end":"2026-10-17T08:00:02.560394Z","steps":["trace[2102590999] 'agreement among raft nodes before linearized reading' (duration: 115.099867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:00:02.56042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:00:02.208918Z","time spent":"351.365427ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:00:08.066352Z","caller":"traceutil/trace.go:171","msg":"trace[940416726] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"169.420331ms","start":"2026-10-17T08:00:07.896901Z","end":"2026-10-17T08:00:08.066321Z","steps":["trace[940416726] 'process raft request' (duration: 169.183035ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:01:03.031714Z","caller":"traceutil/trace.go:171","msg":"trace[476812414] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2778; }","duration":"143.293684ms","start":"2026-10-17T08:01:02.888395Z","end":"2026-10-17T08:01:03.031689Z","steps":["trace[476812414] 'read index received' (duration: 143.13554ms)","trace[476812414] 'applied index is now lower than readState.Index' (duration: 157.154µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:01:03.031798Z","caller":"traceutil/trace.go:171","msg":"trace[513977445] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"147.424059ms","start":"2026-10-17T08:01:02.884342Z","end":"2026-10-17T08:01:03.031766Z","steps":["trace[513977445] 'process raft request' (duration: 147.206543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:01:03.031946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.52907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T08:01:03.032013Z","caller":"traceutil/trace.go:171","msg":"trace[507282078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2677; }","duration":"143.650283ms","start":"2026-10-17T08:01:02.888344Z","end":"2026-10-17T08:01:03.031995Z","steps":["trace[507282078] 'agreement among raft nodes before linearized reading' (duration: 143.438507ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:01:03.153738Z","caller":"traceutil/trace.go:171","msg":"trace[531648049] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"117.323093ms","start":"2026-10-17T08:01:03.036398Z","end":"2026-10-17T08:01:03.153721Z","steps":["trace[531648049] 'process raft request' (duration: 117.20107ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:01:41.605459Z","caller":"traceutil/trace.go:171","msg":"trace[1431744046] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"105.007199ms","start":"2026-10-17T08:01:41.500422Z","end":"2026-10-17T08:01:41.60543Z","steps":["trace[1431744046] 'process raft request' (duration: 104.830345ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:01:41.690024Z","caller":"traceutil/trace.go:171","msg":"trace[1268518885] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"186.878701ms","start":"2026-10-17T08:01:41.503107Z","end":"2026-10-17T08:01:41.689986Z","steps":["trace[1268518885] 'process raft request' (duration: 172.403583ms)","trace[1268518885] 'compare' (duration: 14.084128ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:01:41.690135Z","caller":"traceutil/trace.go:171","msg":"trace[1309142544] linearizableReadLoop","detail":"{readStateIndex:3021; appliedIndex:3018; }","duration":"138.555003ms","start":"2026-10-17T08:01:41.551553Z","end":"2026-10-17T08:01:41.690108Z","steps":["trace[1309142544] 'read index received' (duration: 53.783618ms)","trace[1309142544] 'applied index is now lower than readState.Index' (duration: 84.769765ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:01:41.69016Z","caller":"traceutil/trace.go:171","msg":"trace[1718281243] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"183.702971ms","start":"2026-10-17T08:01:41.506426Z","end":"2026-10-17T08:01:41.690129Z","steps":["trace[1718281243] 'process raft request' (duration: 183.466155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:01:41.690259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.690137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:01:41.690327Z","caller":"traceutil/trace.go:171","msg":"trace[1669722244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2911; }","duration":"138.82767ms","start":"2026-10-17T08:01:41.551486Z","end":"2026-10-17T08:01:41.690313Z","steps":["trace[1669722244] 'agreement among raft nodes before linearized reading' (duration: 138.711837ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:04:05.893852Z","caller":"traceutil/trace.go:171","msg":"trace[1938873617] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"100.490915ms","start":"2026-10-17T08:04:05.793331Z","end":"2026-10-17T08:04:05.893822Z","steps":["trace[1938873617] 'process raft request' (duration: 97.723614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:04:38.670517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.934722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:04:38.670612Z","caller":"traceutil/trace.go:171","msg":"trace[965032629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4044; }","duration":"120.068695ms","start":"2026-10-17T08:04:38.550519Z","end":"2026-10-17T08:04:38.670588Z","steps":["trace[965032629] 'range keys from in-memory index tree' (duration: 119.83083ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:03.519059Z","caller":"traceutil/trace.go:171","msg":"trace[84361648] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5229; }","duration":"177.893378ms","start":"2026-10-17T08:06:03.341136Z","end":"2026-10-17T08:06:03.519029Z","steps":["trace[84361648] 'read index received' (duration: 55.354089ms)","trace[84361648] 'applied index is now lower than readState.Index' (duration: 122.537579ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:06:03.51923Z","caller":"traceutil/trace.go:171","msg":"trace[1085861042] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"190.15129ms","start":"2026-10-17T08:06:03.329036Z","end":"2026-10-17T08:06:03.519187Z","steps":["trace[1085861042] 'process raft request' (duration: 155.059457ms)","trace[1085861042] 'compare' (duration: 34.742004ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:06:03.519627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.463763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:527101"} {"level":"info","ts":"2026-10-17T08:06:03.519695Z","caller":"traceutil/trace.go:171","msg":"trace[25452835] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5041; }","duration":"178.582296ms","start":"2026-10-17T08:06:03.341096Z","end":"2026-10-17T08:06:03.519678Z","steps":["trace[25452835] 'agreement among raft nodes before linearized reading' (duration: 178.083293ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:03.522477Z","caller":"traceutil/trace.go:171","msg":"trace[702815130] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"117.645594ms","start":"2026-10-17T08:06:03.404813Z","end":"2026-10-17T08:06:03.522458Z","steps":["trace[702815130] 'process raft request' (duration: 117.402358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:03.522681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.607897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-17T08:06:03.522765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.745149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-66v2f-tls\" ","response":"range_response_count:1 size:1581"} {"level":"info","ts":"2026-10-17T08:06:03.522794Z","caller":"traceutil/trace.go:171","msg":"trace[1743626099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5042; }","duration":"180.73965ms","start":"2026-10-17T08:06:03.342031Z","end":"2026-10-17T08:06:03.52277Z","steps":["trace[1743626099] 'agreement among raft nodes before linearized reading' (duration: 180.442943ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:03.522879Z","caller":"traceutil/trace.go:171","msg":"trace[1588249580] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-66v2f-tls; range_end:; response_count:1; response_revision:5042; }","duration":"121.829231ms","start":"2026-10-17T08:06:03.400968Z","end":"2026-10-17T08:06:03.522798Z","steps":["trace[1588249580] 'agreement among raft nodes before linearized reading' (duration: 121.744379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:03.523173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.74705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-06214753-82ce-46c7-bde8-cd3a5e0c9d91\" ","response":"range_response_count:1 size:4049"} {"level":"info","ts":"2026-10-17T08:06:03.523188Z","caller":"traceutil/trace.go:171","msg":"trace[1195137983] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5042; }","duration":"118.26945ms","start":"2026-10-17T08:06:03.404891Z","end":"2026-10-17T08:06:03.523161Z","steps":["trace[1195137983] 'process raft request' (duration: 117.501281ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:03.523232Z","caller":"traceutil/trace.go:171","msg":"trace[946105675] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-06214753-82ce-46c7-bde8-cd3a5e0c9d91; range_end:; response_count:1; response_revision:5042; }","duration":"167.842112ms","start":"2026-10-17T08:06:03.355376Z","end":"2026-10-17T08:06:03.523218Z","steps":["trace[946105675] 'agreement among raft nodes before linearized reading' (duration: 167.687148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:08.964558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.775999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ebcc0633-0285-4ef9-98ce-7408027bd8e3\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-17T08:06:08.964657Z","caller":"traceutil/trace.go:171","msg":"trace[133269717] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ebcc0633-0285-4ef9-98ce-7408027bd8e3; range_end:; response_count:1; response_revision:5247; }","duration":"174.925383ms","start":"2026-10-17T08:06:08.789711Z","end":"2026-10-17T08:06:08.964636Z","steps":["trace[133269717] 'range keys from in-memory index tree' (duration: 174.592904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:08.964676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.008454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"info","ts":"2026-10-17T08:06:08.964735Z","caller":"traceutil/trace.go:171","msg":"trace[958724228] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5247; }","duration":"219.108617ms","start":"2026-10-17T08:06:08.745609Z","end":"2026-10-17T08:06:08.964718Z","steps":["trace[958724228] 'range keys from in-memory index tree' (duration: 218.86272ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:09.419234Z","caller":"traceutil/trace.go:171","msg":"trace[1699680785] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5446; }","duration":"202.207907ms","start":"2026-10-17T08:06:09.216998Z","end":"2026-10-17T08:06:09.419206Z","steps":["trace[1699680785] 'read index received' (duration: 202.117505ms)","trace[1699680785] 'applied index is now lower than readState.Index' (duration: 89.162µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:06:09.419321Z","caller":"traceutil/trace.go:171","msg":"trace[567326404] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"249.07541ms","start":"2026-10-17T08:06:09.170193Z","end":"2026-10-17T08:06:09.419268Z","steps":["trace[567326404] 'process raft request' (duration: 248.865145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:09.419504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.490694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fbc2f\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-17T08:06:09.419557Z","caller":"traceutil/trace.go:171","msg":"trace[1654805969] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fbc2f; range_end:; response_count:1; response_revision:5249; }","duration":"202.599267ms","start":"2026-10-17T08:06:09.21692Z","end":"2026-10-17T08:06:09.419542Z","steps":["trace[1654805969] 'agreement among raft nodes before linearized reading' (duration: 202.402192ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:09.436637Z","caller":"traceutil/trace.go:171","msg":"trace[1362199292] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"212.648093ms","start":"2026-10-17T08:06:09.223969Z","end":"2026-10-17T08:06:09.436617Z","steps":["trace[1362199292] 'process raft request' (duration: 212.53888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:09.436832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.553095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T08:06:09.436886Z","caller":"traceutil/trace.go:171","msg":"trace[1888997294] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5250; }","duration":"145.657618ms","start":"2026-10-17T08:06:09.291214Z","end":"2026-10-17T08:06:09.436871Z","steps":["trace[1888997294] 'agreement among raft nodes before linearized reading' (duration: 145.554245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:09.436965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.336017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16785"} {"level":"info","ts":"2026-10-17T08:06:09.437041Z","caller":"traceutil/trace.go:171","msg":"trace[2143806943] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5250; }","duration":"183.46098ms","start":"2026-10-17T08:06:09.253562Z","end":"2026-10-17T08:06:09.437023Z","steps":["trace[2143806943] 'agreement among raft nodes before linearized reading' (duration: 183.242854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:09.437365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.896451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ebcc0633-0285-4ef9-98ce-7408027bd8e3\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-17T08:06:09.43747Z","caller":"traceutil/trace.go:171","msg":"trace[537178108] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ebcc0633-0285-4ef9-98ce-7408027bd8e3; range_end:; response_count:1; response_revision:5250; }","duration":"151.064505ms","start":"2026-10-17T08:06:09.286381Z","end":"2026-10-17T08:06:09.437446Z","steps":["trace[537178108] 'agreement among raft nodes before linearized reading' (duration: 150.404398ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:22.699689Z","caller":"traceutil/trace.go:171","msg":"trace[432763002] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"202.415407ms","start":"2026-10-17T08:06:22.497252Z","end":"2026-10-17T08:06:22.699667Z","steps":["trace[432763002] 'process raft request' (duration: 202.281564ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:22.700308Z","caller":"traceutil/trace.go:171","msg":"trace[562059882] linearizableReadLoop","detail":"{readStateIndex:5705; appliedIndex:5705; }","duration":"149.316774ms","start":"2026-10-17T08:06:22.550978Z","end":"2026-10-17T08:06:22.700294Z","steps":["trace[562059882] 'read index received' (duration: 149.287014ms)","trace[562059882] 'applied index is now lower than readState.Index' (duration: 28.73µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:06:22.700388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.403926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:06:22.700423Z","caller":"traceutil/trace.go:171","msg":"trace[676482399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5498; }","duration":"149.478288ms","start":"2026-10-17T08:06:22.550934Z","end":"2026-10-17T08:06:22.700413Z","steps":["trace[676482399] 'agreement among raft nodes before linearized reading' (duration: 149.410197ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:22.939732Z","caller":"traceutil/trace.go:171","msg":"trace[1570969168] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"322.722181ms","start":"2026-10-17T08:06:22.616971Z","end":"2026-10-17T08:06:22.939693Z","steps":["trace[1570969168] 'process raft request' (duration: 271.292791ms)","trace[1570969168] 'compare' (duration: 51.070061ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:06:22.939821Z","caller":"traceutil/trace.go:171","msg":"trace[191864697] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"274.432892ms","start":"2026-10-17T08:06:22.665363Z","end":"2026-10-17T08:06:22.939796Z","steps":["trace[191864697] 'process raft request' (duration: 274.167685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:22.939897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:06:22.616947Z","time spent":"322.860905ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:06:22.94005Z","caller":"traceutil/trace.go:171","msg":"trace[1043255952] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"269.934487ms","start":"2026-10-17T08:06:22.670082Z","end":"2026-10-17T08:06:22.940016Z","steps":["trace[1043255952] 'process raft request' (duration: 269.534386ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:22.940479Z","caller":"traceutil/trace.go:171","msg":"trace[1565425841] linearizableReadLoop","detail":"{readStateIndex:5709; appliedIndex:5705; }","duration":"238.498806ms","start":"2026-10-17T08:06:22.701966Z","end":"2026-10-17T08:06:22.940465Z","steps":["trace[1565425841] 'read index received' (duration: 186.354857ms)","trace[1565425841] 'applied index is now lower than readState.Index' (duration: 52.141149ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:06:22.940518Z","caller":"traceutil/trace.go:171","msg":"trace[524194204] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"264.643852ms","start":"2026-10-17T08:06:22.675848Z","end":"2026-10-17T08:06:22.940492Z","steps":["trace[524194204] 'process raft request' (duration: 264.099358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:22.940624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.641269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:06:22.940785Z","caller":"traceutil/trace.go:171","msg":"trace[778742024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5502; }","duration":"238.839255ms","start":"2026-10-17T08:06:22.701929Z","end":"2026-10-17T08:06:22.940769Z","steps":["trace[778742024] 'agreement among raft nodes before linearized reading' (duration: 238.598179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:22.940902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.77294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T08:06:22.940971Z","caller":"traceutil/trace.go:171","msg":"trace[2081488049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5502; }","duration":"212.876463ms","start":"2026-10-17T08:06:22.728077Z","end":"2026-10-17T08:06:22.940954Z","steps":["trace[2081488049] 'agreement among raft nodes before linearized reading' (duration: 212.702279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:22.941006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.507234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:06:22.941067Z","caller":"traceutil/trace.go:171","msg":"trace[341917107] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5502; }","duration":"159.618557ms","start":"2026-10-17T08:06:22.781434Z","end":"2026-10-17T08:06:22.941052Z","steps":["trace[341917107] 'agreement among raft nodes before linearized reading' (duration: 159.493734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:22.941262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.009224ms","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-17T08:06:22.941665Z","caller":"traceutil/trace.go:171","msg":"trace[94126624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5502; }","duration":"218.434355ms","start":"2026-10-17T08:06:22.723202Z","end":"2026-10-17T08:06:22.941637Z","steps":["trace[94126624] 'agreement among raft nodes before linearized reading' (duration: 217.738287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:22.941017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.973702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-86cf4f7cb6-4bzfk\" ","response":"range_response_count:1 size:8111"} {"level":"info","ts":"2026-10-17T08:06:22.941842Z","caller":"traceutil/trace.go:171","msg":"trace[1882907784] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-86cf4f7cb6-4bzfk; range_end:; response_count:1; response_revision:5502; }","duration":"238.852694ms","start":"2026-10-17T08:06:22.702971Z","end":"2026-10-17T08:06:22.941823Z","steps":["trace[1882907784] 'agreement among raft nodes before linearized reading' (duration: 237.855729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:29.655052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.898213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T08:06:29.655099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.01459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1a0c927-c426-4f2e-b831-5dee62c4c28a\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-17T08:06:29.655119Z","caller":"traceutil/trace.go:171","msg":"trace[771344924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5653; }","duration":"104.998064ms","start":"2026-10-17T08:06:29.550103Z","end":"2026-10-17T08:06:29.655101Z","steps":["trace[771344924] 'range keys from in-memory index tree' (duration: 104.84502ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:29.655178Z","caller":"traceutil/trace.go:171","msg":"trace[445036354] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1a0c927-c426-4f2e-b831-5dee62c4c28a; range_end:; response_count:1; response_revision:5653; }","duration":"137.151764ms","start":"2026-10-17T08:06:29.518006Z","end":"2026-10-17T08:06:29.655158Z","steps":["trace[445036354] 'range keys from in-memory index tree' (duration: 136.831766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:29.655489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.520071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:06:29.655565Z","caller":"traceutil/trace.go:171","msg":"trace[2034149918] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5653; }","duration":"164.634014ms","start":"2026-10-17T08:06:29.490913Z","end":"2026-10-17T08:06:29.655547Z","steps":["trace[2034149918] 'range keys from in-memory index tree' (duration: 164.431109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:30.021341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.242183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T08:06:30.021552Z","caller":"traceutil/trace.go:171","msg":"trace[344365389] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5866; }","duration":"272.557764ms","start":"2026-10-17T08:06:29.748975Z","end":"2026-10-17T08:06:30.021533Z","steps":["trace[344365389] 'read index received' (duration: 67.984452ms)","trace[344365389] 'applied index is now lower than readState.Index' (duration: 204.572152ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:06:30.021722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.023957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"info","ts":"2026-10-17T08:06:30.021754Z","caller":"traceutil/trace.go:171","msg":"trace[1619490510] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5656; }","duration":"339.100729ms","start":"2026-10-17T08:06:29.682644Z","end":"2026-10-17T08:06:30.021745Z","steps":["trace[1619490510] 'agreement among raft nodes before linearized reading' (duration: 338.942305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:30.021784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:06:29.682607Z","time spent":"339.168061ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12450,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-17T08:06:30.021985Z","caller":"traceutil/trace.go:171","msg":"trace[62627800] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"323.022479ms","start":"2026-10-17T08:06:29.698952Z","end":"2026-10-17T08:06:30.021975Z","steps":["trace[62627800] 'process raft request' (duration: 322.480575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:30.022046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:06:29.698942Z","time spent":"323.072181ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:06:30.022126Z","caller":"traceutil/trace.go:171","msg":"trace[1522312621] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"341.842649ms","start":"2026-10-17T08:06:29.680245Z","end":"2026-10-17T08:06:30.022087Z","steps":["trace[1522312621] 'process raft request' (duration: 136.736814ms)","trace[1522312621] 'compare' (duration: 203.788851ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:06:30.02238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:06:29.680224Z","time spent":"341.981492ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:06:30.022587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.666283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-17T08:06:30.022699Z","caller":"traceutil/trace.go:171","msg":"trace[1487824962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5656; }","duration":"317.909409ms","start":"2026-10-17T08:06:29.704741Z","end":"2026-10-17T08:06:30.022651Z","steps":["trace[1487824962] 'agreement among raft nodes before linearized reading' (duration: 317.341095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:30.022858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:06:29.70473Z","time spent":"318.016412ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-17T08:06:30.107877Z","caller":"traceutil/trace.go:171","msg":"trace[689797946] transaction","detail":"{read_only:false; response_revision:5657; number_of_response:1; }","duration":"184.668315ms","start":"2026-10-17T08:06:29.923192Z","end":"2026-10-17T08:06:30.10786Z","steps":["trace[689797946] 'process raft request' (duration: 184.512921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:06:30.151821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.195591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-17T08:06:30.151879Z","caller":"traceutil/trace.go:171","msg":"trace[912282422] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5658; }","duration":"127.287142ms","start":"2026-10-17T08:06:30.024578Z","end":"2026-10-17T08:06:30.151865Z","steps":["trace[912282422] 'agreement among raft nodes before linearized reading' (duration: 127.137959ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:30.151878Z","caller":"traceutil/trace.go:171","msg":"trace[1561542892] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"124.934643ms","start":"2026-10-17T08:06:30.026922Z","end":"2026-10-17T08:06:30.151857Z","steps":["trace[1561542892] 'process raft request' (duration: 120.293925ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:06:46.339256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2936} {"level":"info","ts":"2026-10-17T08:06:46.421062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2936,"took":"80.286836ms","hash":3482338488,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":32436224,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T08:06:46.421136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3482338488,"revision":2936,"compact-revision":-1} {"level":"info","ts":"2026-10-17T08:11:46.346393Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5843} {"level":"info","ts":"2026-10-17T08:11:46.437487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5843,"took":"89.11598ms","hash":873095453,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":26714112,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T08:11:46.437571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":873095453,"revision":5843,"compact-revision":2936} {"level":"info","ts":"2026-10-17T08:12:49.629398Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022a9.wal"} {"level":"warn","ts":"2026-10-17T08:12:53.617208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9399189756639843710,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-17T08:12:53.643096Z","caller":"traceutil/trace.go:171","msg":"trace[189592436] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"607.994624ms","start":"2026-10-17T08:12:53.035071Z","end":"2026-10-17T08:12:53.643065Z","steps":["trace[189592436] 'process raft request' (duration: 607.84372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:12:53.643267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:12:53.035051Z","time spent":"608.111497ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:12:53.67281Z","caller":"traceutil/trace.go:171","msg":"trace[1445648073] linearizableReadLoop","detail":"{readStateIndex:8894; appliedIndex:8893; }","duration":"556.523533ms","start":"2026-10-17T08:12:53.116265Z","end":"2026-10-17T08:12:53.672789Z","steps":["trace[1445648073] 'read index received' (duration: 526.815836ms)","trace[1445648073] 'applied index is now lower than readState.Index' (duration: 29.706867ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:12:53.672913Z","caller":"traceutil/trace.go:171","msg":"trace[1484960948] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"198.675233ms","start":"2026-10-17T08:12:53.474207Z","end":"2026-10-17T08:12:53.672883Z","steps":["trace[1484960948] 'process raft request' (duration: 198.455307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:12:53.673009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.734368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T08:12:53.673039Z","caller":"traceutil/trace.go:171","msg":"trace[922247579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8588; }","duration":"556.81921ms","start":"2026-10-17T08:12:53.116211Z","end":"2026-10-17T08:12:53.673031Z","steps":["trace[922247579] 'agreement among raft nodes before linearized reading' (duration: 556.684256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:12:53.673064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:12:53.116167Z","time spent":"556.890781ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T08:12:53.673079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.600849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"info","ts":"2026-10-17T08:12:53.673144Z","caller":"traceutil/trace.go:171","msg":"trace[1341501918] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8588; }","duration":"480.706431ms","start":"2026-10-17T08:12:53.192422Z","end":"2026-10-17T08:12:53.673128Z","steps":["trace[1341501918] 'agreement among raft nodes before linearized reading' (duration: 480.513226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:12:53.673168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.676399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-17T08:12:53.67322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:12:53.192401Z","time spent":"480.793263ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12450,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-17T08:12:53.673266Z","caller":"traceutil/trace.go:171","msg":"trace[1403245432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8588; }","duration":"467.802022ms","start":"2026-10-17T08:12:53.205447Z","end":"2026-10-17T08:12:53.673249Z","steps":["trace[1403245432] 'agreement among raft nodes before linearized reading' (duration: 467.590537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:12:53.673303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:12:53.205439Z","time spent":"467.856044ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-17T08:13:05.824308Z","caller":"traceutil/trace.go:171","msg":"trace[765151010] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"103.024646ms","start":"2026-10-17T08:13:05.721234Z","end":"2026-10-17T08:13:05.824258Z","steps":["trace[765151010] 'process raft request' (duration: 102.78112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:13:05.882061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.375096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-17T08:13:05.882265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.888808ms","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-17T08:13:05.882337Z","caller":"traceutil/trace.go:171","msg":"trace[717593490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8649; }","duration":"145.682943ms","start":"2026-10-17T08:13:05.736636Z","end":"2026-10-17T08:13:05.882319Z","steps":["trace[717593490] 'agreement among raft nodes before linearized reading' (duration: 87.792537ms)","trace[717593490] 'range keys from in-memory index tree' (duration: 57.502745ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:13:05.88229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.560845ms","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-17T08:13:05.88238Z","caller":"traceutil/trace.go:171","msg":"trace[568044295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8649; }","duration":"155.053592ms","start":"2026-10-17T08:13:05.727298Z","end":"2026-10-17T08:13:05.882352Z","steps":["trace[568044295] 'agreement among raft nodes before linearized reading' (duration: 97.044833ms)","trace[568044295] 'range keys from in-memory index tree' (duration: 57.702941ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:13:05.882409Z","caller":"traceutil/trace.go:171","msg":"trace[1483358086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8649; }","duration":"146.724709ms","start":"2026-10-17T08:13:05.735659Z","end":"2026-10-17T08:13:05.882383Z","steps":["trace[1483358086] 'agreement among raft nodes before linearized reading' (duration: 88.729771ms)","trace[1483358086] 'range keys from in-memory index tree' (duration: 57.6945ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:13:06.639835Z","caller":"traceutil/trace.go:171","msg":"trace[568178733] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"126.395491ms","start":"2026-10-17T08:13:06.513396Z","end":"2026-10-17T08:13:06.639791Z","steps":["trace[568178733] 'process raft request' (duration: 82.343618ms)","trace[568178733] 'compare' (duration: 43.913869ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:13:06.802338Z","caller":"traceutil/trace.go:171","msg":"trace[1363029236] linearizableReadLoop","detail":"{readStateIndex:8966; appliedIndex:8965; }","duration":"162.556083ms","start":"2026-10-17T08:13:06.639759Z","end":"2026-10-17T08:13:06.802315Z","steps":["trace[1363029236] 'read index received' (duration: 135.431452ms)","trace[1363029236] 'applied index is now lower than readState.Index' (duration: 27.123641ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:13:06.802369Z","caller":"traceutil/trace.go:171","msg":"trace[961704020] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"174.558039ms","start":"2026-10-17T08:13:06.627784Z","end":"2026-10-17T08:13:06.802342Z","steps":["trace[961704020] 'process raft request' (duration: 147.449028ms)","trace[961704020] 'compare' (duration: 26.927686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:13:06.80265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.618149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T08:13:06.802645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.622809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-10-17T08:13:06.802696Z","caller":"traceutil/trace.go:171","msg":"trace[1689927369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8657; }","duration":"161.868636ms","start":"2026-10-17T08:13:06.640816Z","end":"2026-10-17T08:13:06.802684Z","steps":["trace[1689927369] 'agreement among raft nodes before linearized reading' (duration: 161.629239ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:13:06.802736Z","caller":"traceutil/trace.go:171","msg":"trace[2026339986] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8657; }","duration":"223.786513ms","start":"2026-10-17T08:13:06.578924Z","end":"2026-10-17T08:13:06.80271Z","steps":["trace[2026339986] 'agreement among raft nodes before linearized reading' (duration: 223.513276ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:13:32.511612Z","caller":"traceutil/trace.go:171","msg":"trace[1046286007] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"113.363889ms","start":"2026-10-17T08:13:32.398222Z","end":"2026-10-17T08:13:32.511586Z","steps":["trace[1046286007] 'process raft request' (duration: 113.207085ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:14:57.911255Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T08:14:57.917788Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T08:14:57.917902Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T08:15:03.335852Z","caller":"traceutil/trace.go:171","msg":"trace[864190793] transaction","detail":"{read_only:false; response_revision:9712; number_of_response:1; }","duration":"104.249964ms","start":"2026-10-17T08:15:03.231573Z","end":"2026-10-17T08:15:03.335823Z","steps":["trace[864190793] 'process raft request' (duration: 104.0877ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:15:16.923054Z","caller":"traceutil/trace.go:171","msg":"trace[43183186] transaction","detail":"{read_only:false; response_revision:9785; number_of_response:1; }","duration":"101.434793ms","start":"2026-10-17T08:15:16.821596Z","end":"2026-10-17T08:15:16.923031Z","steps":["trace[43183186] 'process raft request' (duration: 101.310229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:15:17.195791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.793317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T08:15:17.196054Z","caller":"traceutil/trace.go:171","msg":"trace[1974618710] linearizableReadLoop","detail":"{readStateIndex:10132; appliedIndex:10130; }","duration":"267.741021ms","start":"2026-10-17T08:15:16.928265Z","end":"2026-10-17T08:15:17.196006Z","steps":["trace[1974618710] 'read index received' (duration: 8.736832ms)","trace[1974618710] 'applied index is now lower than readState.Index' (duration: 259.001199ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:15:17.196194Z","caller":"traceutil/trace.go:171","msg":"trace[1529597468] transaction","detail":"{read_only:false; response_revision:9787; number_of_response:1; }","duration":"268.033158ms","start":"2026-10-17T08:15:16.928147Z","end":"2026-10-17T08:15:17.19618Z","steps":["trace[1529597468] 'process raft request' (duration: 41.746459ms)","trace[1529597468] 'compare' (duration: 225.51998ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:15:17.196583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.303245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:293535"} {"level":"info","ts":"2026-10-17T08:15:17.196666Z","caller":"traceutil/trace.go:171","msg":"trace[1545429898] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9788; }","duration":"268.417738ms","start":"2026-10-17T08:15:16.92823Z","end":"2026-10-17T08:15:17.196647Z","steps":["trace[1545429898] 'agreement among raft nodes before linearized reading' (duration: 267.958186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:15:17.220801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.123399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19524"} {"level":"info","ts":"2026-10-17T08:15:17.220863Z","caller":"traceutil/trace.go:171","msg":"trace[172813385] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9789; }","duration":"292.209212ms","start":"2026-10-17T08:15:16.928637Z","end":"2026-10-17T08:15:17.220846Z","steps":["trace[172813385] 'agreement among raft nodes before linearized reading' (duration: 292.026977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:15:17.2208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.547128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T08:15:17.221044Z","caller":"traceutil/trace.go:171","msg":"trace[1936469855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9789; }","duration":"176.838115ms","start":"2026-10-17T08:15:17.044191Z","end":"2026-10-17T08:15:17.22103Z","steps":["trace[1936469855] 'agreement among raft nodes before linearized reading' (duration: 176.508317ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:15:17.37252Z","caller":"traceutil/trace.go:171","msg":"trace[829441906] transaction","detail":"{read_only:false; response_revision:9790; number_of_response:1; }","duration":"146.035044ms","start":"2026-10-17T08:15:17.226457Z","end":"2026-10-17T08:15:17.372492Z","steps":["trace[829441906] 'process raft request' (duration: 119.518272ms)","trace[829441906] 'compare' (duration: 26.173913ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:16:46.355763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8015} {"level":"info","ts":"2026-10-17T08:16:46.433173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8015,"took":"74.939121ms","hash":2149341356,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":28213248,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T08:16:46.433351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149341356,"revision":8015,"compact-revision":5843} {"level":"info","ts":"2026-10-17T08:20:54.983209Z","caller":"traceutil/trace.go:171","msg":"trace[1972975231] linearizableReadLoop","detail":"{readStateIndex:12637; appliedIndex:12636; }","duration":"336.614551ms","start":"2026-10-17T08:20:54.646568Z","end":"2026-10-17T08:20:54.983183Z","steps":["trace[1972975231] 'read index received' (duration: 336.484638ms)","trace[1972975231] 'applied index is now lower than readState.Index' (duration: 128.783µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:20:54.983351Z","caller":"traceutil/trace.go:171","msg":"trace[898611422] transaction","detail":"{read_only:false; response_revision:12216; number_of_response:1; }","duration":"346.48333ms","start":"2026-10-17T08:20:54.636831Z","end":"2026-10-17T08:20:54.983314Z","steps":["trace[898611422] 'process raft request' (duration: 346.140281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:54.983484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.901468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-17T08:20:54.983521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.636806Z","time spent":"346.626064ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:20:54.983541Z","caller":"traceutil/trace.go:171","msg":"trace[1892226878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12216; }","duration":"337.021401ms","start":"2026-10-17T08:20:54.646507Z","end":"2026-10-17T08:20:54.983529Z","steps":["trace[1892226878] 'agreement among raft nodes before linearized reading' (duration: 336.806536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:54.983578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.646494Z","time spent":"337.074412ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T08:20:55.006739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.286019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:20:55.006794Z","caller":"traceutil/trace.go:171","msg":"trace[765863421] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12217; }","duration":"343.377251ms","start":"2026-10-17T08:20:54.663405Z","end":"2026-10-17T08:20:55.006782Z","steps":["trace[765863421] 'agreement among raft nodes before linearized reading' (duration: 343.251078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.006854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.663357Z","time spent":"343.488144ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-10-17T08:20:55.006795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.620022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T08:20:55.00689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.276293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:20:55.006932Z","caller":"traceutil/trace.go:171","msg":"trace[382897986] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12217; }","duration":"185.800467ms","start":"2026-10-17T08:20:54.821107Z","end":"2026-10-17T08:20:55.006908Z","steps":["trace[382897986] 'agreement among raft nodes before linearized reading' (duration: 185.573021ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:20:55.006956Z","caller":"traceutil/trace.go:171","msg":"trace[1077267163] transaction","detail":"{read_only:false; response_revision:12217; number_of_response:1; }","duration":"354.984274ms","start":"2026-10-17T08:20:54.651939Z","end":"2026-10-17T08:20:55.006923Z","steps":["trace[1077267163] 'process raft request' (duration: 354.577354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.456248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T08:20:55.007058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.23273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"warn","ts":"2026-10-17T08:20:55.007135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.651924Z","time spent":"355.104397ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:20:55.007164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.666963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T08:20:55.007166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.268443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-10-17T08:20:55.007175Z","caller":"traceutil/trace.go:171","msg":"trace[1272297163] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12217; }","duration":"242.405724ms","start":"2026-10-17T08:20:54.764748Z","end":"2026-10-17T08:20:55.007154Z","steps":["trace[1272297163] 'agreement among raft nodes before linearized reading' (duration: 242.110696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.631603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:20:55.007197Z","caller":"traceutil/trace.go:171","msg":"trace[1125404337] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12217; }","duration":"342.738435ms","start":"2026-10-17T08:20:54.664448Z","end":"2026-10-17T08:20:55.007187Z","steps":["trace[1125404337] 'agreement among raft nodes before linearized reading' (duration: 342.697024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.836807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-17T08:20:55.007208Z","caller":"traceutil/trace.go:171","msg":"trace[217663626] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12217; }","duration":"183.320064ms","start":"2026-10-17T08:20:54.823873Z","end":"2026-10-17T08:20:55.007193Z","steps":["trace[217663626] 'agreement among raft nodes before linearized reading' (duration: 183.142029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.881408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:20:55.007231Z","caller":"traceutil/trace.go:171","msg":"trace[1815351227] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12217; }","duration":"185.720954ms","start":"2026-10-17T08:20:54.821494Z","end":"2026-10-17T08:20:55.007215Z","steps":["trace[1815351227] 'agreement among raft nodes before linearized reading' (duration: 185.56048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.792706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:20:55.007246Z","caller":"traceutil/trace.go:171","msg":"trace[1371445152] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12217; }","duration":"185.92851ms","start":"2026-10-17T08:20:54.821301Z","end":"2026-10-17T08:20:55.00723Z","steps":["trace[1371445152] 'agreement among raft nodes before linearized reading' (duration: 185.690464ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:20:55.007266Z","caller":"traceutil/trace.go:171","msg":"trace[187305957] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12217; }","duration":"185.96202ms","start":"2026-10-17T08:20:54.821289Z","end":"2026-10-17T08:20:55.007251Z","steps":["trace[187305957] 'agreement among raft nodes before linearized reading' (duration: 185.821017ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:20:55.007282Z","caller":"traceutil/trace.go:171","msg":"trace[1222161534] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12217; }","duration":"342.836138ms","start":"2026-10-17T08:20:54.66444Z","end":"2026-10-17T08:20:55.007276Z","steps":["trace[1222161534] 'agreement among raft nodes before linearized reading' (duration: 342.799477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.664427Z","time spent":"342.877609ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-10-17T08:20:55.007224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.664424Z","time spent":"342.793966ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-10-17T08:20:55.007267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.522205ms","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-17T08:20:55.007123Z","caller":"traceutil/trace.go:171","msg":"trace[1134181293] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12217; }","duration":"342.553871ms","start":"2026-10-17T08:20:54.66456Z","end":"2026-10-17T08:20:55.007113Z","steps":["trace[1134181293] 'agreement among raft nodes before linearized reading' (duration: 342.477879ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:20:55.006986Z","caller":"traceutil/trace.go:171","msg":"trace[2006591275] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12217; }","duration":"185.423617ms","start":"2026-10-17T08:20:54.82154Z","end":"2026-10-17T08:20:55.006964Z","steps":["trace[2006591275] 'agreement among raft nodes before linearized reading' (duration: 185.211832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.664539Z","time spent":"342.855878ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-10-17T08:20:55.007449Z","caller":"traceutil/trace.go:171","msg":"trace[1120007832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12217; }","duration":"341.74414ms","start":"2026-10-17T08:20:54.665645Z","end":"2026-10-17T08:20:55.007389Z","steps":["trace[1120007832] 'agreement among raft nodes before linearized reading' (duration: 341.147165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.665638Z","time spent":"341.898944ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-17T08:20:55.00768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.770601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-10-17T08:20:55.007737Z","caller":"traceutil/trace.go:171","msg":"trace[528546696] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12217; }","duration":"339.851122ms","start":"2026-10-17T08:20:54.667873Z","end":"2026-10-17T08:20:55.007724Z","steps":["trace[528546696] 'agreement among raft nodes before linearized reading' (duration: 339.066032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:20:55.007768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:20:54.667865Z","time spent":"339.896913ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5099,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"info","ts":"2026-10-17T08:21:46.362849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10393} {"level":"info","ts":"2026-10-17T08:21:46.449736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10393,"took":"84.180234ms","hash":3002001418,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":25858048,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T08:21:46.449803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3002001418,"revision":10393,"compact-revision":8015} {"level":"info","ts":"2026-10-17T08:26:46.369188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12569} {"level":"info","ts":"2026-10-17T08:26:46.453578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12569,"took":"82.437472ms","hash":3475408746,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":27803648,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T08:26:46.453649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3475408746,"revision":12569,"compact-revision":10393} {"level":"info","ts":"2026-10-17T08:27:22.57194Z","caller":"traceutil/trace.go:171","msg":"trace[542289081] transaction","detail":"{read_only:false; response_revision:14810; number_of_response:1; }","duration":"127.760012ms","start":"2026-10-17T08:27:22.444138Z","end":"2026-10-17T08:27:22.571898Z","steps":["trace[542289081] 'process raft request' (duration: 127.490166ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:27:39.244154Z","caller":"traceutil/trace.go:171","msg":"trace[1223637253] transaction","detail":"{read_only:false; response_revision:14908; number_of_response:1; }","duration":"104.963593ms","start":"2026-10-17T08:27:39.139169Z","end":"2026-10-17T08:27:39.244132Z","steps":["trace[1223637253] 'process raft request' (duration: 104.803439ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:27:39.244365Z","caller":"traceutil/trace.go:171","msg":"trace[2042410941] transaction","detail":"{read_only:false; response_revision:14909; number_of_response:1; }","duration":"105.022655ms","start":"2026-10-17T08:27:39.139332Z","end":"2026-10-17T08:27:39.244355Z","steps":["trace[2042410941] 'process raft request' (duration: 104.754988ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:27:39.24468Z","caller":"traceutil/trace.go:171","msg":"trace[389352585] transaction","detail":"{read_only:false; response_revision:14910; number_of_response:1; }","duration":"105.295852ms","start":"2026-10-17T08:27:39.139373Z","end":"2026-10-17T08:27:39.244669Z","steps":["trace[389352585] 'process raft request' (duration: 104.942553ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:27:39.244768Z","caller":"traceutil/trace.go:171","msg":"trace[752262249] transaction","detail":"{read_only:false; response_revision:14911; number_of_response:1; }","duration":"100.769417ms","start":"2026-10-17T08:27:39.143976Z","end":"2026-10-17T08:27:39.244745Z","steps":["trace[752262249] 'process raft request' (duration: 100.576192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:27:39.963708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.699882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T08:27:39.963848Z","caller":"traceutil/trace.go:171","msg":"trace[1526426] transaction","detail":"{read_only:false; response_revision:14913; number_of_response:1; }","duration":"206.669094ms","start":"2026-10-17T08:27:39.757153Z","end":"2026-10-17T08:27:39.963822Z","steps":["trace[1526426] 'process raft request' (duration: 61.740727ms)","trace[1526426] 'compare' (duration: 144.403444ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:27:40.049653Z","caller":"traceutil/trace.go:171","msg":"trace[374205933] transaction","detail":"{read_only:false; response_revision:14914; number_of_response:1; }","duration":"105.162519ms","start":"2026-10-17T08:27:39.944457Z","end":"2026-10-17T08:27:40.049619Z","steps":["trace[374205933] 'process raft request' (duration: 104.934353ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:27:40.442684Z","caller":"traceutil/trace.go:171","msg":"trace[305593449] transaction","detail":"{read_only:false; response_revision:14916; number_of_response:1; }","duration":"151.766251ms","start":"2026-10-17T08:27:40.290878Z","end":"2026-10-17T08:27:40.442644Z","steps":["trace[305593449] 'process raft request' (duration: 151.511014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:27:40.678003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.130296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:27:40.678118Z","caller":"traceutil/trace.go:171","msg":"trace[154256871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14916; }","duration":"114.326911ms","start":"2026-10-17T08:27:40.56377Z","end":"2026-10-17T08:27:40.678096Z","steps":["trace[154256871] 'range keys from in-memory index tree' (duration: 114.012923ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:28:03.4801Z","caller":"traceutil/trace.go:171","msg":"trace[1943022991] linearizableReadLoop","detail":"{readStateIndex:15759; appliedIndex:15758; }","duration":"121.978208ms","start":"2026-10-17T08:28:03.358088Z","end":"2026-10-17T08:28:03.480066Z","steps":["trace[1943022991] 'read index received' (duration: 121.748592ms)","trace[1943022991] 'applied index is now lower than readState.Index' (duration: 228.466µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:28:03.480151Z","caller":"traceutil/trace.go:171","msg":"trace[120899252] transaction","detail":"{read_only:false; response_revision:15228; number_of_response:1; }","duration":"134.016032ms","start":"2026-10-17T08:28:03.346104Z","end":"2026-10-17T08:28:03.48012Z","steps":["trace[120899252] 'process raft request' (duration: 133.790637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:28:03.480345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.245245ms","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-17T08:28:03.480425Z","caller":"traceutil/trace.go:171","msg":"trace[2012928614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15228; }","duration":"122.399259ms","start":"2026-10-17T08:28:03.358015Z","end":"2026-10-17T08:28:03.480414Z","steps":["trace[2012928614] 'agreement among raft nodes before linearized reading' (duration: 122.198834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:28:03.48044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.185987ms","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-17T08:28:03.480468Z","caller":"traceutil/trace.go:171","msg":"trace[1193801625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15228; }","duration":"117.263739ms","start":"2026-10-17T08:28:03.363194Z","end":"2026-10-17T08:28:03.480457Z","steps":["trace[1193801625] 'agreement among raft nodes before linearized reading' (duration: 117.119795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:28:03.480465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.149011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T08:28:03.480545Z","caller":"traceutil/trace.go:171","msg":"trace[131530552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15228; }","duration":"118.267715ms","start":"2026-10-17T08:28:03.362259Z","end":"2026-10-17T08:28:03.480527Z","steps":["trace[131530552] 'agreement among raft nodes before linearized reading' (duration: 118.025279ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:28:03.842638Z","caller":"traceutil/trace.go:171","msg":"trace[2073243127] transaction","detail":"{read_only:false; response_revision:15229; number_of_response:1; }","duration":"353.15265ms","start":"2026-10-17T08:28:03.489464Z","end":"2026-10-17T08:28:03.842616Z","steps":["trace[2073243127] 'process raft request' (duration: 353.013987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:28:03.842792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:28:03.489437Z","time spent":"353.275044ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3738,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:28:03.927851Z","caller":"traceutil/trace.go:171","msg":"trace[1232721281] transaction","detail":"{read_only:false; response_revision:15231; number_of_response:1; }","duration":"438.05476ms","start":"2026-10-17T08:28:03.489771Z","end":"2026-10-17T08:28:03.927825Z","steps":["trace[1232721281] 'process raft request' (duration: 437.975488ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:28:03.927923Z","caller":"traceutil/trace.go:171","msg":"trace[673071829] transaction","detail":"{read_only:false; response_revision:15230; number_of_response:1; }","duration":"438.167253ms","start":"2026-10-17T08:28:03.489711Z","end":"2026-10-17T08:28:03.927878Z","steps":["trace[673071829] 'process raft request' (duration: 437.883516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:28:03.928015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:28:03.489744Z","time spent":"438.184383ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:28:03.928162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:28:03.489694Z","time spent":"438.323747ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:28:03.928167Z","caller":"traceutil/trace.go:171","msg":"trace[1874425622] transaction","detail":"{read_only:false; response_revision:15232; number_of_response:1; }","duration":"438.328787ms","start":"2026-10-17T08:28:03.489816Z","end":"2026-10-17T08:28:03.928144Z","steps":["trace[1874425622] 'process raft request' (duration: 437.974438ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:28:03.92828Z","caller":"traceutil/trace.go:171","msg":"trace[1677078218] linearizableReadLoop","detail":"{readStateIndex:15763; appliedIndex:15759; }","duration":"295.655715ms","start":"2026-10-17T08:28:03.632575Z","end":"2026-10-17T08:28:03.928231Z","steps":["trace[1677078218] 'read index received' (duration: 209.844962ms)","trace[1677078218] 'applied index is now lower than readState.Index' (duration: 85.809423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:28:03.928306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:28:03.489804Z","time spent":"438.42857ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:28:03.928489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.917291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fbc2f\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-17T08:28:03.928534Z","caller":"traceutil/trace.go:171","msg":"trace[468272145] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fbc2f; range_end:; response_count:1; response_revision:15233; }","duration":"295.997113ms","start":"2026-10-17T08:28:03.632526Z","end":"2026-10-17T08:28:03.928523Z","steps":["trace[468272145] 'agreement among raft nodes before linearized reading' (duration: 295.832809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:28:03.92852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.025904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-d7tg7\" ","response":"range_response_count:1 size:20787"} {"level":"info","ts":"2026-10-17T08:28:03.928581Z","caller":"traceutil/trace.go:171","msg":"trace[1425830842] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-d7tg7; range_end:; response_count:1; response_revision:15233; }","duration":"153.117797ms","start":"2026-10-17T08:28:03.775453Z","end":"2026-10-17T08:28:03.92857Z","steps":["trace[1425830842] 'agreement among raft nodes before linearized reading' (duration: 152.946932ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:28:03.928657Z","caller":"traceutil/trace.go:171","msg":"trace[633575726] transaction","detail":"{read_only:false; response_revision:15233; number_of_response:1; }","duration":"271.604746ms","start":"2026-10-17T08:28:03.656835Z","end":"2026-10-17T08:28:03.92844Z","steps":["trace[633575726] 'process raft request' (duration: 271.227017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:29:14.390681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.0323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:29:14.39123Z","caller":"traceutil/trace.go:171","msg":"trace[1443417851] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:15961; }","duration":"107.612045ms","start":"2026-10-17T08:29:14.283585Z","end":"2026-10-17T08:29:14.391197Z","steps":["trace[1443417851] 'count revisions from in-memory index tree' (duration: 106.955588ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:29:14.706256Z","caller":"traceutil/trace.go:171","msg":"trace[614232082] transaction","detail":"{read_only:false; response_revision:15963; number_of_response:1; }","duration":"311.064145ms","start":"2026-10-17T08:29:14.395168Z","end":"2026-10-17T08:29:14.706233Z","steps":["trace[614232082] 'process raft request' (duration: 261.209053ms)","trace[614232082] 'compare' (duration: 49.704419ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:29:14.706402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:29:14.395149Z","time spent":"311.181668ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:29:14.706564Z","caller":"traceutil/trace.go:171","msg":"trace[1194857071] linearizableReadLoop","detail":"{readStateIndex:16515; appliedIndex:16511; }","duration":"299.186484ms","start":"2026-10-17T08:29:14.40736Z","end":"2026-10-17T08:29:14.706546Z","steps":["trace[1194857071] 'read index received' (duration: 5.807927ms)","trace[1194857071] 'applied index is now lower than readState.Index' (duration: 293.377897ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:29:14.706588Z","caller":"traceutil/trace.go:171","msg":"trace[1880514341] transaction","detail":"{read_only:false; response_revision:15964; number_of_response:1; }","duration":"311.223849ms","start":"2026-10-17T08:29:14.39533Z","end":"2026-10-17T08:29:14.706554Z","steps":["trace[1880514341] 'process raft request' (duration: 310.86205ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:29:14.706621Z","caller":"traceutil/trace.go:171","msg":"trace[970873655] transaction","detail":"{read_only:false; response_revision:15965; number_of_response:1; }","duration":"310.905521ms","start":"2026-10-17T08:29:14.395683Z","end":"2026-10-17T08:29:14.706589Z","steps":["trace[970873655] 'process raft request' (duration: 310.779468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:29:14.706766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:29:14.395316Z","time spent":"311.350142ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:29:14.706873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.492342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wqwpv\" ","response":"range_response_count:1 size:9787"} {"level":"warn","ts":"2026-10-17T08:29:14.706795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:29:14.395669Z","time spent":"311.030744ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:29:14.706965Z","caller":"traceutil/trace.go:171","msg":"trace[2039355653] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wqwpv; range_end:; response_count:1; response_revision:15965; }","duration":"299.637625ms","start":"2026-10-17T08:29:14.407303Z","end":"2026-10-17T08:29:14.70694Z","steps":["trace[2039355653] 'agreement among raft nodes before linearized reading' (duration: 299.309247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:29:14.713218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.953981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T08:29:14.713299Z","caller":"traceutil/trace.go:171","msg":"trace[783418555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15966; }","duration":"162.073054ms","start":"2026-10-17T08:29:14.551208Z","end":"2026-10-17T08:29:14.713281Z","steps":["trace[783418555] 'agreement among raft nodes before linearized reading' (duration: 161.962461ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:29:14.713452Z","caller":"traceutil/trace.go:171","msg":"trace[929502961] transaction","detail":"{read_only:false; response_revision:15966; number_of_response:1; }","duration":"295.301796ms","start":"2026-10-17T08:29:14.418128Z","end":"2026-10-17T08:29:14.713429Z","steps":["trace[929502961] 'process raft request' (duration: 294.864484ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:31:46.390424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14573} {"level":"info","ts":"2026-10-17T08:31:46.474698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14573,"took":"81.543035ms","hash":1559074225,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T08:31:46.474765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1559074225,"revision":14573,"compact-revision":12569} {"level":"warn","ts":"2026-10-17T08:35:33.890637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.359202ms","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-17T08:35:33.890728Z","caller":"traceutil/trace.go:171","msg":"trace[778137791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18705; }","duration":"178.488215ms","start":"2026-10-17T08:35:33.712219Z","end":"2026-10-17T08:35:33.890708Z","steps":["trace[778137791] 'range keys from in-memory index tree' (duration: 178.183578ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:35:34.530186Z","caller":"traceutil/trace.go:171","msg":"trace[470426431] transaction","detail":"{read_only:false; response_revision:18707; number_of_response:1; }","duration":"443.060183ms","start":"2026-10-17T08:35:34.087102Z","end":"2026-10-17T08:35:34.530162Z","steps":["trace[470426431] 'process raft request' (duration: 442.848607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:35:34.530352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:35:34.087077Z","time spent":"443.185856ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:35:34.590749Z","caller":"traceutil/trace.go:171","msg":"trace[835730713] transaction","detail":"{read_only:false; response_revision:18708; number_of_response:1; }","duration":"497.228977ms","start":"2026-10-17T08:35:34.093491Z","end":"2026-10-17T08:35:34.59072Z","steps":["trace[835730713] 'process raft request' (duration: 461.629084ms)","trace[835730713] 'compare' (duration: 35.418678ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:35:34.590943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:35:34.093464Z","time spent":"497.401901ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:35:34.591071Z","caller":"traceutil/trace.go:171","msg":"trace[1585050152] linearizableReadLoop","detail":"{readStateIndex:19348; appliedIndex:19346; }","duration":"443.609177ms","start":"2026-10-17T08:35:34.147411Z","end":"2026-10-17T08:35:34.59102Z","steps":["trace[1585050152] 'read index received' (duration: 382.533089ms)","trace[1585050152] 'applied index is now lower than readState.Index' (duration: 61.075248ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:35:34.591129Z","caller":"traceutil/trace.go:171","msg":"trace[1321665229] transaction","detail":"{read_only:false; response_revision:18710; number_of_response:1; }","duration":"152.217589ms","start":"2026-10-17T08:35:34.438876Z","end":"2026-10-17T08:35:34.591094Z","steps":["trace[1321665229] 'process raft request' (duration: 151.997554ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:35:34.591125Z","caller":"traceutil/trace.go:171","msg":"trace[80763587] transaction","detail":"{read_only:false; response_revision:18709; number_of_response:1; }","duration":"260.989087ms","start":"2026-10-17T08:35:34.33008Z","end":"2026-10-17T08:35:34.59107Z","steps":["trace[80763587] 'process raft request' (duration: 260.579356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:35:34.591288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.834863ms","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-17T08:35:34.591573Z","caller":"traceutil/trace.go:171","msg":"trace[1947469817] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:18710; }","duration":"444.169451ms","start":"2026-10-17T08:35:34.147384Z","end":"2026-10-17T08:35:34.591554Z","steps":["trace[1947469817] 'agreement among raft nodes before linearized reading' (duration: 443.75382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:35:34.591624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:35:34.147369Z","time spent":"444.244734ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-10-17T08:35:34.591686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.206705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T08:35:34.59174Z","caller":"traceutil/trace.go:171","msg":"trace[1598317428] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:18710; }","duration":"439.323888ms","start":"2026-10-17T08:35:34.152403Z","end":"2026-10-17T08:35:34.591727Z","steps":["trace[1598317428] 'agreement among raft nodes before linearized reading' (duration: 439.207415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:35:34.591808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:35:34.152387Z","time spent":"439.40881ms","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-17T08:35:34.591817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.323263ms","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-17T08:35:34.591869Z","caller":"traceutil/trace.go:171","msg":"trace[1400204378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18710; }","duration":"197.385775ms","start":"2026-10-17T08:35:34.394467Z","end":"2026-10-17T08:35:34.591853Z","steps":["trace[1400204378] 'agreement among raft nodes before linearized reading' (duration: 197.18882ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:36:26.218481Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T08:36:26.225855Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T08:36:26.225955Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T08:36:46.397084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16975} {"level":"info","ts":"2026-10-17T08:36:46.507656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16975,"took":"107.28859ms","hash":3773803328,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":33632256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T08:36:46.50773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3773803328,"revision":16975,"compact-revision":14573} {"level":"warn","ts":"2026-10-17T08:41:41.681511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.251517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T08:41:41.681598Z","caller":"traceutil/trace.go:171","msg":"trace[754998397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21506; }","duration":"131.38186ms","start":"2026-10-17T08:41:41.550199Z","end":"2026-10-17T08:41:41.68158Z","steps":["trace[754998397] 'range keys from in-memory index tree' (duration: 131.111943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:41:41.681541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.840455ms","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":"info","ts":"2026-10-17T08:41:41.681796Z","caller":"traceutil/trace.go:171","msg":"trace[350409323] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:21506; }","duration":"185.099691ms","start":"2026-10-17T08:41:41.496646Z","end":"2026-10-17T08:41:41.681746Z","steps":["trace[350409323] 'count revisions from in-memory index tree' (duration: 184.760662ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:41:42.049901Z","caller":"traceutil/trace.go:171","msg":"trace[1680957848] transaction","detail":"{read_only:false; response_revision:21507; number_of_response:1; }","duration":"147.293852ms","start":"2026-10-17T08:41:41.902585Z","end":"2026-10-17T08:41:42.049879Z","steps":["trace[1680957848] 'process raft request' (duration: 147.152799ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:41:42.059869Z","caller":"traceutil/trace.go:171","msg":"trace[1524232421] transaction","detail":"{read_only:false; response_revision:21508; number_of_response:1; }","duration":"143.192818ms","start":"2026-10-17T08:41:41.916644Z","end":"2026-10-17T08:41:42.059837Z","steps":["trace[1524232421] 'process raft request' (duration: 143.002583ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:41:43.029069Z","caller":"traceutil/trace.go:171","msg":"trace[1086830345] transaction","detail":"{read_only:false; response_revision:21512; number_of_response:1; }","duration":"409.622552ms","start":"2026-10-17T08:41:42.61942Z","end":"2026-10-17T08:41:43.029042Z","steps":["trace[1086830345] 'process raft request' (duration: 409.459258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:41:43.029225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:41:42.619403Z","time spent":"409.742934ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:41:43.232013Z","caller":"traceutil/trace.go:171","msg":"trace[974433154] linearizableReadLoop","detail":"{readStateIndex:22248; appliedIndex:22246; }","duration":"385.436538ms","start":"2026-10-17T08:41:42.846551Z","end":"2026-10-17T08:41:43.231987Z","steps":["trace[974433154] 'read index received' (duration: 182.32502ms)","trace[974433154] 'applied index is now lower than readState.Index' (duration: 203.110638ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:41:43.232047Z","caller":"traceutil/trace.go:171","msg":"trace[1101332985] transaction","detail":"{read_only:false; response_revision:21513; number_of_response:1; }","duration":"612.333698ms","start":"2026-10-17T08:41:42.619672Z","end":"2026-10-17T08:41:43.232006Z","steps":["trace[1101332985] 'process raft request' (duration: 569.850052ms)","trace[1101332985] 'compare' (duration: 42.369673ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:41:43.232148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.582221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T08:41:43.232183Z","caller":"traceutil/trace.go:171","msg":"trace[1011630007] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:21513; }","duration":"385.682655ms","start":"2026-10-17T08:41:42.846489Z","end":"2026-10-17T08:41:43.232172Z","steps":["trace[1011630007] 'agreement among raft nodes before linearized reading' (duration: 385.587402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:41:43.23222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:41:42.619649Z","time spent":"612.486493ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:41:43.232215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:41:42.846466Z","time spent":"385.738715ms","remote":"127.0.0.1:52844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"info","ts":"2026-10-17T08:41:43.265646Z","caller":"traceutil/trace.go:171","msg":"trace[921204834] transaction","detail":"{read_only:false; response_revision:21514; number_of_response:1; }","duration":"222.617272ms","start":"2026-10-17T08:41:43.043002Z","end":"2026-10-17T08:41:43.26562Z","steps":["trace[921204834] 'process raft request' (duration: 222.472719ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:41:46.404251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19499} {"level":"info","ts":"2026-10-17T08:41:46.550715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19499,"took":"143.114837ms","hash":417678410,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":29712384,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T08:41:46.550842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":417678410,"revision":19499,"compact-revision":16975} {"level":"warn","ts":"2026-10-17T08:45:54.154924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.455893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-17T08:45:54.155027Z","caller":"traceutil/trace.go:171","msg":"trace[1271534818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24076; }","duration":"122.623598ms","start":"2026-10-17T08:45:54.032383Z","end":"2026-10-17T08:45:54.155006Z","steps":["trace[1271534818] 'range keys from in-memory index tree' (duration: 122.210967ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:46:46.411098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21531} {"level":"info","ts":"2026-10-17T08:46:46.539655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21531,"took":"124.632739ms","hash":330178147,"current-db-size-bytes":57896960,"current-db-size":"58 MB","current-db-size-in-use-bytes":43315200,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-17T08:46:46.539723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":330178147,"revision":21531,"compact-revision":19499} {"level":"info","ts":"2026-10-17T08:48:08.296186Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ab.wal"} {"level":"info","ts":"2026-10-17T08:49:17.580208Z","caller":"traceutil/trace.go:171","msg":"trace[1923897771] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"143.541129ms","start":"2026-10-17T08:49:17.436633Z","end":"2026-10-17T08:49:17.580174Z","steps":["trace[1923897771] 'process raft request' (duration: 143.285053ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:49:22.940475Z","caller":"traceutil/trace.go:171","msg":"trace[1753117788] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"123.588272ms","start":"2026-10-17T08:49:22.816865Z","end":"2026-10-17T08:49:22.940454Z","steps":["trace[1753117788] 'process raft request' (duration: 123.085789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:49:23.28242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.13388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T08:49:23.282885Z","caller":"traceutil/trace.go:171","msg":"trace[1797194395] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"271.486572ms","start":"2026-10-17T08:49:23.01138Z","end":"2026-10-17T08:49:23.282867Z","steps":["trace[1797194395] 'process raft request' (duration: 123.842918ms)","trace[1797194395] 'compare' (duration: 146.655958ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:49:23.699047Z","caller":"traceutil/trace.go:171","msg":"trace[706054423] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"367.372968ms","start":"2026-10-17T08:49:23.33165Z","end":"2026-10-17T08:49:23.699023Z","steps":["trace[706054423] 'process raft request' (duration: 367.226154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:49:23.699238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:49:23.331633Z","time spent":"367.504332ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:49:23.830536Z","caller":"traceutil/trace.go:171","msg":"trace[1207872299] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"102.061115ms","start":"2026-10-17T08:49:23.728454Z","end":"2026-10-17T08:49:23.830515Z","steps":["trace[1207872299] 'process raft request' (duration: 102.005684ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:49:23.83079Z","caller":"traceutil/trace.go:171","msg":"trace[212104077] linearizableReadLoop","detail":"{readStateIndex:26975; appliedIndex:26973; }","duration":"222.643639ms","start":"2026-10-17T08:49:23.608136Z","end":"2026-10-17T08:49:23.830779Z","steps":["trace[212104077] 'read index received' (duration: 90.711765ms)","trace[212104077] 'applied index is now lower than readState.Index' (duration: 131.930784ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:49:23.830825Z","caller":"traceutil/trace.go:171","msg":"trace[1330818322] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"234.50924ms","start":"2026-10-17T08:49:23.596256Z","end":"2026-10-17T08:49:23.830765Z","steps":["trace[1330818322] 'process raft request' (duration: 231.972116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:49:23.831066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.112839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-17T08:49:23.831088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.316481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T08:49:23.831138Z","caller":"traceutil/trace.go:171","msg":"trace[2023921896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26109; }","duration":"134.231292ms","start":"2026-10-17T08:49:23.69689Z","end":"2026-10-17T08:49:23.831121Z","steps":["trace[2023921896] 'agreement among raft nodes before linearized reading' (duration: 134.025477ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:49:23.83114Z","caller":"traceutil/trace.go:171","msg":"trace[16570318] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:26109; }","duration":"113.402073ms","start":"2026-10-17T08:49:23.717723Z","end":"2026-10-17T08:49:23.831125Z","steps":["trace[16570318] 'agreement among raft nodes before linearized reading' (duration: 113.31614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:49:23.831174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.021169ms","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-17T08:49:23.831238Z","caller":"traceutil/trace.go:171","msg":"trace[404632309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26109; }","duration":"223.115821ms","start":"2026-10-17T08:49:23.608107Z","end":"2026-10-17T08:49:23.831223Z","steps":["trace[404632309] 'agreement among raft nodes before linearized reading' (duration: 222.970307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:22.35076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.551562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T08:50:22.350893Z","caller":"traceutil/trace.go:171","msg":"trace[139571008] linearizableReadLoop","detail":"{readStateIndex:27562; appliedIndex:27561; }","duration":"162.86636ms","start":"2026-10-17T08:50:22.18801Z","end":"2026-10-17T08:50:22.350876Z","steps":["trace[139571008] 'read index received' (duration: 13.043191ms)","trace[139571008] 'applied index is now lower than readState.Index' (duration: 149.822199ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:50:22.350968Z","caller":"traceutil/trace.go:171","msg":"trace[1258201237] transaction","detail":"{read_only:false; response_revision:26681; number_of_response:1; }","duration":"194.690769ms","start":"2026-10-17T08:50:22.156266Z","end":"2026-10-17T08:50:22.350956Z","steps":["trace[1258201237] 'process raft request' (duration: 44.823849ms)","trace[1258201237] 'compare' (duration: 149.45789ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:50:22.351197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.186179ms","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-17T08:50:22.35126Z","caller":"traceutil/trace.go:171","msg":"trace[301350674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26681; }","duration":"163.282731ms","start":"2026-10-17T08:50:22.187956Z","end":"2026-10-17T08:50:22.351239Z","steps":["trace[301350674] 'agreement among raft nodes before linearized reading' (duration: 163.093036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:22.351264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.675578ms","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-17T08:50:22.351358Z","caller":"traceutil/trace.go:171","msg":"trace[2050047169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26681; }","duration":"157.794612ms","start":"2026-10-17T08:50:22.193538Z","end":"2026-10-17T08:50:22.351333Z","steps":["trace[2050047169] 'agreement among raft nodes before linearized reading' (duration: 157.538705ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:50:27.712422Z","caller":"traceutil/trace.go:171","msg":"trace[810318499] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"165.062796ms","start":"2026-10-17T08:50:27.547317Z","end":"2026-10-17T08:50:27.71238Z","steps":["trace[810318499] 'process raft request' (duration: 164.897672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:27.907381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.942557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T08:50:27.90756Z","caller":"traceutil/trace.go:171","msg":"trace[460397704] linearizableReadLoop","detail":"{readStateIndex:27598; appliedIndex:27596; }","duration":"344.278143ms","start":"2026-10-17T08:50:27.563266Z","end":"2026-10-17T08:50:27.907544Z","steps":["trace[460397704] 'read index received' (duration: 148.947007ms)","trace[460397704] 'applied index is now lower than readState.Index' (duration: 195.329735ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:50:27.907648Z","caller":"traceutil/trace.go:171","msg":"trace[102949094] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"359.977261ms","start":"2026-10-17T08:50:27.547641Z","end":"2026-10-17T08:50:27.907618Z","steps":["trace[102949094] 'process raft request' (duration: 197.47821ms)","trace[102949094] 'compare' (duration: 161.493146ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:50:27.907717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.454197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T08:50:27.907812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:27.54761Z","time spent":"360.113245ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:27.90785Z","caller":"traceutil/trace.go:171","msg":"trace[1763446188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26716; }","duration":"344.555949ms","start":"2026-10-17T08:50:27.563249Z","end":"2026-10-17T08:50:27.907805Z","steps":["trace[1763446188] 'agreement among raft nodes before linearized reading' (duration: 344.416576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:27.907893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:27.563182Z","time spent":"344.696663ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T08:50:27.90828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.321271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T08:50:27.908334Z","caller":"traceutil/trace.go:171","msg":"trace[200045511] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26716; }","duration":"174.429574ms","start":"2026-10-17T08:50:27.73389Z","end":"2026-10-17T08:50:27.90832Z","steps":["trace[200045511] 'agreement among raft nodes before linearized reading' (duration: 174.332142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:28.280919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.882808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-17T08:50:28.281338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.330082ms","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-17T08:50:28.28145Z","caller":"traceutil/trace.go:171","msg":"trace[1806222212] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26716; }","duration":"235.484496ms","start":"2026-10-17T08:50:28.045945Z","end":"2026-10-17T08:50:28.281429Z","steps":["trace[1806222212] 'count revisions from in-memory index tree' (duration: 235.25945ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:50:28.281868Z","caller":"traceutil/trace.go:171","msg":"trace[362533677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26716; }","duration":"251.01653ms","start":"2026-10-17T08:50:28.029966Z","end":"2026-10-17T08:50:28.280983Z","steps":["trace[362533677] 'range keys from in-memory index tree' (duration: 250.700472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:28.281969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.955353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T08:50:28.282152Z","caller":"traceutil/trace.go:171","msg":"trace[48189123] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"227.611546ms","start":"2026-10-17T08:50:28.054522Z","end":"2026-10-17T08:50:28.282133Z","steps":["trace[48189123] 'process raft request' (duration: 51.405786ms)","trace[48189123] 'compare' (duration: 174.907737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:50:29.221671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.010586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T08:50:29.22189Z","caller":"traceutil/trace.go:171","msg":"trace[1689189464] linearizableReadLoop","detail":"{readStateIndex:27601; appliedIndex:27599; }","duration":"1.053914573s","start":"2026-10-17T08:50:28.167961Z","end":"2026-10-17T08:50:29.221875Z","steps":["trace[1689189464] 'read index received' (duration: 257.615809ms)","trace[1689189464] 'applied index is now lower than readState.Index' (duration: 796.297794ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:50:29.221963Z","caller":"traceutil/trace.go:171","msg":"trace[1684539916] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"1.145333777s","start":"2026-10-17T08:50:28.07662Z","end":"2026-10-17T08:50:29.221954Z","steps":["trace[1684539916] 'process raft request' (duration: 1.145150242s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.222028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.076592Z","time spent":"1.145398269s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.222053Z","caller":"traceutil/trace.go:171","msg":"trace[1157100033] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"1.149929092s","start":"2026-10-17T08:50:28.072092Z","end":"2026-10-17T08:50:29.222021Z","steps":["trace[1157100033] 'process raft request' (duration: 353.479475ms)","trace[1157100033] 'compare' (duration: 793.80123ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:50:29.222209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.072074Z","time spent":"1.150052096s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.222306Z","caller":"traceutil/trace.go:171","msg":"trace[952316514] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"1.041690682s","start":"2026-10-17T08:50:28.180582Z","end":"2026-10-17T08:50:29.222273Z","steps":["trace[952316514] 'process raft request' (duration: 1.041258421s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.222355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054390235s","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-17T08:50:29.22239Z","caller":"traceutil/trace.go:171","msg":"trace[2090168145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26720; }","duration":"1.054451586s","start":"2026-10-17T08:50:28.167927Z","end":"2026-10-17T08:50:29.222379Z","steps":["trace[2090168145] 'agreement among raft nodes before linearized reading' (duration: 1.054279172s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.222448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.16791Z","time spent":"1.054530818s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T08:50:29.222495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.180566Z","time spent":"1.041819756s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.222736Z","caller":"traceutil/trace.go:171","msg":"trace[470766572] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"1.040275656s","start":"2026-10-17T08:50:28.182449Z","end":"2026-10-17T08:50:29.222725Z","steps":["trace[470766572] 'process raft request' (duration: 1.039687141s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.222846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.182438Z","time spent":"1.040337207s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.223151Z","caller":"traceutil/trace.go:171","msg":"trace[2082542456] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"936.780095ms","start":"2026-10-17T08:50:28.286361Z","end":"2026-10-17T08:50:29.223141Z","steps":["trace[2082542456] 'process raft request' (duration: 936.321843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.223222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.286338Z","time spent":"936.847857ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.22338Z","caller":"traceutil/trace.go:171","msg":"trace[35192027] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"836.794262ms","start":"2026-10-17T08:50:28.386567Z","end":"2026-10-17T08:50:29.223361Z","steps":["trace[35192027] 'process raft request' (duration: 836.528336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.223487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.386548Z","time spent":"836.895615ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T08:50:29.626863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055279217s","expected-duration":"1s"} {"level":"warn","ts":"2026-10-17T08:50:29.857261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.973617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T08:50:29.857381Z","caller":"traceutil/trace.go:171","msg":"trace[46235061] linearizableReadLoop","detail":"{readStateIndex:27606; appliedIndex:27605; }","duration":"635.435664ms","start":"2026-10-17T08:50:29.221924Z","end":"2026-10-17T08:50:29.857359Z","steps":["trace[46235061] 'read index received' (duration: 405.15624ms)","trace[46235061] 'applied index is now lower than readState.Index' (duration: 230.277604ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T08:50:29.857745Z","caller":"traceutil/trace.go:171","msg":"trace[1719943976] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"1.345261859s","start":"2026-10-17T08:50:28.512464Z","end":"2026-10-17T08:50:29.857725Z","steps":["trace[1719943976] 'process raft request' (duration: 1.114680448s)","trace[1719943976] 'compare' (duration: 229.561846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T08:50:29.85804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010485288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T08:50:29.858058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472245588s","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-17T08:50:29.858168Z","caller":"traceutil/trace.go:171","msg":"trace[15554694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26726; }","duration":"1.472416232s","start":"2026-10-17T08:50:28.385728Z","end":"2026-10-17T08:50:29.858144Z","steps":["trace[15554694] 'agreement among raft nodes before linearized reading' (duration: 1.472154455s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.858174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000650648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T08:50:29.858233Z","caller":"traceutil/trace.go:171","msg":"trace[1977671127] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26726; }","duration":"1.000734121s","start":"2026-10-17T08:50:28.857482Z","end":"2026-10-17T08:50:29.858217Z","steps":["trace[1977671127] 'agreement among raft nodes before linearized reading' (duration: 1.000628768s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.858285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.857449Z","time spent":"1.000823113s","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-10-17T08:50:29.858232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.385709Z","time spent":"1.472508934s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T08:50:29.857839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.512441Z","time spent":"1.345350972s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.858109Z","caller":"traceutil/trace.go:171","msg":"trace[573500999] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26726; }","duration":"1.010624912s","start":"2026-10-17T08:50:28.847469Z","end":"2026-10-17T08:50:29.858094Z","steps":["trace[573500999] 'agreement among raft nodes before linearized reading' (duration: 1.010293493s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.858491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469552719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-17T08:50:29.858588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.847445Z","time spent":"1.011102444s","remote":"127.0.0.1:55298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":126,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-10-17T08:50:29.858223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010680654s","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-17T08:50:29.858663Z","caller":"traceutil/trace.go:171","msg":"trace[5237354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26726; }","duration":"1.469748024s","start":"2026-10-17T08:50:28.388872Z","end":"2026-10-17T08:50:29.85862Z","steps":["trace[5237354] 'agreement among raft nodes before linearized reading' (duration: 1.469354844s)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.858722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.388862Z","time spent":"1.469847226s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-17T08:50:29.858711Z","caller":"traceutil/trace.go:171","msg":"trace[1091907693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26726; }","duration":"1.011158706s","start":"2026-10-17T08:50:28.847502Z","end":"2026-10-17T08:50:29.858661Z","steps":["trace[1091907693] 'agreement among raft nodes before linearized reading' (duration: 1.010634323s)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:50:29.858731Z","caller":"traceutil/trace.go:171","msg":"trace[1642889279] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"630.520779ms","start":"2026-10-17T08:50:29.228155Z","end":"2026-10-17T08:50:29.858676Z","steps":["trace[1642889279] 'process raft request' (duration: 629.451411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.858792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:28.847479Z","time spent":"1.011289769s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-17T08:50:29.858901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:29.228118Z","time spent":"630.717204ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T08:50:29.858035Z","caller":"traceutil/trace.go:171","msg":"trace[1461833659] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"296.767915ms","start":"2026-10-17T08:50:29.561047Z","end":"2026-10-17T08:50:29.857815Z","steps":["trace[1461833659] 'process raft request' (duration: 296.669642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.85949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.338852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T08:50:29.859548Z","caller":"traceutil/trace.go:171","msg":"trace[901295610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26727; }","duration":"305.448265ms","start":"2026-10-17T08:50:29.554083Z","end":"2026-10-17T08:50:29.859531Z","steps":["trace[901295610] 'agreement among raft nodes before linearized reading' (duration: 305.297101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T08:50:29.859595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T08:50:29.554052Z","time spent":"305.533598ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-17T08:50:29.859826Z","caller":"traceutil/trace.go:171","msg":"trace[1325568903] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"139.057285ms","start":"2026-10-17T08:50:29.720752Z","end":"2026-10-17T08:50:29.859809Z","steps":["trace[1325568903] 'process raft request' (duration: 138.503681ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T08:51:46.419228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24702} {"level":"info","ts":"2026-10-17T08:51:46.580168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24702,"took":"156.293854ms","hash":832926071,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":35840000,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T08:51:46.580247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":832926071,"revision":24702,"compact-revision":21531} {"level":"info","ts":"2026-10-17T08:54:27.169269Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T08:54:27.175806Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T08:54:27.175938Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T08:56:46.426575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27475} {"level":"info","ts":"2026-10-17T08:56:46.559423Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27475,"took":"128.255832ms","hash":2427912966,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":37122048,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-17T08:56:46.559515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2427912966,"revision":27475,"compact-revision":24702} {"level":"info","ts":"2026-10-17T09:01:46.43954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30171} {"level":"info","ts":"2026-10-17T09:01:46.607721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30171,"took":"163.106742ms","hash":273755887,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T09:01:46.60782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":273755887,"revision":30171,"compact-revision":27475} {"level":"info","ts":"2026-10-17T09:06:46.446846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32490} {"level":"info","ts":"2026-10-17T09:06:46.644144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32490,"took":"192.955779ms","hash":3690326393,"current-db-size-bytes":59375616,"current-db-size":"59 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T09:06:46.644217Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3690326393,"revision":32490,"compact-revision":30171} {"level":"info","ts":"2026-10-17T09:11:46.455521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34755} {"level":"info","ts":"2026-10-17T09:11:46.609779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34755,"took":"147.70099ms","hash":1044870061,"current-db-size-bytes":59375616,"current-db-size":"59 MB","current-db-size-in-use-bytes":32124928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T09:11:46.609843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1044870061,"revision":34755,"compact-revision":32490} {"level":"info","ts":"2026-10-17T09:15:33.336402Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T09:15:33.344773Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T09:15:33.34489Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T09:16:46.463327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37049} {"level":"info","ts":"2026-10-17T09:16:46.595273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37049,"took":"127.202075ms","hash":2066812343,"current-db-size-bytes":59801600,"current-db-size":"60 MB","current-db-size-in-use-bytes":32808960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T09:16:46.595324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2066812343,"revision":37049,"compact-revision":34755} {"level":"info","ts":"2026-10-17T09:21:46.471607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39326} {"level":"info","ts":"2026-10-17T09:21:46.605344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39326,"took":"130.023359ms","hash":414428763,"current-db-size-bytes":61501440,"current-db-size":"62 MB","current-db-size-in-use-bytes":31711232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T09:21:46.6054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":414428763,"revision":39326,"compact-revision":37049}