{"level":"warn","ts":"2026-04-16T22:39:11.364104Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:39:11.364198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T22:39:11.364276Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:39:11.364306Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-16T22:39:11.364337Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:39:11.364971Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-16T22:39:11.365057Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T22:39:11.368686Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.379728ms"} {"level":"info","ts":"2026-04-16T22:39:11.376008Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-16T22:39:11.376066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:39:11.376099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-16T22:39:11.376111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:39:11.376118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-16T22:39:11.376163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-16T22:39:11.380511Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:39:11.383676Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:39:11.385947Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T22:39:11.387878Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:39:11.388071Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:39:11.388147Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:39:11.388199Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:39:11.387995Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:39:11.389541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-16T22:39:11.389834Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-16T22:39:11.392104Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:39:11.392214Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-16T22:39:11.392258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-16T22:39:11.393114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:39:11.393033Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:39:12.177271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:39:12.177329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:39:12.177364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-16T22:39:12.17739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:39:12.177593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-16T22:39:12.177658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-16T22:39:12.1777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-16T22:39:12.17868Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:39:12.178813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:39:12.17892Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:39:12.178974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:39:12.179478Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:39:12.179726Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:39:12.179815Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:39:12.179771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:39:12.179851Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:39:12.181364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-04-16T22:39:12.182744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-16T22:39:34.06012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.727658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:39:34.06025Z","caller":"traceutil/trace.go:171","msg":"trace[1962358599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"126.91464ms","start":"2026-04-16T22:39:33.933315Z","end":"2026-04-16T22:39:34.06023Z","steps":["trace[1962358599] 'range keys from in-memory index tree' (duration: 126.621227ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:34.188754Z","caller":"traceutil/trace.go:171","msg":"trace[1070211977] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"124.973602ms","start":"2026-04-16T22:39:34.063756Z","end":"2026-04-16T22:39:34.18873Z","steps":["trace[1070211977] 'process raft request' (duration: 124.813971ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:34.189478Z","caller":"traceutil/trace.go:171","msg":"trace[1303525715] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"100.015861ms","start":"2026-04-16T22:39:34.089437Z","end":"2026-04-16T22:39:34.189453Z","steps":["trace[1303525715] 'process raft request' (duration: 99.92156ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:35.065784Z","caller":"traceutil/trace.go:171","msg":"trace[931641529] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"180.749238ms","start":"2026-04-16T22:39:34.88501Z","end":"2026-04-16T22:39:35.065759Z","steps":["trace[931641529] 'process raft request' (duration: 179.117347ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:35.06591Z","caller":"traceutil/trace.go:171","msg":"trace[1079920251] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"180.817759ms","start":"2026-04-16T22:39:34.885085Z","end":"2026-04-16T22:39:35.065902Z","steps":["trace[1079920251] 'process raft request' (duration: 180.604127ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:35.066026Z","caller":"traceutil/trace.go:171","msg":"trace[78125432] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"180.791209ms","start":"2026-04-16T22:39:34.885182Z","end":"2026-04-16T22:39:35.065973Z","steps":["trace[78125432] 'process raft request' (duration: 180.604387ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:39.469402Z","caller":"traceutil/trace.go:171","msg":"trace[294755505] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"144.967405ms","start":"2026-04-16T22:39:39.324417Z","end":"2026-04-16T22:39:39.469384Z","steps":["trace[294755505] 'read index received' (duration: 144.404769ms)","trace[294755505] 'applied index is now lower than readState.Index' (duration: 562.136µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:39:39.469475Z","caller":"traceutil/trace.go:171","msg":"trace[1756773905] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"244.461176ms","start":"2026-04-16T22:39:39.224996Z","end":"2026-04-16T22:39:39.469457Z","steps":["trace[1756773905] 'process raft request' (duration: 244.033994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:39:39.469495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.057018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T22:39:39.469587Z","caller":"traceutil/trace.go:171","msg":"trace[1187440719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"145.202102ms","start":"2026-04-16T22:39:39.324377Z","end":"2026-04-16T22:39:39.469579Z","steps":["trace[1187440719] 'agreement among raft nodes before linearized reading' (duration: 145.070568ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:41.196733Z","caller":"traceutil/trace.go:171","msg":"trace[1906519402] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"144.186772ms","start":"2026-04-16T22:39:41.052464Z","end":"2026-04-16T22:39:41.196651Z","steps":["trace[1906519402] 'process raft request' (duration: 144.032867ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:41.390854Z","caller":"traceutil/trace.go:171","msg":"trace[1004612246] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"189.73383ms","start":"2026-04-16T22:39:41.201091Z","end":"2026-04-16T22:39:41.390825Z","steps":["trace[1004612246] 'process raft request' (duration: 183.027088ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:41.390873Z","caller":"traceutil/trace.go:171","msg":"trace[1012710075] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"187.521948ms","start":"2026-04-16T22:39:41.20333Z","end":"2026-04-16T22:39:41.390852Z","steps":["trace[1012710075] 'process raft request' (duration: 187.474297ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:41.391419Z","caller":"traceutil/trace.go:171","msg":"trace[1729709166] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"189.014051ms","start":"2026-04-16T22:39:41.202394Z","end":"2026-04-16T22:39:41.391408Z","steps":["trace[1729709166] 'process raft request' (duration: 188.355461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:39:41.879581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.016615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:39:41.879641Z","caller":"traceutil/trace.go:171","msg":"trace[1125224314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"155.116228ms","start":"2026-04-16T22:39:41.724511Z","end":"2026-04-16T22:39:41.879628Z","steps":["trace[1125224314] 'range keys from in-memory index tree' (duration: 154.943102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:39:41.879826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.261017ms","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-04-16T22:39:41.879852Z","caller":"traceutil/trace.go:171","msg":"trace[1085230233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"205.308399ms","start":"2026-04-16T22:39:41.674538Z","end":"2026-04-16T22:39:41.879846Z","steps":["trace[1085230233] 'range keys from in-memory index tree' (duration: 205.177285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:39:41.879988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.203725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T22:39:41.880043Z","caller":"traceutil/trace.go:171","msg":"trace[121273026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"103.333069ms","start":"2026-04-16T22:39:41.776696Z","end":"2026-04-16T22:39:41.880029Z","steps":["trace[121273026] 'range keys from in-memory index tree' (duration: 103.040131ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:41.999119Z","caller":"traceutil/trace.go:171","msg":"trace[1398127930] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"107.93665ms","start":"2026-04-16T22:39:41.891162Z","end":"2026-04-16T22:39:41.999099Z","steps":["trace[1398127930] 'process raft request' (duration: 107.878008ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:39:41.999196Z","caller":"traceutil/trace.go:171","msg":"trace[1868263837] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"114.54987ms","start":"2026-04-16T22:39:41.884607Z","end":"2026-04-16T22:39:41.999157Z","steps":["trace[1868263837] 'process raft request' (duration: 114.136988ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:40:50.797182Z","caller":"traceutil/trace.go:171","msg":"trace[1912007884] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"185.30984ms","start":"2026-04-16T22:40:50.61185Z","end":"2026-04-16T22:40:50.797159Z","steps":["trace[1912007884] 'process raft request' (duration: 130.915806ms)","trace[1912007884] 'compare' (duration: 54.294142ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:40:50.816401Z","caller":"traceutil/trace.go:171","msg":"trace[1749597945] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"157.213879ms","start":"2026-04-16T22:40:50.659163Z","end":"2026-04-16T22:40:50.816377Z","steps":["trace[1749597945] 'read index received' (duration: 83.611873ms)","trace[1749597945] 'applied index is now lower than readState.Index' (duration: 73.600796ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:40:50.816529Z","caller":"traceutil/trace.go:171","msg":"trace[546344217] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"204.463921ms","start":"2026-04-16T22:40:50.612052Z","end":"2026-04-16T22:40:50.816516Z","steps":["trace[546344217] 'process raft request' (duration: 204.217397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:50.816795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.625896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:40:50.816847Z","caller":"traceutil/trace.go:171","msg":"trace[23072569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"157.754208ms","start":"2026-04-16T22:40:50.659082Z","end":"2026-04-16T22:40:50.816836Z","steps":["trace[23072569] 'agreement among raft nodes before linearized reading' (duration: 157.574415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:51.130928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.668425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:40:51.131071Z","caller":"traceutil/trace.go:171","msg":"trace[1067775674] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"302.578009ms","start":"2026-04-16T22:40:50.828481Z","end":"2026-04-16T22:40:51.131059Z","steps":["trace[1067775674] 'process raft request' (duration: 302.532388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:51.131136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:50.828473Z","time spent":"302.62902ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3542,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:40:51.131132Z","caller":"traceutil/trace.go:171","msg":"trace[10433923] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"305.311043ms","start":"2026-04-16T22:40:50.825785Z","end":"2026-04-16T22:40:51.131096Z","steps":["trace[10433923] 'process raft request' (duration: 103.058599ms)","trace[10433923] 'compare' (duration: 201.545323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:40:51.13127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:50.825775Z","time spent":"305.435374ms","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2269,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:40:51.601034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.562585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:40:51.601121Z","caller":"traceutil/trace.go:171","msg":"trace[2057337237] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"508.641725ms","start":"2026-04-16T22:40:51.092465Z","end":"2026-04-16T22:40:51.601107Z","steps":["trace[2057337237] 'read index received' (duration: 265.732035ms)","trace[2057337237] 'applied index is now lower than readState.Index' (duration: 242.90825ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:40:51.601427Z","caller":"traceutil/trace.go:171","msg":"trace[426931592] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"601.619557ms","start":"2026-04-16T22:40:50.999795Z","end":"2026-04-16T22:40:51.601415Z","steps":["trace[426931592] 'process raft request' (duration: 358.441992ms)","trace[426931592] 'compare' (duration: 242.421933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:40:51.601497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:50.999754Z","time spent":"601.710208ms","remote":"127.0.0.1:59024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211650,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:40:51.601756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.290916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-xpk2r\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-04-16T22:40:51.601818Z","caller":"traceutil/trace.go:171","msg":"trace[1291359878] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-xpk2r; range_end:; response_count:1; response_revision:1252; }","duration":"509.387717ms","start":"2026-04-16T22:40:51.092416Z","end":"2026-04-16T22:40:51.601804Z","steps":["trace[1291359878] 'agreement among raft nodes before linearized reading' (duration: 509.219524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:51.601874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:51.092394Z","time spent":"509.471899ms","remote":"127.0.0.1:59100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7689,"request content":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-xpk2r\" "} {"level":"warn","ts":"2026-04-16T22:40:51.607553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.906154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:40:51.607614Z","caller":"traceutil/trace.go:171","msg":"trace[587987271] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1254; }","duration":"499.020186ms","start":"2026-04-16T22:40:51.10858Z","end":"2026-04-16T22:40:51.6076Z","steps":["trace[587987271] 'agreement among raft nodes before linearized reading' (duration: 498.898194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:51.60764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:51.108559Z","time spent":"499.075237ms","remote":"127.0.0.1:59156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-04-16T22:40:51.607824Z","caller":"traceutil/trace.go:171","msg":"trace[728821657] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"466.59427ms","start":"2026-04-16T22:40:51.141223Z","end":"2026-04-16T22:40:51.607817Z","steps":["trace[728821657] 'process raft request' (duration: 466.158503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:51.607869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:51.141202Z","time spent":"466.638011ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2916,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:40:51.60798Z","caller":"traceutil/trace.go:171","msg":"trace[1283112774] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"466.5283ms","start":"2026-04-16T22:40:51.141445Z","end":"2026-04-16T22:40:51.607974Z","steps":["trace[1283112774] 'process raft request' (duration: 466.007141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:40:51.608008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:40:51.141428Z","time spent":"466.56893ms","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2247,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:40:51.608206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.620902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:40:51.608227Z","caller":"traceutil/trace.go:171","msg":"trace[659408228] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1254; }","duration":"227.671634ms","start":"2026-04-16T22:40:51.380549Z","end":"2026-04-16T22:40:51.60822Z","steps":["trace[659408228] 'agreement among raft nodes before linearized reading' (duration: 227.634923ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:41:06.368053Z","caller":"traceutil/trace.go:171","msg":"trace[821423323] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"156.450326ms","start":"2026-04-16T22:41:06.211579Z","end":"2026-04-16T22:41:06.36803Z","steps":["trace[821423323] 'process raft request' (duration: 156.290183ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:41:06.515275Z","caller":"traceutil/trace.go:171","msg":"trace[690207753] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"141.34815ms","start":"2026-04-16T22:41:06.373898Z","end":"2026-04-16T22:41:06.515246Z","steps":["trace[690207753] 'process raft request' (duration: 138.858185ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:41:06.581074Z","caller":"traceutil/trace.go:171","msg":"trace[713123295] linearizableReadLoop","detail":"{readStateIndex:1549; appliedIndex:1547; }","duration":"174.385342ms","start":"2026-04-16T22:41:06.406665Z","end":"2026-04-16T22:41:06.581051Z","steps":["trace[713123295] 'read index received' (duration: 106.1127ms)","trace[713123295] 'applied index is now lower than readState.Index' (duration: 68.271542ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:41:06.581161Z","caller":"traceutil/trace.go:171","msg":"trace[305198365] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"189.860332ms","start":"2026-04-16T22:41:06.391266Z","end":"2026-04-16T22:41:06.581126Z","steps":["trace[305198365] 'process raft request' (duration: 189.624327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:41:06.581372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.978166ms","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-04-16T22:41:06.581416Z","caller":"traceutil/trace.go:171","msg":"trace[1707679306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1505; }","duration":"167.083658ms","start":"2026-04-16T22:41:06.414321Z","end":"2026-04-16T22:41:06.581405Z","steps":["trace[1707679306] 'agreement among raft nodes before linearized reading' (duration: 166.870195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:41:06.581408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.687826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T22:41:06.581492Z","caller":"traceutil/trace.go:171","msg":"trace[1142049428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1505; }","duration":"174.862659ms","start":"2026-04-16T22:41:06.406612Z","end":"2026-04-16T22:41:06.581474Z","steps":["trace[1142049428] 'agreement among raft nodes before linearized reading' (duration: 174.562045ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:15.867504Z","caller":"traceutil/trace.go:171","msg":"trace[312413058] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"120.893687ms","start":"2026-04-16T22:42:15.746564Z","end":"2026-04-16T22:42:15.867457Z","steps":["trace[312413058] 'process raft request' (duration: 120.686555ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:16.066321Z","caller":"traceutil/trace.go:171","msg":"trace[1262167770] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"114.448757ms","start":"2026-04-16T22:42:15.95184Z","end":"2026-04-16T22:42:16.066289Z","steps":["trace[1262167770] 'process raft request' (duration: 114.194104ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.463404Z","caller":"traceutil/trace.go:171","msg":"trace[961412210] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"101.473726ms","start":"2026-04-16T22:42:27.361887Z","end":"2026-04-16T22:42:27.463361Z","steps":["trace[961412210] 'process raft request' (duration: 101.050092ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.503994Z","caller":"traceutil/trace.go:171","msg":"trace[1923036753] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"111.423772ms","start":"2026-04-16T22:42:27.392529Z","end":"2026-04-16T22:42:27.503953Z","steps":["trace[1923036753] 'process raft request' (duration: 111.199079ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.50421Z","caller":"traceutil/trace.go:171","msg":"trace[2058460299] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"111.491783ms","start":"2026-04-16T22:42:27.392656Z","end":"2026-04-16T22:42:27.504148Z","steps":["trace[2058460299] 'process raft request' (duration: 111.172089ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.504203Z","caller":"traceutil/trace.go:171","msg":"trace[123808576] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"110.194359ms","start":"2026-04-16T22:42:27.393989Z","end":"2026-04-16T22:42:27.504184Z","steps":["trace[123808576] 'process raft request' (duration: 110.053538ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.800744Z","caller":"traceutil/trace.go:171","msg":"trace[908054387] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"115.356424ms","start":"2026-04-16T22:42:27.685338Z","end":"2026-04-16T22:42:27.800695Z","steps":["trace[908054387] 'process raft request' (duration: 81.446688ms)","trace[908054387] 'compare' (duration: 33.714834ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:42:27.801327Z","caller":"traceutil/trace.go:171","msg":"trace[2068323359] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2280; }","duration":"111.823306ms","start":"2026-04-16T22:42:27.689479Z","end":"2026-04-16T22:42:27.801302Z","steps":["trace[2068323359] 'read index received' (duration: 77.343494ms)","trace[2068323359] 'applied index is now lower than readState.Index' (duration: 34.478732ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:42:27.801442Z","caller":"traceutil/trace.go:171","msg":"trace[441735428] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"111.832686ms","start":"2026-04-16T22:42:27.689599Z","end":"2026-04-16T22:42:27.801432Z","steps":["trace[441735428] 'process raft request' (duration: 111.548623ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.801426Z","caller":"traceutil/trace.go:171","msg":"trace[1891298014] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"112.356551ms","start":"2026-04-16T22:42:27.689039Z","end":"2026-04-16T22:42:27.801395Z","steps":["trace[1891298014] 'process raft request' (duration: 111.930867ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.801756Z","caller":"traceutil/trace.go:171","msg":"trace[1743947663] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2191; }","duration":"112.17515ms","start":"2026-04-16T22:42:27.689566Z","end":"2026-04-16T22:42:27.801741Z","steps":["trace[1743947663] 'process raft request' (duration: 111.556613ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.801783Z","caller":"traceutil/trace.go:171","msg":"trace[2025460624] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"110.234899ms","start":"2026-04-16T22:42:27.691537Z","end":"2026-04-16T22:42:27.801772Z","steps":["trace[2025460624] 'process raft request' (duration: 109.654513ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.802274Z","caller":"traceutil/trace.go:171","msg":"trace[989953888] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"109.198198ms","start":"2026-04-16T22:42:27.693064Z","end":"2026-04-16T22:42:27.802262Z","steps":["trace[989953888] 'process raft request' (duration: 108.192218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:42:27.802314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.818586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:112827"} {"level":"info","ts":"2026-04-16T22:42:27.802531Z","caller":"traceutil/trace.go:171","msg":"trace[1669452111] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2194; }","duration":"113.061859ms","start":"2026-04-16T22:42:27.689455Z","end":"2026-04-16T22:42:27.802517Z","steps":["trace[1669452111] 'agreement among raft nodes before linearized reading' (duration: 112.497743ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:27.97688Z","caller":"traceutil/trace.go:171","msg":"trace[805425038] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"102.372827ms","start":"2026-04-16T22:42:27.874482Z","end":"2026-04-16T22:42:27.976855Z","steps":["trace[805425038] 'process raft request' (duration: 57.810628ms)","trace[805425038] 'compare' (duration: 44.429688ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:42:29.946024Z","caller":"traceutil/trace.go:171","msg":"trace[128143126] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"420.594013ms","start":"2026-04-16T22:42:29.525381Z","end":"2026-04-16T22:42:29.945975Z","steps":["trace[128143126] 'process raft request' (duration: 420.23698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:42:29.946135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.057819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4a3351b-22ee-4756-b2d1-7da4e465d2bf\" ","response":"range_response_count:1 size:3736"} {"level":"warn","ts":"2026-04-16T22:42:29.946353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:42:29.525355Z","time spent":"420.823146ms","remote":"127.0.0.1:59100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3721,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:42:29.946371Z","caller":"traceutil/trace.go:171","msg":"trace[503431673] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f4a3351b-22ee-4756-b2d1-7da4e465d2bf; range_end:; response_count:1; response_revision:2240; }","duration":"398.315421ms","start":"2026-04-16T22:42:29.548033Z","end":"2026-04-16T22:42:29.946349Z","steps":["trace[503431673] 'agreement among raft nodes before linearized reading' (duration: 397.999737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:42:29.946525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:42:29.547985Z","time spent":"398.522084ms","remote":"127.0.0.1:59100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3760,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4a3351b-22ee-4756-b2d1-7da4e465d2bf\" "} {"level":"info","ts":"2026-04-16T22:42:29.945921Z","caller":"traceutil/trace.go:171","msg":"trace[1474808499] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"397.846836ms","start":"2026-04-16T22:42:29.548049Z","end":"2026-04-16T22:42:29.945896Z","steps":["trace[1474808499] 'read index received' (duration: 397.668734ms)","trace[1474808499] 'applied index is now lower than readState.Index' (duration: 176.612µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:42:30.058805Z","caller":"traceutil/trace.go:171","msg":"trace[794777132] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2332; }","duration":"112.021502ms","start":"2026-04-16T22:42:29.946759Z","end":"2026-04-16T22:42:30.05878Z","steps":["trace[794777132] 'read index received' (duration: 98.41505ms)","trace[794777132] 'applied index is now lower than readState.Index' (duration: 13.605402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:42:30.059036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.357771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:42:30.059122Z","caller":"traceutil/trace.go:171","msg":"trace[1410652197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2242; }","duration":"336.460212ms","start":"2026-04-16T22:42:29.722649Z","end":"2026-04-16T22:42:30.059109Z","steps":["trace[1410652197] 'agreement among raft nodes before linearized reading' (duration: 336.33297ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:30.059103Z","caller":"traceutil/trace.go:171","msg":"trace[1628470525] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"430.298885ms","start":"2026-04-16T22:42:29.628723Z","end":"2026-04-16T22:42:30.059022Z","steps":["trace[1628470525] 'process raft request' (duration: 416.43721ms)","trace[1628470525] 'compare' (duration: 13.42551ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:42:30.059136Z","caller":"traceutil/trace.go:171","msg":"trace[533791597] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"424.273562ms","start":"2026-04-16T22:42:29.634813Z","end":"2026-04-16T22:42:30.059086Z","steps":["trace[533791597] 'process raft request' (duration: 423.901169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:42:30.059255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:42:29.722599Z","time spent":"336.590433ms","remote":"127.0.0.1:58906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:42:30.059342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:42:29.6287Z","time spent":"430.535948ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:42:30.059378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:42:29.634781Z","time spent":"424.467534ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:42:30.059106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.412665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:42:30.05999Z","caller":"traceutil/trace.go:171","msg":"trace[1693057685] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2242; }","duration":"175.333234ms","start":"2026-04-16T22:42:29.88464Z","end":"2026-04-16T22:42:30.059974Z","steps":["trace[1693057685] 'agreement among raft nodes before linearized reading' (duration: 174.334924ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:42:41.091848Z","caller":"traceutil/trace.go:171","msg":"trace[175681389] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"107.870124ms","start":"2026-04-16T22:42:40.98395Z","end":"2026-04-16T22:42:41.09182Z","steps":["trace[175681389] 'process raft request' (duration: 107.668202ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:02.735486Z","caller":"traceutil/trace.go:171","msg":"trace[1628535373] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"135.531752ms","start":"2026-04-16T22:43:02.599937Z","end":"2026-04-16T22:43:02.735468Z","steps":["trace[1628535373] 'process raft request' (duration: 135.428031ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:02.789653Z","caller":"traceutil/trace.go:171","msg":"trace[559179371] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"128.234ms","start":"2026-04-16T22:43:02.661394Z","end":"2026-04-16T22:43:02.789628Z","steps":["trace[559179371] 'process raft request' (duration: 118.969677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:02.818479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.960608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T22:43:02.818578Z","caller":"traceutil/trace.go:171","msg":"trace[371867217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2511; }","duration":"121.113759ms","start":"2026-04-16T22:43:02.697443Z","end":"2026-04-16T22:43:02.818557Z","steps":["trace[371867217] 'agreement among raft nodes before linearized reading' (duration: 120.804616ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:02.934502Z","caller":"traceutil/trace.go:171","msg":"trace[268071128] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"111.594755ms","start":"2026-04-16T22:43:02.822878Z","end":"2026-04-16T22:43:02.934473Z","steps":["trace[268071128] 'process raft request' (duration: 79.951542ms)","trace[268071128] 'compare' (duration: 31.532342ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:03.386263Z","caller":"traceutil/trace.go:171","msg":"trace[552514299] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2616; }","duration":"156.875082ms","start":"2026-04-16T22:43:03.229363Z","end":"2026-04-16T22:43:03.386238Z","steps":["trace[552514299] 'read index received' (duration: 156.716271ms)","trace[552514299] 'applied index is now lower than readState.Index' (duration: 157.771µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:03.386317Z","caller":"traceutil/trace.go:171","msg":"trace[1833146390] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"221.217889ms","start":"2026-04-16T22:43:03.165062Z","end":"2026-04-16T22:43:03.38628Z","steps":["trace[1833146390] 'process raft request' (duration: 221.019007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:03.386533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.152765ms","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-04-16T22:43:03.387485Z","caller":"traceutil/trace.go:171","msg":"trace[2127364797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2514; }","duration":"158.116644ms","start":"2026-04-16T22:43:03.229351Z","end":"2026-04-16T22:43:03.387468Z","steps":["trace[2127364797] 'agreement among raft nodes before linearized reading' (duration: 157.017643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:03.550818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.773373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:03.551118Z","caller":"traceutil/trace.go:171","msg":"trace[1631670016] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"158.943892ms","start":"2026-04-16T22:43:03.392146Z","end":"2026-04-16T22:43:03.55109Z","steps":["trace[1631670016] 'process raft request' (duration: 22.717744ms)","trace[1631670016] 'compare' (duration: 135.647132ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:03.551371Z","caller":"traceutil/trace.go:171","msg":"trace[1129951235] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"114.084989ms","start":"2026-04-16T22:43:03.437246Z","end":"2026-04-16T22:43:03.551331Z","steps":["trace[1129951235] 'process raft request' (duration: 113.700575ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:03.712157Z","caller":"traceutil/trace.go:171","msg":"trace[2076081667] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"130.029486ms","start":"2026-04-16T22:43:03.582098Z","end":"2026-04-16T22:43:03.712127Z","steps":["trace[2076081667] 'process raft request' (duration: 79.749498ms)","trace[2076081667] 'compare' (duration: 50.103956ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:04.531592Z","caller":"traceutil/trace.go:171","msg":"trace[1565271370] linearizableReadLoop","detail":"{readStateIndex:2622; appliedIndex:2621; }","duration":"135.781302ms","start":"2026-04-16T22:43:04.395768Z","end":"2026-04-16T22:43:04.531549Z","steps":["trace[1565271370] 'read index received' (duration: 135.551128ms)","trace[1565271370] 'applied index is now lower than readState.Index' (duration: 228.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:04.531619Z","caller":"traceutil/trace.go:171","msg":"trace[1203660574] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"296.251617ms","start":"2026-04-16T22:43:04.23534Z","end":"2026-04-16T22:43:04.531591Z","steps":["trace[1203660574] 'process raft request' (duration: 296.037855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:04.531796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.008185ms","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-04-16T22:43:04.531838Z","caller":"traceutil/trace.go:171","msg":"trace[1413597091] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2519; }","duration":"136.106525ms","start":"2026-04-16T22:43:04.395717Z","end":"2026-04-16T22:43:04.531824Z","steps":["trace[1413597091] 'agreement among raft nodes before linearized reading' (duration: 135.950264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:20.440544Z","caller":"traceutil/trace.go:171","msg":"trace[1651311101] transaction","detail":"{read_only:false; response_revision:3321; number_of_response:1; }","duration":"208.893458ms","start":"2026-04-16T22:45:20.231625Z","end":"2026-04-16T22:45:20.440519Z","steps":["trace[1651311101] 'process raft request' (duration: 205.470368ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:06.812623Z","caller":"traceutil/trace.go:171","msg":"trace[1921499612] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"108.219436ms","start":"2026-04-16T22:49:06.704376Z","end":"2026-04-16T22:49:06.812596Z","steps":["trace[1921499612] 'process raft request' (duration: 108.045494ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:06.826747Z","caller":"traceutil/trace.go:171","msg":"trace[1189063178] linearizableReadLoop","detail":"{readStateIndex:5766; appliedIndex:5763; }","duration":"109.373225ms","start":"2026-04-16T22:49:06.717355Z","end":"2026-04-16T22:49:06.826729Z","steps":["trace[1189063178] 'read index received' (duration: 95.085121ms)","trace[1189063178] 'applied index is now lower than readState.Index' (duration: 14.287394ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:06.826839Z","caller":"traceutil/trace.go:171","msg":"trace[1233818] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"122.077063ms","start":"2026-04-16T22:49:06.704754Z","end":"2026-04-16T22:49:06.826831Z","steps":["trace[1233818] 'process raft request' (duration: 121.948781ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:06.826892Z","caller":"traceutil/trace.go:171","msg":"trace[1115479718] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"122.114323ms","start":"2026-04-16T22:49:06.70467Z","end":"2026-04-16T22:49:06.826784Z","steps":["trace[1115479718] 'process raft request' (duration: 121.934491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:06.827091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.71794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T22:49:06.827117Z","caller":"traceutil/trace.go:171","msg":"trace[2020739835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5557; }","duration":"109.78235ms","start":"2026-04-16T22:49:06.717328Z","end":"2026-04-16T22:49:06.82711Z","steps":["trace[2020739835] 'agreement among raft nodes before linearized reading' (duration: 109.645398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:06.827233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.712092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:49:06.827246Z","caller":"traceutil/trace.go:171","msg":"trace[205163571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5557; }","duration":"106.762744ms","start":"2026-04-16T22:49:06.720479Z","end":"2026-04-16T22:49:06.827242Z","steps":["trace[205163571] 'agreement among raft nodes before linearized reading' (duration: 106.721713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:06.827635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.386482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:49:06.827667Z","caller":"traceutil/trace.go:171","msg":"trace[1050526732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5557; }","duration":"105.442753ms","start":"2026-04-16T22:49:06.722215Z","end":"2026-04-16T22:49:06.827658Z","steps":["trace[1050526732] 'agreement among raft nodes before linearized reading' (duration: 105.369532ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:12.208451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2912} {"level":"info","ts":"2026-04-16T22:49:12.309863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2912,"took":"100.30979ms","hash":3076490491,"current-db-size-bytes":54775808,"current-db-size":"55 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-16T22:49:12.309943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3076490491,"revision":2912,"compact-revision":-1} {"level":"warn","ts":"2026-04-16T22:49:13.477419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.709884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:49:13.477813Z","caller":"traceutil/trace.go:171","msg":"trace[252580712] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"221.162765ms","start":"2026-04-16T22:49:13.256635Z","end":"2026-04-16T22:49:13.477798Z","steps":["trace[252580712] 'process raft request' (duration: 119.999913ms)","trace[252580712] 'compare' (duration: 100.591183ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:13.941223Z","caller":"traceutil/trace.go:171","msg":"trace[925619148] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"156.575132ms","start":"2026-04-16T22:49:13.784615Z","end":"2026-04-16T22:49:13.94119Z","steps":["trace[925619148] 'process raft request' (duration: 156.348658ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:13.941347Z","caller":"traceutil/trace.go:171","msg":"trace[1929554665] linearizableReadLoop","detail":"{readStateIndex:5867; appliedIndex:5862; }","duration":"150.896784ms","start":"2026-04-16T22:49:13.790427Z","end":"2026-04-16T22:49:13.941324Z","steps":["trace[1929554665] 'read index received' (duration: 17.226077ms)","trace[1929554665] 'applied index is now lower than readState.Index' (duration: 133.669847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:49:13.941569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.114887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-16T22:49:13.941619Z","caller":"traceutil/trace.go:171","msg":"trace[116651012] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5657; }","duration":"151.220599ms","start":"2026-04-16T22:49:13.79038Z","end":"2026-04-16T22:49:13.9416Z","steps":["trace[116651012] 'agreement among raft nodes before linearized reading' (duration: 151.038946ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:13.941835Z","caller":"traceutil/trace.go:171","msg":"trace[1592545241] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"152.61548ms","start":"2026-04-16T22:49:13.789182Z","end":"2026-04-16T22:49:13.941797Z","steps":["trace[1592545241] 'process raft request' (duration: 152.00174ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:13.941887Z","caller":"traceutil/trace.go:171","msg":"trace[472489222] transaction","detail":"{read_only:false; response_revision:5657; number_of_response:1; }","duration":"143.514029ms","start":"2026-04-16T22:49:13.798358Z","end":"2026-04-16T22:49:13.941872Z","steps":["trace[472489222] 'process raft request' (duration: 142.91939ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:13.942115Z","caller":"traceutil/trace.go:171","msg":"trace[1692792683] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"157.407084ms","start":"2026-04-16T22:49:13.784696Z","end":"2026-04-16T22:49:13.942103Z","steps":["trace[1692792683] 'process raft request' (duration: 156.387569ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:13.942215Z","caller":"traceutil/trace.go:171","msg":"trace[338723722] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"148.140961ms","start":"2026-04-16T22:49:13.794063Z","end":"2026-04-16T22:49:13.942204Z","steps":["trace[338723722] 'process raft request' (duration: 147.166506ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:13.942462Z","caller":"traceutil/trace.go:171","msg":"trace[488222226] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"157.320643ms","start":"2026-04-16T22:49:13.785102Z","end":"2026-04-16T22:49:13.942423Z","steps":["trace[488222226] 'process raft request' (duration: 156.025714ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:51.220047Z","caller":"traceutil/trace.go:171","msg":"trace[1486391008] transaction","detail":"{read_only:false; response_revision:5941; number_of_response:1; }","duration":"143.271742ms","start":"2026-04-16T22:49:51.076752Z","end":"2026-04-16T22:49:51.220024Z","steps":["trace[1486391008] 'process raft request' (duration: 143.008728ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:51.22024Z","caller":"traceutil/trace.go:171","msg":"trace[885263944] transaction","detail":"{read_only:false; response_revision:5942; number_of_response:1; }","duration":"143.471315ms","start":"2026-04-16T22:49:51.07676Z","end":"2026-04-16T22:49:51.220231Z","steps":["trace[885263944] 'process raft request' (duration: 143.207861ms)"],"step_count":1}