{"level":"warn","ts":"2026-09-15T22:45:23.228299Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:45:23.228419Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.174: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.174:2380","--initial-cluster=instance=https://162.253.55.174:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.174:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.174:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-15T22:45:23.22852Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:45:23.228537Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.174:2380"]} {"level":"info","ts":"2026-09-15T22:45:23.228586Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-15T22:45:23.22952Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.174:2379"]} {"level":"info","ts":"2026-09-15T22:45:23.229643Z","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.174:2380"],"listen-peer-urls":["https://162.253.55.174:2380"],"advertise-client-urls":["https://162.253.55.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.174:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-15T22:45:23.233667Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.764747ms"} {"level":"info","ts":"2026-09-15T22:45:23.239092Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"757b052826ed9e87","cluster-id":"6c32c51160b3149f"} {"level":"info","ts":"2026-09-15T22:45:23.239177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:45:23.239208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 became follower at term 0"} {"level":"info","ts":"2026-09-15T22:45:23.239228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 757b052826ed9e87 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:45:23.239246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 became follower at term 1"} {"level":"info","ts":"2026-09-15T22:45:23.239309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 switched to configuration voters=(8465365594582916743)"} {"level":"warn","ts":"2026-09-15T22:45:23.245289Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:45:23.247134Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:45:23.248748Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-15T22:45:23.251818Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"757b052826ed9e87","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:45:23.252019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"757b052826ed9e87","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:45:23.252228Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-15T22:45:23.252305Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-15T22:45:23.25232Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-15T22:45:23.253442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 switched to configuration voters=(8465365594582916743)"} {"level":"info","ts":"2026-09-15T22:45:23.254331Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c32c51160b3149f","local-member-id":"757b052826ed9e87","added-peer-id":"757b052826ed9e87","added-peer-peer-urls":["https://162.253.55.174:2380"]} {"level":"info","ts":"2026-09-15T22:45:23.257049Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-15T22:45:23.257201Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.174:2380"} {"level":"info","ts":"2026-09-15T22:45:23.257264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.174:2380"} {"level":"info","ts":"2026-09-15T22:45:23.257395Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"757b052826ed9e87","initial-advertise-peer-urls":["https://162.253.55.174:2380"],"listen-peer-urls":["https://162.253.55.174:2380"],"advertise-client-urls":["https://162.253.55.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:45:23.257457Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:45:23.740209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:45:23.74027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:45:23.74031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 received MsgPreVoteResp from 757b052826ed9e87 at term 1"} {"level":"info","ts":"2026-09-15T22:45:23.740327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:45:23.740338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 received MsgVoteResp from 757b052826ed9e87 at term 2"} {"level":"info","ts":"2026-09-15T22:45:23.740354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"757b052826ed9e87 became leader at term 2"} {"level":"info","ts":"2026-09-15T22:45:23.740366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 757b052826ed9e87 elected leader 757b052826ed9e87 at term 2"} {"level":"info","ts":"2026-09-15T22:45:23.747128Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:23.747302Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"757b052826ed9e87","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.174:2379]}","request-path":"/0/members/757b052826ed9e87/attributes","cluster-id":"6c32c51160b3149f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:45:23.747321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:45:23.747364Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:45:23.747687Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:45:23.747731Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:45:23.74841Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c32c51160b3149f","local-member-id":"757b052826ed9e87","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:23.74852Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:23.748543Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:45:23.7504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:45:23.753569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.174:2379"} {"level":"info","ts":"2026-09-15T22:45:28.529277Z","caller":"traceutil/trace.go:171","msg":"trace[1582437076] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"100.918805ms","start":"2026-09-15T22:45:28.428327Z","end":"2026-09-15T22:45:28.529246Z","steps":["trace[1582437076] 'process raft request' (duration: 98.871202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:45:37.33885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.08937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:45:37.338969Z","caller":"traceutil/trace.go:171","msg":"trace[1538670425] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"226.101054ms","start":"2026-09-15T22:45:37.11285Z","end":"2026-09-15T22:45:37.338951Z","steps":["trace[1538670425] 'read index received' (duration: 59.162962ms)","trace[1538670425] 'applied index is now lower than readState.Index' (duration: 166.936812ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:45:37.339056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.203726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:45:37.339086Z","caller":"traceutil/trace.go:171","msg":"trace[523604676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"226.269628ms","start":"2026-09-15T22:45:37.112807Z","end":"2026-09-15T22:45:37.339076Z","steps":["trace[523604676] 'agreement among raft nodes before linearized reading' (duration: 226.188496ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:45:37.33904Z","caller":"traceutil/trace.go:171","msg":"trace[1600722851] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"243.09317ms","start":"2026-09-15T22:45:37.095905Z","end":"2026-09-15T22:45:37.338998Z","steps":["trace[1600722851] 'process raft request' (duration: 76.178519ms)","trace[1600722851] 'compare' (duration: 165.902945ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:45:37.539083Z","caller":"traceutil/trace.go:171","msg":"trace[1046869364] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"160.822276ms","start":"2026-09-15T22:45:37.378231Z","end":"2026-09-15T22:45:37.539053Z","steps":["trace[1046869364] 'read index received' (duration: 160.62871ms)","trace[1046869364] 'applied index is now lower than readState.Index' (duration: 192.846µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:45:37.539201Z","caller":"traceutil/trace.go:171","msg":"trace[288821506] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"185.88769ms","start":"2026-09-15T22:45:37.353294Z","end":"2026-09-15T22:45:37.539182Z","steps":["trace[288821506] 'process raft request' (duration: 185.619383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:45:37.539335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.088943ms","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-09-15T22:45:37.539381Z","caller":"traceutil/trace.go:171","msg":"trace[1014395665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"161.179754ms","start":"2026-09-15T22:45:37.378189Z","end":"2026-09-15T22:45:37.539368Z","steps":["trace[1014395665] 'agreement among raft nodes before linearized reading' (duration: 161.086422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:45:37.539378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.229891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T22:45:37.539462Z","caller":"traceutil/trace.go:171","msg":"trace[1996152269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"160.348833ms","start":"2026-09-15T22:45:37.379092Z","end":"2026-09-15T22:45:37.539441Z","steps":["trace[1996152269] 'agreement among raft nodes before linearized reading' (duration: 160.179069ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:45:37.789814Z","caller":"traceutil/trace.go:171","msg":"trace[470815760] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:416; }","duration":"165.953568ms","start":"2026-09-15T22:45:37.623836Z","end":"2026-09-15T22:45:37.789789Z","steps":["trace[470815760] 'read index received' (duration: 110.640936ms)","trace[470815760] 'applied index is now lower than readState.Index' (duration: 55.311782ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:45:37.789919Z","caller":"traceutil/trace.go:171","msg":"trace[851959980] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"246.129948ms","start":"2026-09-15T22:45:37.543751Z","end":"2026-09-15T22:45:37.789881Z","steps":["trace[851959980] 'process raft request' (duration: 190.750444ms)","trace[851959980] 'compare' (duration: 55.045006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:45:37.789967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.112021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-15T22:45:37.790003Z","caller":"traceutil/trace.go:171","msg":"trace[1094567724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"166.212784ms","start":"2026-09-15T22:45:37.62378Z","end":"2026-09-15T22:45:37.789992Z","steps":["trace[1094567724] 'agreement among raft nodes before linearized reading' (duration: 166.117611ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:45:37.790125Z","caller":"traceutil/trace.go:171","msg":"trace[1638016334] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"246.304763ms","start":"2026-09-15T22:45:37.543794Z","end":"2026-09-15T22:45:37.790098Z","steps":["trace[1638016334] 'process raft request' (duration: 245.911963ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:10.544062Z","caller":"traceutil/trace.go:171","msg":"trace[778519419] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"117.159348ms","start":"2026-09-15T22:46:10.426858Z","end":"2026-09-15T22:46:10.544018Z","steps":["trace[778519419] 'process raft request' (duration: 51.085306ms)","trace[778519419] 'compare' (duration: 65.807315ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:46:10.753727Z","caller":"traceutil/trace.go:171","msg":"trace[314410646] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"130.157263ms","start":"2026-09-15T22:46:10.623526Z","end":"2026-09-15T22:46:10.753683Z","steps":["trace[314410646] 'process raft request' (duration: 46.59974ms)","trace[314410646] 'compare' (duration: 83.329118ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:46:15.839084Z","caller":"traceutil/trace.go:171","msg":"trace[451935981] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"134.518216ms","start":"2026-09-15T22:46:15.704539Z","end":"2026-09-15T22:46:15.839057Z","steps":["trace[451935981] 'process raft request' (duration: 133.284014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:46:16.315389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.647809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:46:16.315511Z","caller":"traceutil/trace.go:171","msg":"trace[724108142] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"150.283402ms","start":"2026-09-15T22:46:16.165209Z","end":"2026-09-15T22:46:16.315493Z","steps":["trace[724108142] 'read index received' (duration: 48.417738ms)","trace[724108142] 'applied index is now lower than readState.Index' (duration: 101.864554ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:46:16.315613Z","caller":"traceutil/trace.go:171","msg":"trace[928456315] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"232.145592ms","start":"2026-09-15T22:46:16.083456Z","end":"2026-09-15T22:46:16.315602Z","steps":["trace[928456315] 'process raft request' (duration: 130.200754ms)","trace[928456315] 'compare' (duration: 101.451935ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:46:16.315828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.832384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-15T22:46:16.315894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.665272ms","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-09-15T22:46:16.315937Z","caller":"traceutil/trace.go:171","msg":"trace[387870132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"146.971897ms","start":"2026-09-15T22:46:16.168938Z","end":"2026-09-15T22:46:16.31591Z","steps":["trace[387870132] 'agreement among raft nodes before linearized reading' (duration: 146.755302ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:16.315984Z","caller":"traceutil/trace.go:171","msg":"trace[48743587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"150.799356ms","start":"2026-09-15T22:46:16.165165Z","end":"2026-09-15T22:46:16.315965Z","steps":["trace[48743587] 'agreement among raft nodes before linearized reading' (duration: 150.56917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:46:16.316088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.533059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t9cr5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-15T22:46:16.316137Z","caller":"traceutil/trace.go:171","msg":"trace[81351847] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t9cr5; range_end:; response_count:1; response_revision:651; }","duration":"105.615511ms","start":"2026-09-15T22:46:16.210506Z","end":"2026-09-15T22:46:16.316122Z","steps":["trace[81351847] 'agreement among raft nodes before linearized reading' (duration: 105.480927ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:16.810049Z","caller":"traceutil/trace.go:171","msg":"trace[1312357666] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"475.895582ms","start":"2026-09-15T22:46:16.334115Z","end":"2026-09-15T22:46:16.810011Z","steps":["trace[1312357666] 'process raft request' (duration: 463.848461ms)","trace[1312357666] 'compare' (duration: 11.934138ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:46:16.810428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:46:16.334094Z","time spent":"476.023885ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:46:16.870352Z","caller":"traceutil/trace.go:171","msg":"trace[655017654] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"265.784078ms","start":"2026-09-15T22:46:16.604532Z","end":"2026-09-15T22:46:16.870316Z","steps":["trace[655017654] 'read index received' (duration: 193.481124ms)","trace[655017654] 'applied index is now lower than readState.Index' (duration: 72.301504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:46:16.870583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.040685ms","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-09-15T22:46:16.870637Z","caller":"traceutil/trace.go:171","msg":"trace[2133915410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"266.163237ms","start":"2026-09-15T22:46:16.604455Z","end":"2026-09-15T22:46:16.870618Z","steps":["trace[2133915410] 'agreement among raft nodes before linearized reading' (duration: 265.985923ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:16.870648Z","caller":"traceutil/trace.go:171","msg":"trace[1997255152] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"446.032223ms","start":"2026-09-15T22:46:16.424564Z","end":"2026-09-15T22:46:16.870596Z","steps":["trace[1997255152] 'process raft request' (duration: 445.114389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:46:16.870925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:46:16.424519Z","time spent":"446.275959ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:46:32.54509Z","caller":"traceutil/trace.go:171","msg":"trace[696603758] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"147.254923ms","start":"2026-09-15T22:46:32.39781Z","end":"2026-09-15T22:46:32.545064Z","steps":["trace[696603758] 'read index received' (duration: 147.078338ms)","trace[696603758] 'applied index is now lower than readState.Index' (duration: 175.545µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:46:32.545353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.546351ms","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-09-15T22:46:32.545406Z","caller":"traceutil/trace.go:171","msg":"trace[211716679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"147.617023ms","start":"2026-09-15T22:46:32.397775Z","end":"2026-09-15T22:46:32.545392Z","steps":["trace[211716679] 'agreement among raft nodes before linearized reading' (duration: 147.433118ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:32.545318Z","caller":"traceutil/trace.go:171","msg":"trace[1244903184] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"148.552738ms","start":"2026-09-15T22:46:32.396578Z","end":"2026-09-15T22:46:32.545131Z","steps":["trace[1244903184] 'process raft request' (duration: 148.318242ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:46:32.748863Z","caller":"traceutil/trace.go:171","msg":"trace[2058494388] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"199.243044ms","start":"2026-09-15T22:46:32.54959Z","end":"2026-09-15T22:46:32.748833Z","steps":["trace[2058494388] 'process raft request' (duration: 143.337333ms)","trace[2058494388] 'compare' (duration: 55.675844ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:46:32.749128Z","caller":"traceutil/trace.go:171","msg":"trace[1541488555] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"199.528941ms","start":"2026-09-15T22:46:32.54959Z","end":"2026-09-15T22:46:32.749119Z","steps":["trace[1541488555] 'process raft request' (duration: 199.174352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:47:51.446632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.832875ms","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-09-15T22:47:51.446757Z","caller":"traceutil/trace.go:171","msg":"trace[1588497406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1907; }","duration":"249.97189ms","start":"2026-09-15T22:47:51.196759Z","end":"2026-09-15T22:47:51.446731Z","steps":["trace[1588497406] 'range keys from in-memory index tree' (duration: 249.574289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:47:51.446632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.896268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-15T22:47:51.446927Z","caller":"traceutil/trace.go:171","msg":"trace[1121518773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1907; }","duration":"252.252368ms","start":"2026-09-15T22:47:51.194657Z","end":"2026-09-15T22:47:51.446909Z","steps":["trace[1121518773] 'range keys from in-memory index tree' (duration: 251.706713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:00.141691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.130831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-15T22:48:00.141841Z","caller":"traceutil/trace.go:171","msg":"trace[1568353518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1986; }","duration":"142.359447ms","start":"2026-09-15T22:47:59.999441Z","end":"2026-09-15T22:48:00.1418Z","steps":["trace[1568353518] 'range keys from in-memory index tree' (duration: 141.859224ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:00.15999Z","caller":"traceutil/trace.go:171","msg":"trace[1569213075] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"119.476374ms","start":"2026-09-15T22:48:00.040471Z","end":"2026-09-15T22:48:00.159947Z","steps":["trace[1569213075] 'process raft request' (duration: 119.114464ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:04.263811Z","caller":"traceutil/trace.go:171","msg":"trace[532978167] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2084; }","duration":"160.025497ms","start":"2026-09-15T22:48:04.103758Z","end":"2026-09-15T22:48:04.263784Z","steps":["trace[532978167] 'read index received' (duration: 159.935605ms)","trace[532978167] 'applied index is now lower than readState.Index' (duration: 88.302µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:48:04.26416Z","caller":"traceutil/trace.go:171","msg":"trace[866717824] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"172.364312ms","start":"2026-09-15T22:48:04.091761Z","end":"2026-09-15T22:48:04.264126Z","steps":["trace[866717824] 'process raft request' (duration: 171.820708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:04.264438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.628542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-09-15T22:48:04.264556Z","caller":"traceutil/trace.go:171","msg":"trace[1844327856] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2013; }","duration":"160.844298ms","start":"2026-09-15T22:48:04.103685Z","end":"2026-09-15T22:48:04.264529Z","steps":["trace[1844327856] 'agreement among raft nodes before linearized reading' (duration: 160.460449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:04.540286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.836948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:48:04.540364Z","caller":"traceutil/trace.go:171","msg":"trace[960421351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2024; }","duration":"200.973461ms","start":"2026-09-15T22:48:04.33937Z","end":"2026-09-15T22:48:04.540344Z","steps":["trace[960421351] 'range keys from in-memory index tree' (duration: 200.749475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:37.257669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.909857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-15T22:48:37.257803Z","caller":"traceutil/trace.go:171","msg":"trace[691435911] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2207; }","duration":"102.123512ms","start":"2026-09-15T22:48:37.155653Z","end":"2026-09-15T22:48:37.257776Z","steps":["trace[691435911] 'count revisions from in-memory index tree' (duration: 101.767673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.083437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.600092ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-15T22:48:43.083928Z","caller":"traceutil/trace.go:171","msg":"trace[1380338854] linearizableReadLoop","detail":"{readStateIndex:2317; appliedIndex:2316; }","duration":"563.979959ms","start":"2026-09-15T22:48:42.519871Z","end":"2026-09-15T22:48:43.083851Z","steps":["trace[1380338854] 'read index received' (duration: 69.112µs)","trace[1380338854] 'applied index is now lower than readState.Index' (duration: 563.908377ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:43.084057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.136773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:48:43.084164Z","caller":"traceutil/trace.go:171","msg":"trace[906335419] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2236; }","duration":"564.320668ms","start":"2026-09-15T22:48:42.519821Z","end":"2026-09-15T22:48:43.084141Z","steps":["trace[906335419] 'agreement among raft nodes before linearized reading' (duration: 564.123493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.084247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.519794Z","time spent":"564.43667ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-15T22:48:43.092642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.491666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:48:43.092709Z","caller":"traceutil/trace.go:171","msg":"trace[1962781629] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2237; }","duration":"572.612749ms","start":"2026-09-15T22:48:42.520082Z","end":"2026-09-15T22:48:43.092694Z","steps":["trace[1962781629] 'agreement among raft nodes before linearized reading' (duration: 572.493586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.092737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.52006Z","time spent":"572.6712ms","remote":"127.0.0.1:58366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-09-15T22:48:43.092726Z","caller":"traceutil/trace.go:171","msg":"trace[1336957744] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"543.254222ms","start":"2026-09-15T22:48:42.54944Z","end":"2026-09-15T22:48:43.092694Z","steps":["trace[1336957744] 'process raft request' (duration: 542.382209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.092869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.549419Z","time spent":"543.370894ms","remote":"127.0.0.1:58346","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-09-15T22:48:43.093007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.22943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-15T22:48:43.092989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.039959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:48:43.093084Z","caller":"traceutil/trace.go:171","msg":"trace[2047679420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2237; }","duration":"508.362872ms","start":"2026-09-15T22:48:42.584698Z","end":"2026-09-15T22:48:43.093061Z","steps":["trace[2047679420] 'agreement among raft nodes before linearized reading' (duration: 508.055074ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:43.093117Z","caller":"traceutil/trace.go:171","msg":"trace[1764425363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2237; }","duration":"571.220894ms","start":"2026-09-15T22:48:42.521863Z","end":"2026-09-15T22:48:43.093084Z","steps":["trace[1764425363] 'agreement among raft nodes before linearized reading' (duration: 570.961787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.093154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.584667Z","time spent":"508.474505ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-15T22:48:43.093187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:42.521852Z","time spent":"571.314166ms","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-15T22:48:43.386295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.3465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:48:43.3868Z","caller":"traceutil/trace.go:171","msg":"trace[654349284] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"215.640434ms","start":"2026-09-15T22:48:43.171125Z","end":"2026-09-15T22:48:43.386766Z","steps":["trace[654349284] 'process raft request' (duration: 64.709619ms)","trace[654349284] 'compare' (duration: 150.206887ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:48:43.387155Z","caller":"traceutil/trace.go:171","msg":"trace[194483854] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"160.350106ms","start":"2026-09-15T22:48:43.226742Z","end":"2026-09-15T22:48:43.387092Z","steps":["trace[194483854] 'process raft request' (duration: 159.689119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.973352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.792415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:48:43.973472Z","caller":"traceutil/trace.go:171","msg":"trace[1355314868] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"214.923196ms","start":"2026-09-15T22:48:43.75853Z","end":"2026-09-15T22:48:43.973454Z","steps":["trace[1355314868] 'read index received' (duration: 30.111µs)","trace[1355314868] 'applied index is now lower than readState.Index' (duration: 214.891875ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:48:43.973532Z","caller":"traceutil/trace.go:171","msg":"trace[826940364] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"581.471015ms","start":"2026-09-15T22:48:43.392027Z","end":"2026-09-15T22:48:43.973498Z","steps":["trace[826940364] 'process raft request' (duration: 238.423654ms)","trace[826940364] 'compare' (duration: 342.339533ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:43.973653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.125221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-15T22:48:43.973678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.391999Z","time spent":"581.603258ms","remote":"127.0.0.1:58346","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-09-15T22:48:43.973692Z","caller":"traceutil/trace.go:171","msg":"trace[130921827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2241; }","duration":"215.203104ms","start":"2026-09-15T22:48:43.758479Z","end":"2026-09-15T22:48:43.973682Z","steps":["trace[130921827] 'agreement among raft nodes before linearized reading' (duration: 215.06425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.973868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.982822ms","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-09-15T22:48:43.973901Z","caller":"traceutil/trace.go:171","msg":"trace[1197715783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2241; }","duration":"214.048534ms","start":"2026-09-15T22:48:43.759842Z","end":"2026-09-15T22:48:43.973891Z","steps":["trace[1197715783] 'agreement among raft nodes before linearized reading' (duration: 213.960482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:43.974149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.189839ms","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-09-15T22:48:43.974586Z","caller":"traceutil/trace.go:171","msg":"trace[475771135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2241; }","duration":"198.675032ms","start":"2026-09-15T22:48:43.775899Z","end":"2026-09-15T22:48:43.974575Z","steps":["trace[475771135] 'agreement among raft nodes before linearized reading' (duration: 198.1863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:44.279663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.533268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:48:44.279786Z","caller":"traceutil/trace.go:171","msg":"trace[24820494] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"300.698001ms","start":"2026-09-15T22:48:43.979063Z","end":"2026-09-15T22:48:44.279761Z","steps":["trace[24820494] 'process raft request' (duration: 143.978228ms)","trace[24820494] 'compare' (duration: 156.402194ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:48:44.279849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.979033Z","time spent":"300.785263ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:48:44.280846Z","caller":"traceutil/trace.go:171","msg":"trace[258402302] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2322; }","duration":"179.612155ms","start":"2026-09-15T22:48:44.101217Z","end":"2026-09-15T22:48:44.280829Z","steps":["trace[258402302] 'read index received' (duration: 21.949488ms)","trace[258402302] 'applied index is now lower than readState.Index' (duration: 157.661687ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:48:44.280987Z","caller":"traceutil/trace.go:171","msg":"trace[1380276686] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"301.855901ms","start":"2026-09-15T22:48:43.979119Z","end":"2026-09-15T22:48:44.280975Z","steps":["trace[1380276686] 'process raft request' (duration: 301.551483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:44.281061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.979099Z","time spent":"301.916632ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:48:44.281138Z","caller":"traceutil/trace.go:171","msg":"trace[397387411] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"300.033303ms","start":"2026-09-15T22:48:43.981094Z","end":"2026-09-15T22:48:44.281128Z","steps":["trace[397387411] 'process raft request' (duration: 299.658044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:44.281187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:48:43.981081Z","time spent":"300.076945ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:48:44.28136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.302574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d5a022ff8241f0\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-15T22:48:44.281316Z","caller":"traceutil/trace.go:171","msg":"trace[482356448] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"181.535195ms","start":"2026-09-15T22:48:44.099737Z","end":"2026-09-15T22:48:44.281272Z","steps":["trace[482356448] 'process raft request' (duration: 181.055363ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:48:44.281414Z","caller":"traceutil/trace.go:171","msg":"trace[1109455828] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d5a022ff8241f0; range_end:; response_count:1; response_revision:2245; }","duration":"149.370595ms","start":"2026-09-15T22:48:44.132025Z","end":"2026-09-15T22:48:44.281395Z","steps":["trace[1109455828] 'agreement among raft nodes before linearized reading' (duration: 149.238241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:48:44.28165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.300594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-15T22:48:44.281763Z","caller":"traceutil/trace.go:171","msg":"trace[476616450] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2245; }","duration":"180.534489ms","start":"2026-09-15T22:48:44.101197Z","end":"2026-09-15T22:48:44.281732Z","steps":["trace[476616450] 'agreement among raft nodes before linearized reading' (duration: 179.962844ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:49:41.985114Z","caller":"traceutil/trace.go:171","msg":"trace[730940260] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"232.853726ms","start":"2026-09-15T22:49:41.752225Z","end":"2026-09-15T22:49:41.985078Z","steps":["trace[730940260] 'process raft request' (duration: 232.628391ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:49:41.98621Z","caller":"traceutil/trace.go:171","msg":"trace[199634813] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"214.342043ms","start":"2026-09-15T22:49:41.771823Z","end":"2026-09-15T22:49:41.986165Z","steps":["trace[199634813] 'process raft request' (duration: 214.134578ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:50:17.607391Z","caller":"traceutil/trace.go:171","msg":"trace[1074774357] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"123.617906ms","start":"2026-09-15T22:50:17.483733Z","end":"2026-09-15T22:50:17.607351Z","steps":["trace[1074774357] 'process raft request' (duration: 123.412671ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:23.778626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-09-15T22:55:23.865141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"84.820556ms","hash":2396197623,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-15T22:55:23.86522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2396197623,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-09-15T22:55:26.742041Z","caller":"traceutil/trace.go:171","msg":"trace[1632072067] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"108.712075ms","start":"2026-09-15T22:55:26.633291Z","end":"2026-09-15T22:55:26.742003Z","steps":["trace[1632072067] 'process raft request' (duration: 108.610432ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:26.742609Z","caller":"traceutil/trace.go:171","msg":"trace[532204491] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"116.873362ms","start":"2026-09-15T22:55:26.625719Z","end":"2026-09-15T22:55:26.742592Z","steps":["trace[532204491] 'process raft request' (duration: 115.868677ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:26.742878Z","caller":"traceutil/trace.go:171","msg":"trace[452453393] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"117.057368ms","start":"2026-09-15T22:55:26.625809Z","end":"2026-09-15T22:55:26.742866Z","steps":["trace[452453393] 'process raft request' (duration: 115.96441ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:26.743035Z","caller":"traceutil/trace.go:171","msg":"trace[1939564325] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"116.916803ms","start":"2026-09-15T22:55:26.626109Z","end":"2026-09-15T22:55:26.743025Z","steps":["trace[1939564325] 'process raft request' (duration: 115.727613ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:27.244808Z","caller":"traceutil/trace.go:171","msg":"trace[460536327] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"344.4673ms","start":"2026-09-15T22:55:26.900302Z","end":"2026-09-15T22:55:27.24477Z","steps":["trace[460536327] 'process raft request' (duration: 320.659944ms)","trace[460536327] 'compare' (duration: 23.682863ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:55:27.245011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:55:26.900282Z","time spent":"344.618784ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:55:27.248546Z","caller":"traceutil/trace.go:171","msg":"trace[2123688019] linearizableReadLoop","detail":"{readStateIndex:5888; appliedIndex:5884; }","duration":"214.434991ms","start":"2026-09-15T22:55:27.034094Z","end":"2026-09-15T22:55:27.248529Z","steps":["trace[2123688019] 'read index received' (duration: 186.890389ms)","trace[2123688019] 'applied index is now lower than readState.Index' (duration: 27.543162ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:55:27.248782Z","caller":"traceutil/trace.go:171","msg":"trace[307644696] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"347.072497ms","start":"2026-09-15T22:55:26.901698Z","end":"2026-09-15T22:55:27.248771Z","steps":["trace[307644696] 'process raft request' (duration: 346.658176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:27.248853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:55:26.901678Z","time spent":"347.134648ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:55:27.249083Z","caller":"traceutil/trace.go:171","msg":"trace[247396702] transaction","detail":"{read_only:false; response_revision:5678; number_of_response:1; }","duration":"242.592521ms","start":"2026-09-15T22:55:27.006479Z","end":"2026-09-15T22:55:27.249072Z","steps":["trace[247396702] 'process raft request' (duration: 241.967294ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:27.249223Z","caller":"traceutil/trace.go:171","msg":"trace[849477672] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"223.624256ms","start":"2026-09-15T22:55:27.025587Z","end":"2026-09-15T22:55:27.249212Z","steps":["trace[849477672] 'process raft request' (duration: 222.905037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:27.249432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.356015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.174\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-15T22:55:27.249476Z","caller":"traceutil/trace.go:171","msg":"trace[1438238243] range","detail":"{range_begin:/registry/masterleases/162.253.55.174; range_end:; response_count:1; response_revision:5679; }","duration":"215.439697ms","start":"2026-09-15T22:55:27.034021Z","end":"2026-09-15T22:55:27.249461Z","steps":["trace[1438238243] 'agreement among raft nodes before linearized reading' (duration: 215.308984ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:36.303377Z","caller":"traceutil/trace.go:171","msg":"trace[1096332816] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"193.803455ms","start":"2026-09-15T22:55:36.109541Z","end":"2026-09-15T22:55:36.303345Z","steps":["trace[1096332816] 'process raft request' (duration: 193.572419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:36.536882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.306882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:55:36.53713Z","caller":"traceutil/trace.go:171","msg":"trace[2031935916] linearizableReadLoop","detail":"{readStateIndex:6106; appliedIndex:6104; }","duration":"313.473659ms","start":"2026-09-15T22:55:36.223632Z","end":"2026-09-15T22:55:36.537106Z","steps":["trace[2031935916] 'read index received' (duration: 79.445446ms)","trace[2031935916] 'applied index is now lower than readState.Index' (duration: 234.026573ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:55:36.5373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.156092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:55:36.53737Z","caller":"traceutil/trace.go:171","msg":"trace[1307141715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5892; }","duration":"199.270136ms","start":"2026-09-15T22:55:36.338086Z","end":"2026-09-15T22:55:36.537356Z","steps":["trace[1307141715] 'agreement among raft nodes before linearized reading' (duration: 199.172614ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:36.537504Z","caller":"traceutil/trace.go:171","msg":"trace[742263897] transaction","detail":"{read_only:false; response_revision:5892; number_of_response:1; }","duration":"362.07909ms","start":"2026-09-15T22:55:36.175187Z","end":"2026-09-15T22:55:36.537266Z","steps":["trace[742263897] 'process raft request' (duration: 219.263785ms)","trace[742263897] 'compare' (duration: 142.165278ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:55:36.537573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.939851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f76bdbef-0817-4f0a-9c1b-9a123b7acf4d\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-09-15T22:55:36.537582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.720177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-15T22:55:36.537726Z","caller":"traceutil/trace.go:171","msg":"trace[829051849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5892; }","duration":"152.926923ms","start":"2026-09-15T22:55:36.384768Z","end":"2026-09-15T22:55:36.537695Z","steps":["trace[829051849] 'agreement among raft nodes before linearized reading' (duration: 152.569034ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:36.53764Z","caller":"traceutil/trace.go:171","msg":"trace[1274495397] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f76bdbef-0817-4f0a-9c1b-9a123b7acf4d; range_end:; response_count:1; response_revision:5892; }","duration":"314.060114ms","start":"2026-09-15T22:55:36.223562Z","end":"2026-09-15T22:55:36.537622Z","steps":["trace[1274495397] 'agreement among raft nodes before linearized reading' (duration: 313.685395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:55:36.5379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:55:36.223539Z","time spent":"314.343551ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f76bdbef-0817-4f0a-9c1b-9a123b7acf4d\" "} {"level":"warn","ts":"2026-09-15T22:55:36.538552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:55:36.175147Z","time spent":"362.46081ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:55:47.917225Z","caller":"traceutil/trace.go:171","msg":"trace[1140750939] transaction","detail":"{read_only:false; response_revision:5996; number_of_response:1; }","duration":"119.203292ms","start":"2026-09-15T22:55:47.797992Z","end":"2026-09-15T22:55:47.917196Z","steps":["trace[1140750939] 'process raft request' (duration: 118.953526ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:47.91779Z","caller":"traceutil/trace.go:171","msg":"trace[1653616413] transaction","detail":"{read_only:false; response_revision:5997; number_of_response:1; }","duration":"119.485319ms","start":"2026-09-15T22:55:47.798266Z","end":"2026-09-15T22:55:47.917752Z","steps":["trace[1653616413] 'process raft request' (duration: 118.854263ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:55:47.91786Z","caller":"traceutil/trace.go:171","msg":"trace[1770678193] transaction","detail":"{read_only:false; response_revision:5998; number_of_response:1; }","duration":"119.426028ms","start":"2026-09-15T22:55:47.798414Z","end":"2026-09-15T22:55:47.91784Z","steps":["trace[1770678193] 'process raft request' (duration: 119.061139ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:00:23.793071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5613} {"level":"info","ts":"2026-09-15T23:00:23.895092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5613,"took":"99.962707ms","hash":3506000970,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":28856320,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-15T23:00:23.895218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3506000970,"revision":5613,"compact-revision":2977} {"level":"warn","ts":"2026-09-15T23:01:57.676219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.581195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67j7b\" ","response":"range_response_count:1 size:6445"} {"level":"warn","ts":"2026-09-15T23:01:57.676268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.620385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:01:57.676328Z","caller":"traceutil/trace.go:171","msg":"trace[1098846271] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67j7b; range_end:; response_count:1; response_revision:8751; }","duration":"236.718359ms","start":"2026-09-15T23:01:57.439584Z","end":"2026-09-15T23:01:57.676302Z","steps":["trace[1098846271] 'range keys from in-memory index tree' (duration: 236.434211ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:01:57.67635Z","caller":"traceutil/trace.go:171","msg":"trace[1635569907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8751; }","duration":"337.752648ms","start":"2026-09-15T23:01:57.338577Z","end":"2026-09-15T23:01:57.676329Z","steps":["trace[1635569907] 'range keys from in-memory index tree' (duration: 337.48221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:57.676414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:01:57.338557Z","time spent":"337.847741ms","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-15T23:01:57.676525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.248687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:01:57.676589Z","caller":"traceutil/trace.go:171","msg":"trace[1690428317] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8751; }","duration":"258.350549ms","start":"2026-09-15T23:01:57.418221Z","end":"2026-09-15T23:01:57.676571Z","steps":["trace[1690428317] 'count revisions from in-memory index tree' (duration: 258.186156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:57.676787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.054147ms","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-09-15T23:01:57.676823Z","caller":"traceutil/trace.go:171","msg":"trace[1160224726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8751; }","duration":"119.123379ms","start":"2026-09-15T23:01:57.557687Z","end":"2026-09-15T23:01:57.676811Z","steps":["trace[1160224726] 'range keys from in-memory index tree' (duration: 118.879682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:01:57.676829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.758055ms","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-09-15T23:01:57.676917Z","caller":"traceutil/trace.go:171","msg":"trace[1316456201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8751; }","duration":"202.9559ms","start":"2026-09-15T23:01:57.473937Z","end":"2026-09-15T23:01:57.676893Z","steps":["trace[1316456201] 'range keys from in-memory index tree' (duration: 202.53612ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:02.585142Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237c.wal"} {"level":"info","ts":"2026-09-15T23:02:09.906472Z","caller":"traceutil/trace.go:171","msg":"trace[149665150] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"131.486884ms","start":"2026-09-15T23:02:09.77495Z","end":"2026-09-15T23:02:09.906437Z","steps":["trace[149665150] 'process raft request' (duration: 131.273288ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:10.097156Z","caller":"traceutil/trace.go:171","msg":"trace[1675043183] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"317.644736ms","start":"2026-09-15T23:02:09.779484Z","end":"2026-09-15T23:02:10.097128Z","steps":["trace[1675043183] 'process raft request' (duration: 317.39662ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:10.097257Z","caller":"traceutil/trace.go:171","msg":"trace[308026654] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"296.462679ms","start":"2026-09-15T23:02:09.800765Z","end":"2026-09-15T23:02:10.097228Z","steps":["trace[308026654] 'process raft request' (duration: 296.290874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:10.09737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:09.779466Z","time spent":"317.781409ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:02:10.09781Z","caller":"traceutil/trace.go:171","msg":"trace[848801373] linearizableReadLoop","detail":"{readStateIndex:9124; appliedIndex:9122; }","duration":"310.601188ms","start":"2026-09-15T23:02:09.786502Z","end":"2026-09-15T23:02:10.097104Z","steps":["trace[848801373] 'read index received' (duration: 119.811087ms)","trace[848801373] 'applied index is now lower than readState.Index' (duration: 190.788711ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:10.098046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.509561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67j7b\" ","response":"range_response_count:1 size:6445"} {"level":"warn","ts":"2026-09-15T23:02:10.098085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.848226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T23:02:10.098157Z","caller":"traceutil/trace.go:171","msg":"trace[840844978] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8817; }","duration":"207.990219ms","start":"2026-09-15T23:02:09.890147Z","end":"2026-09-15T23:02:10.098137Z","steps":["trace[840844978] 'agreement among raft nodes before linearized reading' (duration: 207.844595ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:10.098153Z","caller":"traceutil/trace.go:171","msg":"trace[1337228259] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67j7b; range_end:; response_count:1; response_revision:8817; }","duration":"311.652385ms","start":"2026-09-15T23:02:09.786474Z","end":"2026-09-15T23:02:10.098127Z","steps":["trace[1337228259] 'agreement among raft nodes before linearized reading' (duration: 310.892265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:10.098205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:09.786463Z","time spent":"311.732886ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6467,"request content":"key:\"/registry/pods/openstack/keepalived-67j7b\" "} {"level":"info","ts":"2026-09-15T23:02:10.967691Z","caller":"traceutil/trace.go:171","msg":"trace[184395927] linearizableReadLoop","detail":"{readStateIndex:9127; appliedIndex:9126; }","duration":"122.270949ms","start":"2026-09-15T23:02:10.845394Z","end":"2026-09-15T23:02:10.967665Z","steps":["trace[184395927] 'read index received' (duration: 122.108275ms)","trace[184395927] 'applied index is now lower than readState.Index' (duration: 161.634µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:10.967931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.526297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-15T23:02:10.96801Z","caller":"traceutil/trace.go:171","msg":"trace[1599511186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8819; }","duration":"122.66433ms","start":"2026-09-15T23:02:10.845333Z","end":"2026-09-15T23:02:10.967997Z","steps":["trace[1599511186] 'agreement among raft nodes before linearized reading' (duration: 122.436334ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:10.968263Z","caller":"traceutil/trace.go:171","msg":"trace[825612519] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"276.787597ms","start":"2026-09-15T23:02:10.690937Z","end":"2026-09-15T23:02:10.967724Z","steps":["trace[825612519] 'process raft request' (duration: 276.519901ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:11.261832Z","caller":"traceutil/trace.go:171","msg":"trace[707489767] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"343.215526ms","start":"2026-09-15T23:02:10.918586Z","end":"2026-09-15T23:02:11.261801Z","steps":["trace[707489767] 'process raft request' (duration: 343.121504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:11.262017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:10.918557Z","time spent":"343.350101ms","remote":"127.0.0.1:58346","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-09-15T23:02:11.262287Z","caller":"traceutil/trace.go:171","msg":"trace[1914054235] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"345.559376ms","start":"2026-09-15T23:02:10.916652Z","end":"2026-09-15T23:02:11.262211Z","steps":["trace[1914054235] 'process raft request' (duration: 299.375582ms)","trace[1914054235] 'compare' (duration: 45.310922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:11.262594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:10.916615Z","time spent":"345.804382ms","remote":"127.0.0.1:58346","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-09-15T23:02:11.301778Z","caller":"traceutil/trace.go:171","msg":"trace[1066118732] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"328.709028ms","start":"2026-09-15T23:02:10.97303Z","end":"2026-09-15T23:02:11.301739Z","steps":["trace[1066118732] 'process raft request' (duration: 328.37374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:11.301997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:10.973009Z","time spent":"328.856811ms","remote":"127.0.0.1:58346","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-09-15T23:02:11.302442Z","caller":"traceutil/trace.go:171","msg":"trace[1006297426] linearizableReadLoop","detail":"{readStateIndex:9131; appliedIndex:9127; }","duration":"279.921078ms","start":"2026-09-15T23:02:11.02249Z","end":"2026-09-15T23:02:11.302411Z","steps":["trace[1006297426] 'read index received' (duration: 193.548531ms)","trace[1006297426] 'applied index is now lower than readState.Index' (duration: 86.371077ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:02:11.302583Z","caller":"traceutil/trace.go:171","msg":"trace[60931216] transaction","detail":"{read_only:false; response_revision:8823; number_of_response:1; }","duration":"316.092817ms","start":"2026-09-15T23:02:10.986439Z","end":"2026-09-15T23:02:11.302531Z","steps":["trace[60931216] 'process raft request' (duration: 315.169753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:11.302737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:10.98643Z","time spent":"316.224511ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:02:11.303017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.503143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T23:02:11.303097Z","caller":"traceutil/trace.go:171","msg":"trace[467092449] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8823; }","duration":"280.607116ms","start":"2026-09-15T23:02:11.022469Z","end":"2026-09-15T23:02:11.303076Z","steps":["trace[467092449] 'agreement among raft nodes before linearized reading' (duration: 280.367789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:11.303756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.402315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-15T23:02:11.303839Z","caller":"traceutil/trace.go:171","msg":"trace[861499586] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8823; }","duration":"188.166794ms","start":"2026-09-15T23:02:11.115653Z","end":"2026-09-15T23:02:11.30382Z","steps":["trace[861499586] 'agreement among raft nodes before linearized reading' (duration: 187.340974ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:11.428746Z","caller":"traceutil/trace.go:171","msg":"trace[1124663767] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"117.924449ms","start":"2026-09-15T23:02:11.310784Z","end":"2026-09-15T23:02:11.428709Z","steps":["trace[1124663767] 'process raft request' (duration: 54.467845ms)","trace[1124663767] 'compare' (duration: 62.976522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:12.291162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.489031ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-15T23:02:12.291294Z","caller":"traceutil/trace.go:171","msg":"trace[2022338150] linearizableReadLoop","detail":"{readStateIndex:9135; appliedIndex:9134; }","duration":"188.928874ms","start":"2026-09-15T23:02:12.102345Z","end":"2026-09-15T23:02:12.291274Z","steps":["trace[2022338150] 'read index received' (duration: 4.32928ms)","trace[2022338150] 'applied index is now lower than readState.Index' (duration: 184.597984ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:02:12.291523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.157639ms","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-09-15T23:02:12.29159Z","caller":"traceutil/trace.go:171","msg":"trace[4283171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8826; }","duration":"189.265602ms","start":"2026-09-15T23:02:12.102307Z","end":"2026-09-15T23:02:12.291573Z","steps":["trace[4283171] 'agreement among raft nodes before linearized reading' (duration: 189.050887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:12.295754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.349111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67j7b\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-15T23:02:12.295807Z","caller":"traceutil/trace.go:171","msg":"trace[2146916520] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67j7b; range_end:; response_count:1; response_revision:8827; }","duration":"181.489865ms","start":"2026-09-15T23:02:12.114305Z","end":"2026-09-15T23:02:12.295795Z","steps":["trace[2146916520] 'agreement among raft nodes before linearized reading' (duration: 181.341331ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:12.29586Z","caller":"traceutil/trace.go:171","msg":"trace[1084912060] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"192.269718ms","start":"2026-09-15T23:02:12.103562Z","end":"2026-09-15T23:02:12.295832Z","steps":["trace[1084912060] 'process raft request' (duration: 191.950461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:39.956156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11423275620026612604,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T23:02:39.980124Z","caller":"traceutil/trace.go:171","msg":"trace[329421468] linearizableReadLoop","detail":"{readStateIndex:9345; appliedIndex:9344; }","duration":"524.324792ms","start":"2026-09-15T23:02:39.455764Z","end":"2026-09-15T23:02:39.980089Z","steps":["trace[329421468] 'read index received' (duration: 524.139677ms)","trace[329421468] 'applied index is now lower than readState.Index' (duration: 183.555µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:02:39.980185Z","caller":"traceutil/trace.go:171","msg":"trace[538898590] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"560.219805ms","start":"2026-09-15T23:02:39.419885Z","end":"2026-09-15T23:02:39.980105Z","steps":["trace[538898590] 'process raft request' (duration: 559.990758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:39.980349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.562748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-15T23:02:39.980435Z","caller":"traceutil/trace.go:171","msg":"trace[1196163718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9028; }","duration":"524.700331ms","start":"2026-09-15T23:02:39.455717Z","end":"2026-09-15T23:02:39.980417Z","steps":["trace[1196163718] 'agreement among raft nodes before linearized reading' (duration: 524.453215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:39.980488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:39.455701Z","time spent":"524.773213ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-15T23:02:39.980599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.438167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-15T23:02:39.980564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:39.41986Z","time spent":"560.450841ms","remote":"127.0.0.1:58346","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-09-15T23:02:39.980642Z","caller":"traceutil/trace.go:171","msg":"trace[409368113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9028; }","duration":"366.52921ms","start":"2026-09-15T23:02:39.614101Z","end":"2026-09-15T23:02:39.98063Z","steps":["trace[409368113] 'agreement among raft nodes before linearized reading' (duration: 366.169551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:02:39.980775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:02:39.614079Z","time spent":"366.683263ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-15T23:02:44.239312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.499155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T23:02:44.239635Z","caller":"traceutil/trace.go:171","msg":"trace[723442022] transaction","detail":"{read_only:false; response_revision:9051; number_of_response:1; }","duration":"156.14721ms","start":"2026-09-15T23:02:44.083452Z","end":"2026-09-15T23:02:44.239599Z","steps":["trace[723442022] 'process raft request' (duration: 155.980946ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:02:44.239782Z","caller":"traceutil/trace.go:171","msg":"trace[1095688515] transaction","detail":"{read_only:false; response_revision:9050; number_of_response:1; }","duration":"156.810398ms","start":"2026-09-15T23:02:44.082937Z","end":"2026-09-15T23:02:44.239747Z","steps":["trace[1095688515] 'process raft request' (duration: 31.502742ms)","trace[1095688515] 'compare' (duration: 124.322651ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:02:52.546313Z","caller":"traceutil/trace.go:171","msg":"trace[766855291] transaction","detail":"{read_only:false; response_revision:9096; number_of_response:1; }","duration":"103.563604ms","start":"2026-09-15T23:02:52.442719Z","end":"2026-09-15T23:02:52.546282Z","steps":["trace[766855291] 'process raft request' (duration: 62.853868ms)","trace[766855291] 'compare' (duration: 40.487929ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:03:54.364403Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"757b052826ed9e87","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-15T23:03:54.370818Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-15T23:03:54.370934Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-15T23:04:09.897336Z","caller":"traceutil/trace.go:171","msg":"trace[2097608459] transaction","detail":"{read_only:false; response_revision:9885; number_of_response:1; }","duration":"133.628391ms","start":"2026-09-15T23:04:09.763678Z","end":"2026-09-15T23:04:09.897307Z","steps":["trace[2097608459] 'process raft request' (duration: 133.352154ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:04:23.755409Z","caller":"traceutil/trace.go:171","msg":"trace[468343222] transaction","detail":"{read_only:false; response_revision:9962; number_of_response:1; }","duration":"160.829737ms","start":"2026-09-15T23:04:23.594547Z","end":"2026-09-15T23:04:23.755377Z","steps":["trace[468343222] 'process raft request' (duration: 160.385965ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:05:23.799825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7998} {"level":"info","ts":"2026-09-15T23:05:23.882985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7998,"took":"81.068777ms","hash":4104185511,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":25190400,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-15T23:05:23.883053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4104185511,"revision":7998,"compact-revision":5613} {"level":"warn","ts":"2026-09-15T23:10:06.389472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.209295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T23:10:06.389505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.218305ms","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-09-15T23:10:06.389569Z","caller":"traceutil/trace.go:171","msg":"trace[1915404826] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12414; }","duration":"109.334407ms","start":"2026-09-15T23:10:06.280212Z","end":"2026-09-15T23:10:06.389547Z","steps":["trace[1915404826] 'agreement among raft nodes before linearized reading' (duration: 109.177014ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:06.389594Z","caller":"traceutil/trace.go:171","msg":"trace[502661845] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12414; }","duration":"109.359478ms","start":"2026-09-15T23:10:06.280213Z","end":"2026-09-15T23:10:06.389573Z","steps":["trace[502661845] 'agreement among raft nodes before linearized reading' (duration: 109.189794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:10:06.389591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.534619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-09-15T23:10:06.389664Z","caller":"traceutil/trace.go:171","msg":"trace[1831865233] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12414; }","duration":"110.652322ms","start":"2026-09-15T23:10:06.278992Z","end":"2026-09-15T23:10:06.389645Z","steps":["trace[1831865233] 'agreement among raft nodes before linearized reading' (duration: 110.453987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:10:06.389795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.877577ms","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-09-15T23:10:06.389824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.23593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-15T23:10:06.389857Z","caller":"traceutil/trace.go:171","msg":"trace[957095619] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12414; }","duration":"108.295922ms","start":"2026-09-15T23:10:06.281552Z","end":"2026-09-15T23:10:06.389848Z","steps":["trace[957095619] 'agreement among raft nodes before linearized reading' (duration: 108.168549ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:06.38986Z","caller":"traceutil/trace.go:171","msg":"trace[1986290351] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12414; }","duration":"110.96071ms","start":"2026-09-15T23:10:06.278885Z","end":"2026-09-15T23:10:06.389846Z","steps":["trace[1986290351] 'agreement among raft nodes before linearized reading' (duration: 110.872368ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:06.59228Z","caller":"traceutil/trace.go:171","msg":"trace[507781157] linearizableReadLoop","detail":"{readStateIndex:12839; appliedIndex:12838; }","duration":"202.873104ms","start":"2026-09-15T23:10:06.389363Z","end":"2026-09-15T23:10:06.592237Z","steps":["trace[507781157] 'read index received' (duration: 115.205997ms)","trace[507781157] 'applied index is now lower than readState.Index' (duration: 87.664557ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:10:06.59249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.014483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T23:10:06.5925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.379993ms","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-09-15T23:10:06.592551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.022415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:10:06.592602Z","caller":"traceutil/trace.go:171","msg":"trace[1022259857] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12415; }","duration":"243.526387ms","start":"2026-09-15T23:10:06.349056Z","end":"2026-09-15T23:10:06.592583Z","steps":["trace[1022259857] 'agreement among raft nodes before linearized reading' (duration: 243.281501ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:06.592544Z","caller":"traceutil/trace.go:171","msg":"trace[1177226963] transaction","detail":"{read_only:false; response_revision:12415; number_of_response:1; }","duration":"264.394107ms","start":"2026-09-15T23:10:06.328042Z","end":"2026-09-15T23:10:06.592436Z","steps":["trace[1177226963] 'process raft request' (duration: 176.584076ms)","trace[1177226963] 'compare' (duration: 87.38609ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:10:06.592643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.140047ms","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-09-15T23:10:06.592655Z","caller":"traceutil/trace.go:171","msg":"trace[1794372090] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12415; }","duration":"245.160698ms","start":"2026-09-15T23:10:06.347468Z","end":"2026-09-15T23:10:06.592628Z","steps":["trace[1794372090] 'agreement among raft nodes before linearized reading' (duration: 244.887372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:10:06.592657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.904402ms","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-09-15T23:10:06.592703Z","caller":"traceutil/trace.go:171","msg":"trace[593020412] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12415; }","duration":"245.23214ms","start":"2026-09-15T23:10:06.347453Z","end":"2026-09-15T23:10:06.592686Z","steps":["trace[593020412] 'agreement among raft nodes before linearized reading' (duration: 244.902622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:10:06.592705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.247194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-09-15T23:10:06.59255Z","caller":"traceutil/trace.go:171","msg":"trace[767006937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12415; }","duration":"254.128916ms","start":"2026-09-15T23:10:06.338404Z","end":"2026-09-15T23:10:06.592533Z","steps":["trace[767006937] 'agreement among raft nodes before linearized reading' (duration: 253.990283ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:06.592775Z","caller":"traceutil/trace.go:171","msg":"trace[1913570497] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12415; }","duration":"242.337026ms","start":"2026-09-15T23:10:06.350418Z","end":"2026-09-15T23:10:06.592755Z","steps":["trace[1913570497] 'agreement among raft nodes before linearized reading' (duration: 242.116621ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:06.592755Z","caller":"traceutil/trace.go:171","msg":"trace[1419412580] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12415; }","duration":"245.193989ms","start":"2026-09-15T23:10:06.347544Z","end":"2026-09-15T23:10:06.592738Z","steps":["trace[1419412580] 'agreement among raft nodes before linearized reading' (duration: 244.82174ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:07.699391Z","caller":"traceutil/trace.go:171","msg":"trace[2024738020] transaction","detail":"{read_only:false; response_revision:12422; number_of_response:1; }","duration":"256.040645ms","start":"2026-09-15T23:10:07.443325Z","end":"2026-09-15T23:10:07.699365Z","steps":["trace[2024738020] 'process raft request' (duration: 255.873911ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:07.969963Z","caller":"traceutil/trace.go:171","msg":"trace[1084782700] transaction","detail":"{read_only:false; response_revision:12424; number_of_response:1; }","duration":"109.287566ms","start":"2026-09-15T23:10:07.86059Z","end":"2026-09-15T23:10:07.969877Z","steps":["trace[1084782700] 'process raft request' (duration: 108.981169ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:07.980193Z","caller":"traceutil/trace.go:171","msg":"trace[2018787272] transaction","detail":"{read_only:false; response_revision:12425; number_of_response:1; }","duration":"117.889725ms","start":"2026-09-15T23:10:07.862236Z","end":"2026-09-15T23:10:07.980126Z","steps":["trace[2018787272] 'process raft request' (duration: 117.667939ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:10:23.806449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10394} {"level":"info","ts":"2026-09-15T23:10:23.891425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10394,"took":"81.939392ms","hash":25470468,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":25743360,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-15T23:10:23.891491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":25470468,"revision":10394,"compact-revision":7998} {"level":"info","ts":"2026-09-15T23:15:23.813552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12554} {"level":"info","ts":"2026-09-15T23:15:23.897323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12554,"took":"81.41297ms","hash":2932391072,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":28090368,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T23:15:23.897403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2932391072,"revision":12554,"compact-revision":10394} {"level":"warn","ts":"2026-09-15T23:16:54.432732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.934602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T23:16:54.432898Z","caller":"traceutil/trace.go:171","msg":"trace[587575141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15123; }","duration":"365.141198ms","start":"2026-09-15T23:16:54.067729Z","end":"2026-09-15T23:16:54.432871Z","steps":["trace[587575141] 'range keys from in-memory index tree' (duration: 364.747208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:54.432956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:54.06771Z","time spent":"365.230931ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-15T23:16:54.433397Z","caller":"traceutil/trace.go:171","msg":"trace[1623282928] transaction","detail":"{read_only:false; response_revision:15124; number_of_response:1; }","duration":"261.676462ms","start":"2026-09-15T23:16:54.171676Z","end":"2026-09-15T23:16:54.433353Z","steps":["trace[1623282928] 'process raft request' (duration: 231.436165ms)","trace[1623282928] 'compare' (duration: 29.1846ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:16:54.584821Z","caller":"traceutil/trace.go:171","msg":"trace[1783137522] linearizableReadLoop","detail":"{readStateIndex:15646; appliedIndex:15644; }","duration":"359.333412ms","start":"2026-09-15T23:16:54.225457Z","end":"2026-09-15T23:16:54.584791Z","steps":["trace[1783137522] 'read index received' (duration: 177.667751ms)","trace[1783137522] 'applied index is now lower than readState.Index' (duration: 181.664471ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:16:54.58486Z","caller":"traceutil/trace.go:171","msg":"trace[1256705483] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"412.173562ms","start":"2026-09-15T23:16:54.172632Z","end":"2026-09-15T23:16:54.584806Z","steps":["trace[1256705483] 'process raft request' (duration: 379.049302ms)","trace[1256705483] 'compare' (duration: 32.933506ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:16:54.585009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:54.172598Z","time spent":"412.335117ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:16:54.585541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.569066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:16:54.5856Z","caller":"traceutil/trace.go:171","msg":"trace[1576148649] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:15126; }","duration":"353.626567ms","start":"2026-09-15T23:16:54.231955Z","end":"2026-09-15T23:16:54.585582Z","steps":["trace[1576148649] 'agreement among raft nodes before linearized reading' (duration: 353.517214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:54.585629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:54.231933Z","time spent":"353.688308ms","remote":"127.0.0.1:57680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-09-15T23:16:54.585883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.43061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-15T23:16:54.585919Z","caller":"traceutil/trace.go:171","msg":"trace[297876402] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15126; }","duration":"360.466571ms","start":"2026-09-15T23:16:54.225443Z","end":"2026-09-15T23:16:54.58591Z","steps":["trace[297876402] 'agreement among raft nodes before linearized reading' (duration: 359.507987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:54.585944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:16:54.225386Z","time spent":"360.551883ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-15T23:16:54.586197Z","caller":"traceutil/trace.go:171","msg":"trace[759969644] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"148.388827ms","start":"2026-09-15T23:16:54.437796Z","end":"2026-09-15T23:16:54.586185Z","steps":["trace[759969644] 'process raft request' (duration: 146.96191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:16:54.586433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.554439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:16:54.586468Z","caller":"traceutil/trace.go:171","msg":"trace[1709368660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15126; }","duration":"248.646701ms","start":"2026-09-15T23:16:54.337812Z","end":"2026-09-15T23:16:54.586459Z","steps":["trace[1709368660] 'agreement among raft nodes before linearized reading' (duration: 248.59811ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:04.605035Z","caller":"traceutil/trace.go:171","msg":"trace[385209273] transaction","detail":"{read_only:false; response_revision:15297; number_of_response:1; }","duration":"133.278643ms","start":"2026-09-15T23:17:04.471726Z","end":"2026-09-15T23:17:04.605005Z","steps":["trace[385209273] 'process raft request' (duration: 103.832646ms)","trace[385209273] 'compare' (duration: 29.024006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:17:05.069812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.499143ms","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-09-15T23:17:05.069943Z","caller":"traceutil/trace.go:171","msg":"trace[2022820088] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:15298; }","duration":"145.647357ms","start":"2026-09-15T23:17:04.924268Z","end":"2026-09-15T23:17:05.069916Z","steps":["trace[2022820088] 'count revisions from in-memory index tree' (duration: 145.406231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:05.070032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.960508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-dkp97.18d5a1b476761d55\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-15T23:17:05.070111Z","caller":"traceutil/trace.go:171","msg":"trace[1625355319] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-dkp97.18d5a1b476761d55; range_end:; response_count:1; response_revision:15298; }","duration":"141.067832ms","start":"2026-09-15T23:17:04.929019Z","end":"2026-09-15T23:17:05.070087Z","steps":["trace[1625355319] 'range keys from in-memory index tree' (duration: 140.822435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:05.070335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.787188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-15T23:17:05.070396Z","caller":"traceutil/trace.go:171","msg":"trace[401106020] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15298; }","duration":"256.896041ms","start":"2026-09-15T23:17:04.813486Z","end":"2026-09-15T23:17:05.070382Z","steps":["trace[401106020] 'range keys from in-memory index tree' (duration: 256.47286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:05.070431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.729637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-15T23:17:05.070488Z","caller":"traceutil/trace.go:171","msg":"trace[1618365172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15298; }","duration":"139.839159ms","start":"2026-09-15T23:17:04.930635Z","end":"2026-09-15T23:17:05.070474Z","steps":["trace[1618365172] 'range keys from in-memory index tree' (duration: 139.587244ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:05.224673Z","caller":"traceutil/trace.go:171","msg":"trace[292919923] transaction","detail":"{read_only:false; response_revision:15299; number_of_response:1; }","duration":"277.916585ms","start":"2026-09-15T23:17:04.946727Z","end":"2026-09-15T23:17:05.224644Z","steps":["trace[292919923] 'process raft request' (duration: 277.791082ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:05.224677Z","caller":"traceutil/trace.go:171","msg":"trace[756236057] linearizableReadLoop","detail":"{readStateIndex:15824; appliedIndex:15823; }","duration":"235.150408ms","start":"2026-09-15T23:17:04.989489Z","end":"2026-09-15T23:17:05.224639Z","steps":["trace[756236057] 'read index received' (duration: 235.130358ms)","trace[756236057] 'applied index is now lower than readState.Index' (duration: 17.47µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:17:05.224916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.403635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:17:05.224976Z","caller":"traceutil/trace.go:171","msg":"trace[1864731290] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:15299; }","duration":"235.526458ms","start":"2026-09-15T23:17:04.98943Z","end":"2026-09-15T23:17:05.224957Z","steps":["trace[1864731290] 'agreement among raft nodes before linearized reading' (duration: 235.369764ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:05.234604Z","caller":"traceutil/trace.go:171","msg":"trace[1142376987] transaction","detail":"{read_only:false; response_revision:15300; number_of_response:1; }","duration":"161.940781ms","start":"2026-09-15T23:17:05.072633Z","end":"2026-09-15T23:17:05.234574Z","steps":["trace[1142376987] 'process raft request' (duration: 161.701274ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:17:05.234719Z","caller":"traceutil/trace.go:171","msg":"trace[1907009224] transaction","detail":"{read_only:false; response_revision:15301; number_of_response:1; }","duration":"160.361641ms","start":"2026-09-15T23:17:05.074316Z","end":"2026-09-15T23:17:05.234678Z","steps":["trace[1907009224] 'process raft request' (duration: 160.166976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:05.234801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.160595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-129364e2-30b0-495c-b9ac-c9a2f2cd77a1\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-09-15T23:17:05.234897Z","caller":"traceutil/trace.go:171","msg":"trace[778860206] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-129364e2-30b0-495c-b9ac-c9a2f2cd77a1; range_end:; response_count:1; response_revision:15301; }","duration":"121.295308ms","start":"2026-09-15T23:17:05.11358Z","end":"2026-09-15T23:17:05.234875Z","steps":["trace[778860206] 'agreement among raft nodes before linearized reading' (duration: 121.006141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:17:05.235091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.74693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-15T23:17:05.235151Z","caller":"traceutil/trace.go:171","msg":"trace[501273445] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15301; }","duration":"160.831812ms","start":"2026-09-15T23:17:05.074302Z","end":"2026-09-15T23:17:05.235134Z","steps":["trace[501273445] 'agreement among raft nodes before linearized reading' (duration: 160.653727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:12.472704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.986049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:18:12.47281Z","caller":"traceutil/trace.go:171","msg":"trace[1909601146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16223; }","duration":"121.145603ms","start":"2026-09-15T23:18:12.351642Z","end":"2026-09-15T23:18:12.472788Z","steps":["trace[1909601146] 'range keys from in-memory index tree' (duration: 120.904827ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:18:37.746568Z","caller":"traceutil/trace.go:171","msg":"trace[1122079492] linearizableReadLoop","detail":"{readStateIndex:16913; appliedIndex:16912; }","duration":"130.479549ms","start":"2026-09-15T23:18:37.616066Z","end":"2026-09-15T23:18:37.746546Z","steps":["trace[1122079492] 'read index received' (duration: 130.165701ms)","trace[1122079492] 'applied index is now lower than readState.Index' (duration: 312.978µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:18:37.746715Z","caller":"traceutil/trace.go:171","msg":"trace[662925953] transaction","detail":"{read_only:false; response_revision:16354; number_of_response:1; }","duration":"166.986966ms","start":"2026-09-15T23:18:37.579685Z","end":"2026-09-15T23:18:37.746672Z","steps":["trace[662925953] 'process raft request' (duration: 166.685157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:37.746812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.738337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-z44qw\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-09-15T23:18:37.746885Z","caller":"traceutil/trace.go:171","msg":"trace[206310881] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-z44qw; range_end:; response_count:1; response_revision:16354; }","duration":"130.86257ms","start":"2026-09-15T23:18:37.616011Z","end":"2026-09-15T23:18:37.746873Z","steps":["trace[206310881] 'agreement among raft nodes before linearized reading' (duration: 130.660765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:37.746991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.343387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:18:37.747035Z","caller":"traceutil/trace.go:171","msg":"trace[2110867302] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16354; }","duration":"117.417588ms","start":"2026-09-15T23:18:37.629605Z","end":"2026-09-15T23:18:37.747023Z","steps":["trace[2110867302] 'agreement among raft nodes before linearized reading' (duration: 117.335146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:37.747189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.33523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-15T23:18:37.747226Z","caller":"traceutil/trace.go:171","msg":"trace[2012257281] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16354; }","duration":"114.408922ms","start":"2026-09-15T23:18:37.632808Z","end":"2026-09-15T23:18:37.747217Z","steps":["trace[2012257281] 'agreement among raft nodes before linearized reading' (duration: 114.30861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:37.747302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.85399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:18:37.747375Z","caller":"traceutil/trace.go:171","msg":"trace[1445279868] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16354; }","duration":"117.927161ms","start":"2026-09-15T23:18:37.629432Z","end":"2026-09-15T23:18:37.747359Z","steps":["trace[1445279868] 'agreement among raft nodes before linearized reading' (duration: 117.829319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:37.747657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.889562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T23:18:37.747733Z","caller":"traceutil/trace.go:171","msg":"trace[693899017] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16354; }","duration":"118.841675ms","start":"2026-09-15T23:18:37.628872Z","end":"2026-09-15T23:18:37.747713Z","steps":["trace[693899017] 'agreement among raft nodes before linearized reading' (duration: 117.86793ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:18:38.041689Z","caller":"traceutil/trace.go:171","msg":"trace[815596159] transaction","detail":"{read_only:false; response_revision:16356; number_of_response:1; }","duration":"286.302161ms","start":"2026-09-15T23:18:37.755356Z","end":"2026-09-15T23:18:38.041659Z","steps":["trace[815596159] 'process raft request' (duration: 286.230629ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:18:38.041979Z","caller":"traceutil/trace.go:171","msg":"trace[335271702] transaction","detail":"{read_only:false; response_revision:16355; number_of_response:1; }","duration":"287.556883ms","start":"2026-09-15T23:18:37.75441Z","end":"2026-09-15T23:18:38.041967Z","steps":["trace[335271702] 'process raft request' (duration: 195.024537ms)","trace[335271702] 'compare' (duration: 91.89023ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:18:38.042051Z","caller":"traceutil/trace.go:171","msg":"trace[1853860000] linearizableReadLoop","detail":"{readStateIndex:16914; appliedIndex:16913; }","duration":"286.885116ms","start":"2026-09-15T23:18:37.755148Z","end":"2026-09-15T23:18:38.042033Z","steps":["trace[1853860000] 'read index received' (duration: 194.269007ms)","trace[1853860000] 'applied index is now lower than readState.Index' (duration: 92.614299ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:18:38.042816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.656306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764247"} {"level":"info","ts":"2026-09-15T23:18:38.042883Z","caller":"traceutil/trace.go:171","msg":"trace[1318551040] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16356; }","duration":"287.739958ms","start":"2026-09-15T23:18:37.755133Z","end":"2026-09-15T23:18:38.042873Z","steps":["trace[1318551040] 'agreement among raft nodes before linearized reading' (duration: 286.981259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:38.107352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.364397ms","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-09-15T23:18:38.107361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.347348ms","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-09-15T23:18:38.107428Z","caller":"traceutil/trace.go:171","msg":"trace[1706255882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16357; }","duration":"266.49182ms","start":"2026-09-15T23:18:37.840918Z","end":"2026-09-15T23:18:38.107409Z","steps":["trace[1706255882] 'agreement among raft nodes before linearized reading' (duration: 266.289926ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:18:38.10746Z","caller":"traceutil/trace.go:171","msg":"trace[1860319197] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16357; }","duration":"259.469622ms","start":"2026-09-15T23:18:37.847962Z","end":"2026-09-15T23:18:38.107431Z","steps":["trace[1860319197] 'agreement among raft nodes before linearized reading' (duration: 259.315038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:18:38.107601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.702579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-15T23:18:38.107633Z","caller":"traceutil/trace.go:171","msg":"trace[1738448936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16357; }","duration":"265.753181ms","start":"2026-09-15T23:18:37.841872Z","end":"2026-09-15T23:18:38.107625Z","steps":["trace[1738448936] 'agreement among raft nodes before linearized reading' (duration: 265.667019ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:20:23.820697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14564} {"level":"info","ts":"2026-09-15T23:20:23.902188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14564,"took":"78.577223ms","hash":991541338,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-15T23:20:23.902278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":991541338,"revision":14564,"compact-revision":12554} {"level":"info","ts":"2026-09-15T23:24:20.312088Z","caller":"traceutil/trace.go:171","msg":"trace[994338976] transaction","detail":"{read_only:false; response_revision:18839; number_of_response:1; }","duration":"163.177629ms","start":"2026-09-15T23:24:20.148866Z","end":"2026-09-15T23:24:20.312044Z","steps":["trace[994338976] 'process raft request' (duration: 151.882123ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:20.312704Z","caller":"traceutil/trace.go:171","msg":"trace[1449783263] transaction","detail":"{read_only:false; response_revision:18841; number_of_response:1; }","duration":"103.350071ms","start":"2026-09-15T23:24:20.209324Z","end":"2026-09-15T23:24:20.312674Z","steps":["trace[1449783263] 'process raft request' (duration: 103.292139ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:20.312724Z","caller":"traceutil/trace.go:171","msg":"trace[1761376893] transaction","detail":"{read_only:false; response_revision:18840; number_of_response:1; }","duration":"159.115235ms","start":"2026-09-15T23:24:20.153588Z","end":"2026-09-15T23:24:20.312703Z","steps":["trace[1761376893] 'process raft request' (duration: 158.928901ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:20.313096Z","caller":"traceutil/trace.go:171","msg":"trace[436436710] linearizableReadLoop","detail":"{readStateIndex:19482; appliedIndex:19479; }","duration":"136.638285ms","start":"2026-09-15T23:24:20.176441Z","end":"2026-09-15T23:24:20.313079Z","steps":["trace[436436710] 'read index received' (duration: 28.650756ms)","trace[436436710] 'applied index is now lower than readState.Index' (duration: 107.985889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:24:20.313194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.726928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:24:20.31324Z","caller":"traceutil/trace.go:171","msg":"trace[292028296] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:18841; }","duration":"136.880142ms","start":"2026-09-15T23:24:20.176341Z","end":"2026-09-15T23:24:20.313221Z","steps":["trace[292028296] 'agreement among raft nodes before linearized reading' (duration: 136.79253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:26.720275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.831283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-15T23:24:26.720828Z","caller":"traceutil/trace.go:171","msg":"trace[167776966] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:18893; }","duration":"130.359385ms","start":"2026-09-15T23:24:26.590322Z","end":"2026-09-15T23:24:26.720682Z","steps":["trace[167776966] 'count revisions from in-memory index tree' (duration: 129.70375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.707017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11423275620026642852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T23:24:27.74391Z","caller":"traceutil/trace.go:171","msg":"trace[637842064] transaction","detail":"{read_only:false; response_revision:18895; number_of_response:1; }","duration":"585.831228ms","start":"2026-09-15T23:24:27.158031Z","end":"2026-09-15T23:24:27.743863Z","steps":["trace[637842064] 'process raft request' (duration: 585.590912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.744216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.158004Z","time spent":"586.034593ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:24:27.754697Z","caller":"traceutil/trace.go:171","msg":"trace[551284951] transaction","detail":"{read_only:false; response_revision:18896; number_of_response:1; }","duration":"579.177361ms","start":"2026-09-15T23:24:27.175488Z","end":"2026-09-15T23:24:27.754665Z","steps":["trace[551284951] 'process raft request' (duration: 578.893773ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:27.754748Z","caller":"traceutil/trace.go:171","msg":"trace[1410403680] linearizableReadLoop","detail":"{readStateIndex:19543; appliedIndex:19539; }","duration":"547.913677ms","start":"2026-09-15T23:24:27.20681Z","end":"2026-09-15T23:24:27.754724Z","steps":["trace[1410403680] 'read index received' (duration: 537.660117ms)","trace[1410403680] 'applied index is now lower than readState.Index' (duration: 10.25168ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:24:27.754775Z","caller":"traceutil/trace.go:171","msg":"trace[1111781641] transaction","detail":"{read_only:false; response_revision:18897; number_of_response:1; }","duration":"405.720741ms","start":"2026-09-15T23:24:27.348997Z","end":"2026-09-15T23:24:27.754718Z","steps":["trace[1111781641] 'process raft request' (duration: 405.531106ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:27.754868Z","caller":"traceutil/trace.go:171","msg":"trace[1288021977] transaction","detail":"{read_only:false; response_revision:18899; number_of_response:1; }","duration":"226.163826ms","start":"2026-09-15T23:24:27.528689Z","end":"2026-09-15T23:24:27.754853Z","steps":["trace[1288021977] 'process raft request' (duration: 225.976152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.754859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.175459Z","time spent":"579.312544ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:24:27.755119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.31995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-w7pd6\" ","response":"range_response_count:1 size:20661"} {"level":"warn","ts":"2026-09-15T23:24:27.75513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.348979Z","time spent":"405.913456ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:24:27.755213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.403759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1985"} {"level":"info","ts":"2026-09-15T23:24:27.75524Z","caller":"traceutil/trace.go:171","msg":"trace[1027760809] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:18899; }","duration":"548.46606ms","start":"2026-09-15T23:24:27.206763Z","end":"2026-09-15T23:24:27.755229Z","steps":["trace[1027760809] 'agreement among raft nodes before linearized reading' (duration: 548.414259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.755262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.20672Z","time spent":"548.537042ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2007,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-09-15T23:24:27.755505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.644963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-09-15T23:24:27.755557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.413488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-09-15T23:24:27.75555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.154965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-09-15T23:24:27.755586Z","caller":"traceutil/trace.go:171","msg":"trace[577820581] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18899; }","duration":"225.47345ms","start":"2026-09-15T23:24:27.530105Z","end":"2026-09-15T23:24:27.755579Z","steps":["trace[577820581] 'agreement among raft nodes before linearized reading' (duration: 225.383048ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:27.755586Z","caller":"traceutil/trace.go:171","msg":"trace[976942805] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18899; }","duration":"221.750966ms","start":"2026-09-15T23:24:27.533816Z","end":"2026-09-15T23:24:27.755567Z","steps":["trace[976942805] 'agreement among raft nodes before linearized reading' (duration: 221.51937ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:27.755606Z","caller":"traceutil/trace.go:171","msg":"trace[529475692] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18899; }","duration":"181.259337ms","start":"2026-09-15T23:24:27.574332Z","end":"2026-09-15T23:24:27.755591Z","steps":["trace[529475692] 'agreement among raft nodes before linearized reading' (duration: 181.006291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.75573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.296031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:24:27.755764Z","caller":"traceutil/trace.go:171","msg":"trace[1500730866] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:18899; }","duration":"409.332822ms","start":"2026-09-15T23:24:27.346421Z","end":"2026-09-15T23:24:27.755754Z","steps":["trace[1500730866] 'agreement among raft nodes before linearized reading' (duration: 409.277941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.755788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.346407Z","time spent":"409.373594ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-09-15T23:24:27.755962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.848638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:24:27.755988Z","caller":"traceutil/trace.go:171","msg":"trace[1399905231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18899; }","duration":"417.9159ms","start":"2026-09-15T23:24:27.338064Z","end":"2026-09-15T23:24:27.75598Z","steps":["trace[1399905231] 'agreement among raft nodes before linearized reading' (duration: 417.838838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.756007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.338053Z","time spent":"417.94922ms","remote":"127.0.0.1:58050","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-09-15T23:24:27.756123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.511319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:24:27.756189Z","caller":"traceutil/trace.go:171","msg":"trace[1953655932] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:18899; }","duration":"426.60809ms","start":"2026-09-15T23:24:27.329566Z","end":"2026-09-15T23:24:27.756174Z","steps":["trace[1953655932] 'agreement among raft nodes before linearized reading' (duration: 426.496598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.756233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.329553Z","time spent":"426.666383ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-15T23:24:27.756422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.285074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:24:27.756455Z","caller":"traceutil/trace.go:171","msg":"trace[1880824622] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:18899; }","duration":"210.339906ms","start":"2026-09-15T23:24:27.546104Z","end":"2026-09-15T23:24:27.756444Z","steps":["trace[1880824622] 'agreement among raft nodes before linearized reading' (duration: 210.285235ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:27.755163Z","caller":"traceutil/trace.go:171","msg":"trace[1147537178] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-w7pd6; range_end:; response_count:1; response_revision:18899; }","duration":"457.402562ms","start":"2026-09-15T23:24:27.29775Z","end":"2026-09-15T23:24:27.755153Z","steps":["trace[1147537178] 'agreement among raft nodes before linearized reading' (duration: 457.143025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.756651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.29773Z","time spent":"458.901171ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20683,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-w7pd6\" "} {"level":"info","ts":"2026-09-15T23:24:27.754822Z","caller":"traceutil/trace.go:171","msg":"trace[739128992] transaction","detail":"{read_only:false; response_revision:18898; number_of_response:1; }","duration":"403.159576ms","start":"2026-09-15T23:24:27.351575Z","end":"2026-09-15T23:24:27.754734Z","steps":["trace[739128992] 'process raft request' (duration: 403.025623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:27.756747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:24:27.351562Z","time spent":"405.139976ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:24:36.039709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.244539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-15T23:24:36.039836Z","caller":"traceutil/trace.go:171","msg":"trace[1103018967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18992; }","duration":"103.630738ms","start":"2026-09-15T23:24:35.93617Z","end":"2026-09-15T23:24:36.0398Z","steps":["trace[1103018967] 'agreement among raft nodes before linearized reading' (duration: 99.193596ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:36.377086Z","caller":"traceutil/trace.go:171","msg":"trace[1971091732] linearizableReadLoop","detail":"{readStateIndex:19661; appliedIndex:19659; }","duration":"124.825377ms","start":"2026-09-15T23:24:36.252229Z","end":"2026-09-15T23:24:36.377054Z","steps":["trace[1971091732] 'read index received' (duration: 6.754192ms)","trace[1971091732] 'applied index is now lower than readState.Index' (duration: 118.070025ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:24:36.377221Z","caller":"traceutil/trace.go:171","msg":"trace[162591877] transaction","detail":"{read_only:false; response_revision:19013; number_of_response:1; }","duration":"125.153825ms","start":"2026-09-15T23:24:36.252054Z","end":"2026-09-15T23:24:36.377208Z","steps":["trace[162591877] 'process raft request' (duration: 124.643971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:36.377715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.457532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-15T23:24:36.377756Z","caller":"traceutil/trace.go:171","msg":"trace[1406032980] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19015; }","duration":"125.539504ms","start":"2026-09-15T23:24:36.252206Z","end":"2026-09-15T23:24:36.377745Z","steps":["trace[1406032980] 'agreement among raft nodes before linearized reading' (duration: 125.248717ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:36.377848Z","caller":"traceutil/trace.go:171","msg":"trace[1378259429] transaction","detail":"{read_only:false; response_revision:19014; number_of_response:1; }","duration":"123.987764ms","start":"2026-09-15T23:24:36.253824Z","end":"2026-09-15T23:24:36.377811Z","steps":["trace[1378259429] 'process raft request' (duration: 123.064681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:36.378339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.022656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-15T23:24:36.378102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.746094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-09-15T23:24:36.378531Z","caller":"traceutil/trace.go:171","msg":"trace[855394971] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:19015; }","duration":"108.221405ms","start":"2026-09-15T23:24:36.270292Z","end":"2026-09-15T23:24:36.378514Z","steps":["trace[855394971] 'agreement among raft nodes before linearized reading' (duration: 107.724173ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:36.378638Z","caller":"traceutil/trace.go:171","msg":"trace[154208818] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:19015; }","duration":"126.320413ms","start":"2026-09-15T23:24:36.252159Z","end":"2026-09-15T23:24:36.378479Z","steps":["trace[154208818] 'agreement among raft nodes before linearized reading' (duration: 125.817501ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:36.379174Z","caller":"traceutil/trace.go:171","msg":"trace[645085081] transaction","detail":"{read_only:false; response_revision:19015; number_of_response:1; }","duration":"125.010491ms","start":"2026-09-15T23:24:36.254137Z","end":"2026-09-15T23:24:36.379147Z","steps":["trace[645085081] 'process raft request' (duration: 122.859846ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:24:36.520757Z","caller":"traceutil/trace.go:171","msg":"trace[626196847] linearizableReadLoop","detail":"{readStateIndex:19664; appliedIndex:19663; }","duration":"118.615828ms","start":"2026-09-15T23:24:36.402118Z","end":"2026-09-15T23:24:36.520734Z","steps":["trace[626196847] 'read index received' (duration: 118.570597ms)","trace[626196847] 'applied index is now lower than readState.Index' (duration: 43.821µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:24:36.520864Z","caller":"traceutil/trace.go:171","msg":"trace[1157216086] transaction","detail":"{read_only:false; response_revision:19016; number_of_response:1; }","duration":"119.121762ms","start":"2026-09-15T23:24:36.40173Z","end":"2026-09-15T23:24:36.520852Z","steps":["trace[1157216086] 'process raft request' (duration: 118.868295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:36.521264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.106862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-09-15T23:24:36.521403Z","caller":"traceutil/trace.go:171","msg":"trace[1337337183] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:19016; }","duration":"119.280846ms","start":"2026-09-15T23:24:36.402092Z","end":"2026-09-15T23:24:36.521373Z","steps":["trace[1337337183] 'agreement among raft nodes before linearized reading' (duration: 118.832415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:24:36.525207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.627919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:24:36.525283Z","caller":"traceutil/trace.go:171","msg":"trace[1210574792] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:19017; }","duration":"120.699851ms","start":"2026-09-15T23:24:36.404561Z","end":"2026-09-15T23:24:36.525261Z","steps":["trace[1210574792] 'agreement among raft nodes before linearized reading' (duration: 120.569588ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:25:12.442852Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"757b052826ed9e87","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-15T23:25:12.448158Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-15T23:25:12.448237Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-15T23:25:23.827811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17032} {"level":"info","ts":"2026-09-15T23:25:23.926354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17032,"took":"94.99821ms","hash":1599774201,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":33931264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-15T23:25:23.926437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1599774201,"revision":17032,"compact-revision":14564} {"level":"info","ts":"2026-09-15T23:30:23.837144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19580} {"level":"info","ts":"2026-09-15T23:30:23.95622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19580,"took":"115.462177ms","hash":336918646,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-15T23:30:23.956277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":336918646,"revision":19580,"compact-revision":17032} {"level":"warn","ts":"2026-09-15T23:30:30.09874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.89105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-15T23:30:30.098846Z","caller":"traceutil/trace.go:171","msg":"trace[93191760] linearizableReadLoop","detail":"{readStateIndex:22429; appliedIndex:22428; }","duration":"179.534143ms","start":"2026-09-15T23:30:29.919291Z","end":"2026-09-15T23:30:30.098825Z","steps":["trace[93191760] 'read index received' (duration: 46.396157ms)","trace[93191760] 'applied index is now lower than readState.Index' (duration: 133.136846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:30:30.098993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.696286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:30:30.099023Z","caller":"traceutil/trace.go:171","msg":"trace[1920614470] transaction","detail":"{read_only:false; response_revision:21694; number_of_response:1; }","duration":"185.275667ms","start":"2026-09-15T23:30:29.9137Z","end":"2026-09-15T23:30:30.098976Z","steps":["trace[1920614470] 'process raft request' (duration: 52.06958ms)","trace[1920614470] 'compare' (duration: 132.767876ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:30:30.099075Z","caller":"traceutil/trace.go:171","msg":"trace[846734375] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21694; }","duration":"179.822519ms","start":"2026-09-15T23:30:29.919238Z","end":"2026-09-15T23:30:30.09906Z","steps":["trace[846734375] 'agreement among raft nodes before linearized reading' (duration: 179.675136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:30.099276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.908405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-15T23:30:30.09932Z","caller":"traceutil/trace.go:171","msg":"trace[1095423403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21694; }","duration":"107.985926ms","start":"2026-09-15T23:30:29.991321Z","end":"2026-09-15T23:30:30.099307Z","steps":["trace[1095423403] 'agreement among raft nodes before linearized reading' (duration: 107.851103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:30.099405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.769862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-15T23:30:30.09951Z","caller":"traceutil/trace.go:171","msg":"trace[1801776694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21694; }","duration":"150.924486ms","start":"2026-09-15T23:30:29.948558Z","end":"2026-09-15T23:30:30.099483Z","steps":["trace[1801776694] 'agreement among raft nodes before linearized reading' (duration: 150.482245ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:30.316695Z","caller":"traceutil/trace.go:171","msg":"trace[201940877] transaction","detail":"{read_only:false; response_revision:21695; number_of_response:1; }","duration":"212.104947ms","start":"2026-09-15T23:30:30.104555Z","end":"2026-09-15T23:30:30.31666Z","steps":["trace[201940877] 'process raft request' (duration: 133.818002ms)","trace[201940877] 'compare' (duration: 78.156942ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:30:30.341648Z","caller":"traceutil/trace.go:171","msg":"trace[1721347176] transaction","detail":"{read_only:false; response_revision:21696; number_of_response:1; }","duration":"237.04057ms","start":"2026-09-15T23:30:30.104581Z","end":"2026-09-15T23:30:30.341621Z","steps":["trace[1721347176] 'process raft request' (duration: 236.895457ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:30.341839Z","caller":"traceutil/trace.go:171","msg":"trace[1273342181] transaction","detail":"{read_only:false; response_revision:21697; number_of_response:1; }","duration":"236.498496ms","start":"2026-09-15T23:30:30.10531Z","end":"2026-09-15T23:30:30.341808Z","steps":["trace[1273342181] 'process raft request' (duration: 236.27632ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:30.341889Z","caller":"traceutil/trace.go:171","msg":"trace[2103924188] linearizableReadLoop","detail":"{readStateIndex:22432; appliedIndex:22429; }","duration":"233.581021ms","start":"2026-09-15T23:30:30.108284Z","end":"2026-09-15T23:30:30.341865Z","steps":["trace[2103924188] 'read index received' (duration: 130.005955ms)","trace[2103924188] 'applied index is now lower than readState.Index' (duration: 103.573286ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:30:30.341916Z","caller":"traceutil/trace.go:171","msg":"trace[1493303801] transaction","detail":"{read_only:false; response_revision:21698; number_of_response:1; }","duration":"133.795102ms","start":"2026-09-15T23:30:30.208109Z","end":"2026-09-15T23:30:30.341904Z","steps":["trace[1493303801] 'process raft request' (duration: 133.652989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:30.34216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.852149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-15T23:30:30.342238Z","caller":"traceutil/trace.go:171","msg":"trace[1432815203] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21698; }","duration":"233.960381ms","start":"2026-09-15T23:30:30.108259Z","end":"2026-09-15T23:30:30.342219Z","steps":["trace[1432815203] 'agreement among raft nodes before linearized reading' (duration: 233.681454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:30.342167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.987112ms","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-09-15T23:30:30.342334Z","caller":"traceutil/trace.go:171","msg":"trace[520136285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21698; }","duration":"180.185858ms","start":"2026-09-15T23:30:30.162132Z","end":"2026-09-15T23:30:30.342318Z","steps":["trace[520136285] 'agreement among raft nodes before linearized reading' (duration: 179.89451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:30.342183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.452866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-09-15T23:30:30.342437Z","caller":"traceutil/trace.go:171","msg":"trace[1284686621] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21698; }","duration":"100.756385ms","start":"2026-09-15T23:30:30.241669Z","end":"2026-09-15T23:30:30.342425Z","steps":["trace[1284686621] 'agreement among raft nodes before linearized reading' (duration: 100.373225ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:46.209901Z","caller":"traceutil/trace.go:171","msg":"trace[1782035262] transaction","detail":"{read_only:false; response_revision:21777; number_of_response:1; }","duration":"257.964ms","start":"2026-09-15T23:30:45.951886Z","end":"2026-09-15T23:30:46.20985Z","steps":["trace[1782035262] 'process raft request' (duration: 257.681783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:46.4886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.781872ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:30:46.488757Z","caller":"traceutil/trace.go:171","msg":"trace[265889299] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21777; }","duration":"146.967947ms","start":"2026-09-15T23:30:46.341762Z","end":"2026-09-15T23:30:46.48873Z","steps":["trace[265889299] 'range keys from in-memory index tree' (duration: 146.763431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:46.488941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.389317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-15T23:30:46.489054Z","caller":"traceutil/trace.go:171","msg":"trace[1634484327] linearizableReadLoop","detail":"{readStateIndex:22517; appliedIndex:22516; }","duration":"150.369692ms","start":"2026-09-15T23:30:46.338665Z","end":"2026-09-15T23:30:46.489035Z","steps":["trace[1634484327] 'read index received' (duration: 73.982µs)","trace[1634484327] 'applied index is now lower than readState.Index' (duration: 150.29407ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:30:46.489197Z","caller":"traceutil/trace.go:171","msg":"trace[1023747825] transaction","detail":"{read_only:false; response_revision:21778; number_of_response:1; }","duration":"298.22544ms","start":"2026-09-15T23:30:46.19093Z","end":"2026-09-15T23:30:46.489156Z","steps":["trace[1023747825] 'process raft request' (duration: 135.501465ms)","trace[1023747825] 'compare' (duration: 162.057678ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:30:46.489255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.599048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-15T23:30:46.489313Z","caller":"traceutil/trace.go:171","msg":"trace[1718863706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21778; }","duration":"150.69924ms","start":"2026-09-15T23:30:46.338604Z","end":"2026-09-15T23:30:46.489304Z","steps":["trace[1718863706] 'agreement among raft nodes before linearized reading' (duration: 150.619728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:46.816162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.962534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-15T23:30:46.816417Z","caller":"traceutil/trace.go:171","msg":"trace[1236011047] transaction","detail":"{read_only:false; response_revision:21779; number_of_response:1; }","duration":"311.462936ms","start":"2026-09-15T23:30:46.504906Z","end":"2026-09-15T23:30:46.816369Z","steps":["trace[1236011047] 'process raft request' (duration: 170.217435ms)","trace[1236011047] 'compare' (duration: 140.814611ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:30:46.816583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:30:46.504872Z","time spent":"311.625351ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:30:46.816581Z","caller":"traceutil/trace.go:171","msg":"trace[1795762301] transaction","detail":"{read_only:false; response_revision:21780; number_of_response:1; }","duration":"311.619831ms","start":"2026-09-15T23:30:46.504939Z","end":"2026-09-15T23:30:46.816559Z","steps":["trace[1795762301] 'process raft request' (duration: 311.489588ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:46.816722Z","caller":"traceutil/trace.go:171","msg":"trace[1234173980] linearizableReadLoop","detail":"{readStateIndex:22521; appliedIndex:22517; }","duration":"236.019854ms","start":"2026-09-15T23:30:46.580683Z","end":"2026-09-15T23:30:46.816703Z","steps":["trace[1234173980] 'read index received' (duration: 94.460585ms)","trace[1234173980] 'applied index is now lower than readState.Index' (duration: 141.558609ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:30:46.816715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:30:46.50492Z","time spent":"311.757715ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:30:46.816768Z","caller":"traceutil/trace.go:171","msg":"trace[1614639677] transaction","detail":"{read_only:false; response_revision:21781; number_of_response:1; }","duration":"310.955713ms","start":"2026-09-15T23:30:46.5058Z","end":"2026-09-15T23:30:46.816755Z","steps":["trace[1614639677] 'process raft request' (duration: 310.695927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:46.816843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:30:46.505791Z","time spent":"311.009016ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:30:46.816882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.188348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-15T23:30:46.816907Z","caller":"traceutil/trace.go:171","msg":"trace[745761528] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:21782; }","duration":"236.26344ms","start":"2026-09-15T23:30:46.580637Z","end":"2026-09-15T23:30:46.8169Z","steps":["trace[745761528] 'agreement among raft nodes before linearized reading' (duration: 236.130497ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:30:46.816931Z","caller":"traceutil/trace.go:171","msg":"trace[1808763103] transaction","detail":"{read_only:false; response_revision:21782; number_of_response:1; }","duration":"309.356123ms","start":"2026-09-15T23:30:46.50755Z","end":"2026-09-15T23:30:46.816906Z","steps":["trace[1808763103] 'process raft request' (duration: 309.102877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T23:30:46.817086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:30:46.507537Z","time spent":"309.486546ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T23:30:47.339419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.344256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-15T23:30:47.339523Z","caller":"traceutil/trace.go:171","msg":"trace[2113968921] linearizableReadLoop","detail":"{readStateIndex:22522; appliedIndex:22521; }","duration":"174.135576ms","start":"2026-09-15T23:30:47.16537Z","end":"2026-09-15T23:30:47.339506Z","steps":["trace[2113968921] 'read index received' (duration: 52.132µs)","trace[2113968921] 'applied index is now lower than readState.Index' (duration: 174.081984ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T23:30:47.339614Z","caller":"traceutil/trace.go:171","msg":"trace[1166803112] transaction","detail":"{read_only:false; response_revision:21783; number_of_response:1; }","duration":"629.137221ms","start":"2026-09-15T23:30:46.710447Z","end":"2026-09-15T23:30:47.339584Z","steps":["trace[1166803112] 'process raft request' (duration: 210.541908ms)","trace[1166803112] 'compare' (duration: 418.057039ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T23:30:47.339702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.33094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.174\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-09-15T23:30:47.339739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T23:30:46.71042Z","time spent":"629.259624ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T23:30:47.339762Z","caller":"traceutil/trace.go:171","msg":"trace[1603915101] range","detail":"{range_begin:/registry/masterleases/162.253.55.174; range_end:; response_count:1; response_revision:21783; }","duration":"174.434043ms","start":"2026-09-15T23:30:47.165313Z","end":"2026-09-15T23:30:47.339747Z","steps":["trace[1603915101] 'agreement among raft nodes before linearized reading' (duration: 174.256788ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:34:48.998573Z","caller":"traceutil/trace.go:171","msg":"trace[1248806629] transaction","detail":"{read_only:false; response_revision:24248; number_of_response:1; }","duration":"102.453059ms","start":"2026-09-15T23:34:48.896089Z","end":"2026-09-15T23:34:48.998542Z","steps":["trace[1248806629] 'process raft request' (duration: 102.212003ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:34:49.31426Z","caller":"traceutil/trace.go:171","msg":"trace[488225259] transaction","detail":"{read_only:false; response_revision:24249; number_of_response:1; }","duration":"270.797572ms","start":"2026-09-15T23:34:49.043413Z","end":"2026-09-15T23:34:49.314211Z","steps":["trace[488225259] 'process raft request' (duration: 270.461314ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T23:35:23.844535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21660} {"level":"info","ts":"2026-09-15T23:35:23.953118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21660,"took":"104.877419ms","hash":2119217784,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":42741760,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-15T23:35:23.953184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119217784,"revision":21660,"compact-revision":19580} {"level":"info","ts":"2026-09-15T23:39:51.441529Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c26.wal"} {"level":"info","ts":"2026-09-15T23:40:23.851638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24625} {"level":"info","ts":"2026-09-15T23:40:24.011769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24625,"took":"153.338414ms","hash":1865151544,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":32493568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-15T23:40:24.011865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1865151544,"revision":24625,"compact-revision":21660} {"level":"info","ts":"2026-09-15T23:44:55.529879Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"757b052826ed9e87","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-15T23:44:55.536423Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-15T23:44:55.536527Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-15T23:45:23.859419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27072} {"level":"info","ts":"2026-09-15T23:45:23.98274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27072,"took":"120.170472ms","hash":52226872,"current-db-size-bytes":55250944,"current-db-size":"55 MB","current-db-size-in-use-bytes":30765056,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-15T23:45:23.982805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":52226872,"revision":27072,"compact-revision":24625}