{"level":"warn","ts":"2026-05-19T05:29:25.387325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T05:29:25.38744Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84: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.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T05:29:25.38753Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T05:29:25.387544Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-05-19T05:29:25.38761Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T05:29:25.388474Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-05-19T05:29:25.388561Z","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://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T05:29:25.393976Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.071757ms"} {"level":"info","ts":"2026-05-19T05:29:25.402143Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-05-19T05:29:25.402231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T05:29:25.402274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-05-19T05:29:25.402295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T05:29:25.402304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-05-19T05:29:25.402365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-05-19T05:29:25.410256Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T05:29:25.411626Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T05:29:25.413416Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T05:29:25.416794Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T05:29:25.417109Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T05:29:25.41708Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T05:29:25.417227Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T05:29:25.417247Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T05:29:25.417634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-05-19T05:29:25.417699Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-05-19T05:29:25.428316Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T05:29:25.4285Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-05-19T05:29:25.428644Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T05:29:25.428699Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T05:29:25.428672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-05-19T05:29:26.303023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T05:29:26.30322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T05:29:26.303433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-05-19T05:29:26.303545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-05-19T05:29:26.30364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-05-19T05:29:26.303717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-05-19T05:29:26.30379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-05-19T05:29:26.418721Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T05:29:26.4189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T05:29:26.418752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T05:29:26.418819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:29:26.419501Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T05:29:26.419533Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T05:29:26.4213Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:29:26.421428Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:29:26.421469Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:29:26.423295Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-05-19T05:29:26.424126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T05:29:43.125982Z","caller":"traceutil/trace.go:171","msg":"trace[437987451] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"123.98471ms","start":"2026-05-19T05:29:43.001981Z","end":"2026-05-19T05:29:43.125966Z","steps":["trace[437987451] 'process raft request' (duration: 115.760995ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:29:43.126314Z","caller":"traceutil/trace.go:171","msg":"trace[783583483] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"123.97415ms","start":"2026-05-19T05:29:43.002317Z","end":"2026-05-19T05:29:43.126291Z","steps":["trace[783583483] 'process raft request' (duration: 123.618577ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:29:43.127736Z","caller":"traceutil/trace.go:171","msg":"trace[648626115] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"125.386352ms","start":"2026-05-19T05:29:43.002334Z","end":"2026-05-19T05:29:43.127721Z","steps":["trace[648626115] 'process raft request' (duration: 125.18964ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:29:43.127722Z","caller":"traceutil/trace.go:171","msg":"trace[737285424] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"122.543017ms","start":"2026-05-19T05:29:43.005153Z","end":"2026-05-19T05:29:43.127696Z","steps":["trace[737285424] 'process raft request' (duration: 122.487126ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:29:54.984368Z","caller":"traceutil/trace.go:171","msg":"trace[597825733] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"101.965366ms","start":"2026-05-19T05:29:54.882368Z","end":"2026-05-19T05:29:54.984334Z","steps":["trace[597825733] 'process raft request' (duration: 43.281435ms)","trace[597825733] 'compare' (duration: 58.335179ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:30:00.361578Z","caller":"traceutil/trace.go:171","msg":"trace[443119896] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"274.664982ms","start":"2026-05-19T05:30:00.08688Z","end":"2026-05-19T05:30:00.361545Z","steps":["trace[443119896] 'read index received' (duration: 274.549581ms)","trace[443119896] 'applied index is now lower than readState.Index' (duration: 114.441µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:30:00.361836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.938164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T05:30:00.362012Z","caller":"traceutil/trace.go:171","msg":"trace[534271102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"275.186517ms","start":"2026-05-19T05:30:00.086809Z","end":"2026-05-19T05:30:00.361995Z","steps":["trace[534271102] 'agreement among raft nodes before linearized reading' (duration: 274.852084ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:30:00.362246Z","caller":"traceutil/trace.go:171","msg":"trace[1560519594] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"300.194763ms","start":"2026-05-19T05:30:00.061411Z","end":"2026-05-19T05:30:00.361606Z","steps":["trace[1560519594] 'process raft request' (duration: 299.86648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:30:00.363963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:30:00.061371Z","time spent":"301.843776ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:30:00.388915Z","caller":"traceutil/trace.go:171","msg":"trace[144774705] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"121.489316ms","start":"2026-05-19T05:30:00.267403Z","end":"2026-05-19T05:30:00.388892Z","steps":["trace[144774705] 'process raft request' (duration: 120.993792ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:30:03.418362Z","caller":"traceutil/trace.go:171","msg":"trace[569081100] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"201.349785ms","start":"2026-05-19T05:30:03.216937Z","end":"2026-05-19T05:30:03.418287Z","steps":["trace[569081100] 'read index received' (duration: 196.302156ms)","trace[569081100] 'applied index is now lower than readState.Index' (duration: 5.045689ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:30:03.418753Z","caller":"traceutil/trace.go:171","msg":"trace[2052392116] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"341.591138ms","start":"2026-05-19T05:30:03.077117Z","end":"2026-05-19T05:30:03.418708Z","steps":["trace[2052392116] 'process raft request' (duration: 336.402227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:30:03.419097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:30:03.077083Z","time spent":"341.7645ms","remote":"127.0.0.1:50384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:30:03.419404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.449193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2g72b\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T05:30:03.420042Z","caller":"traceutil/trace.go:171","msg":"trace[1538142163] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2g72b; range_end:; response_count:1; response_revision:576; }","duration":"202.588504ms","start":"2026-05-19T05:30:03.216884Z","end":"2026-05-19T05:30:03.419472Z","steps":["trace[1538142163] 'agreement among raft nodes before linearized reading' (duration: 201.589227ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:30:03.926011Z","caller":"traceutil/trace.go:171","msg":"trace[718966072] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"491.712038ms","start":"2026-05-19T05:30:03.43426Z","end":"2026-05-19T05:30:03.925972Z","steps":["trace[718966072] 'process raft request' (duration: 455.953111ms)","trace[718966072] 'compare' (duration: 35.212373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:30:03.926597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:30:03.434241Z","time spent":"491.873529ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:30:03.937106Z","caller":"traceutil/trace.go:171","msg":"trace[1807325937] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"436.887913ms","start":"2026-05-19T05:30:03.500193Z","end":"2026-05-19T05:30:03.937081Z","steps":["trace[1807325937] 'read index received' (duration: 390.034732ms)","trace[1807325937] 'applied index is now lower than readState.Index' (duration: 46.851831ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:30:03.937175Z","caller":"traceutil/trace.go:171","msg":"trace[760443378] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"463.258817ms","start":"2026-05-19T05:30:03.473877Z","end":"2026-05-19T05:30:03.937135Z","steps":["trace[760443378] 'process raft request' (duration: 463.093436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:30:03.937286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.099706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:30:03.93737Z","caller":"traceutil/trace.go:171","msg":"trace[73188770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"437.231836ms","start":"2026-05-19T05:30:03.500125Z","end":"2026-05-19T05:30:03.937357Z","steps":["trace[73188770] 'agreement among raft nodes before linearized reading' (duration: 437.039285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:30:03.937319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:30:03.473854Z","time spent":"463.405369ms","remote":"127.0.0.1:50700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T05:30:03.937452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:30:03.500103Z","time spent":"437.324277ms","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T05:30:18.543768Z","caller":"traceutil/trace.go:171","msg":"trace[345171749] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"219.162859ms","start":"2026-05-19T05:30:18.324577Z","end":"2026-05-19T05:30:18.54374Z","steps":["trace[345171749] 'process raft request' (duration: 218.986218ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:31:12.022838Z","caller":"traceutil/trace.go:171","msg":"trace[1659871986] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"131.003789ms","start":"2026-05-19T05:31:11.891664Z","end":"2026-05-19T05:31:12.022667Z","steps":["trace[1659871986] 'process raft request' (duration: 129.105908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:31:36.927416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.490926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ca41948-0270-47ea-8458-a043fbc8e303\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-19T05:31:36.927511Z","caller":"traceutil/trace.go:171","msg":"trace[2019072787] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ca41948-0270-47ea-8458-a043fbc8e303; range_end:; response_count:1; response_revision:1819; }","duration":"122.66273ms","start":"2026-05-19T05:31:36.804829Z","end":"2026-05-19T05:31:36.927492Z","steps":["trace[2019072787] 'range keys from in-memory index tree' (duration: 122.329332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:31:36.928955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.472647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-05-19T05:31:36.929024Z","caller":"traceutil/trace.go:171","msg":"trace[580924386] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1819; }","duration":"203.588969ms","start":"2026-05-19T05:31:36.725421Z","end":"2026-05-19T05:31:36.92901Z","steps":["trace[580924386] 'range keys from in-memory index tree' (duration: 202.214409ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:31:36.929734Z","caller":"traceutil/trace.go:171","msg":"trace[593102752] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"113.562423ms","start":"2026-05-19T05:31:36.816133Z","end":"2026-05-19T05:31:36.929696Z","steps":["trace[593102752] 'process raft request' (duration: 90.923525ms)","trace[593102752] 'compare' (duration: 20.250437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:31:37.300423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.489752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:31:37.300575Z","caller":"traceutil/trace.go:171","msg":"trace[1060323711] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"277.166569ms","start":"2026-05-19T05:31:37.02336Z","end":"2026-05-19T05:31:37.300527Z","steps":["trace[1060323711] 'read index received' (duration: 10.008963ms)","trace[1060323711] 'applied index is now lower than readState.Index' (duration: 267.156016ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:31:37.300903Z","caller":"traceutil/trace.go:171","msg":"trace[886419146] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"359.132597ms","start":"2026-05-19T05:31:36.941718Z","end":"2026-05-19T05:31:37.300851Z","steps":["trace[886419146] 'process raft request' (duration: 91.809818ms)","trace[886419146] 'compare' (duration: 266.097323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:31:37.301023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.656831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T05:31:37.301375Z","caller":"traceutil/trace.go:171","msg":"trace[1068994326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1822; }","duration":"277.990108ms","start":"2026-05-19T05:31:37.023348Z","end":"2026-05-19T05:31:37.301338Z","steps":["trace[1068994326] 'agreement among raft nodes before linearized reading' (duration: 277.534158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:31:37.301472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:31:36.941706Z","time spent":"359.521175ms","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:31:37.304658Z","caller":"traceutil/trace.go:171","msg":"trace[1508159854] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"247.370311ms","start":"2026-05-19T05:31:37.057265Z","end":"2026-05-19T05:31:37.304635Z","steps":["trace[1508159854] 'process raft request' (duration: 247.258429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:31:37.305279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.312982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T05:31:37.305294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.560301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-19T05:31:37.305319Z","caller":"traceutil/trace.go:171","msg":"trace[972275826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1823; }","duration":"165.365693ms","start":"2026-05-19T05:31:37.139945Z","end":"2026-05-19T05:31:37.305311Z","steps":["trace[972275826] 'agreement among raft nodes before linearized reading' (duration: 165.232171ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:31:37.305347Z","caller":"traceutil/trace.go:171","msg":"trace[1402410762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1823; }","duration":"244.655443ms","start":"2026-05-19T05:31:37.060676Z","end":"2026-05-19T05:31:37.305332Z","steps":["trace[1402410762] 'agreement among raft nodes before linearized reading' (duration: 244.52526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:31:37.305606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.696911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T05:31:37.305651Z","caller":"traceutil/trace.go:171","msg":"trace[148101831] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1823; }","duration":"221.782722ms","start":"2026-05-19T05:31:37.083856Z","end":"2026-05-19T05:31:37.305639Z","steps":["trace[148101831] 'agreement among raft nodes before linearized reading' (duration: 221.6753ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:31:49.121125Z","caller":"traceutil/trace.go:171","msg":"trace[28361572] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"138.526734ms","start":"2026-05-19T05:31:48.982572Z","end":"2026-05-19T05:31:49.121099Z","steps":["trace[28361572] 'process raft request' (duration: 138.34531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:32:11.399369Z","caller":"traceutil/trace.go:171","msg":"trace[1181065672] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"213.410099ms","start":"2026-05-19T05:32:11.185927Z","end":"2026-05-19T05:32:11.399337Z","steps":["trace[1181065672] 'process raft request' (duration: 203.755665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:32:11.670253Z","caller":"traceutil/trace.go:171","msg":"trace[687985523] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2139; }","duration":"352.110681ms","start":"2026-05-19T05:32:11.31811Z","end":"2026-05-19T05:32:11.670221Z","steps":["trace[687985523] 'read index received' (duration: 71.575604ms)","trace[687985523] 'applied index is now lower than readState.Index' (duration: 280.533117ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:32:11.670414Z","caller":"traceutil/trace.go:171","msg":"trace[1384520136] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"455.820069ms","start":"2026-05-19T05:32:11.21458Z","end":"2026-05-19T05:32:11.6704Z","steps":["trace[1384520136] 'process raft request' (duration: 455.351791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:11.670564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:32:11.214561Z","time spent":"455.884259ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:32:11.670679Z","caller":"traceutil/trace.go:171","msg":"trace[744348325] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"419.027866ms","start":"2026-05-19T05:32:11.251479Z","end":"2026-05-19T05:32:11.670506Z","steps":["trace[744348325] 'process raft request' (duration: 418.674249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:11.670749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.60491ms","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-05-19T05:32:11.670835Z","caller":"traceutil/trace.go:171","msg":"trace[112702106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2066; }","duration":"352.758732ms","start":"2026-05-19T05:32:11.318053Z","end":"2026-05-19T05:32:11.670812Z","steps":["trace[112702106] 'agreement among raft nodes before linearized reading' (duration: 352.537739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:11.670861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:32:11.251433Z","time spent":"419.327051ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:32:11.670886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:32:11.318023Z","time spent":"352.852003ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-19T05:32:11.710165Z","caller":"traceutil/trace.go:171","msg":"trace[1388101247] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"180.277707ms","start":"2026-05-19T05:32:11.529861Z","end":"2026-05-19T05:32:11.710139Z","steps":["trace[1388101247] 'process raft request' (duration: 180.140875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:11.711487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.282573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-19T05:32:11.711463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.005634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:32:11.711559Z","caller":"traceutil/trace.go:171","msg":"trace[2025974126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2067; }","duration":"132.389114ms","start":"2026-05-19T05:32:11.579153Z","end":"2026-05-19T05:32:11.711543Z","steps":["trace[2025974126] 'agreement among raft nodes before linearized reading' (duration: 132.12338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:11.711764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.685474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-19T05:32:11.711758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.181145ms","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-05-19T05:32:11.712017Z","caller":"traceutil/trace.go:171","msg":"trace[1392446158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2067; }","duration":"209.221118ms","start":"2026-05-19T05:32:11.502356Z","end":"2026-05-19T05:32:11.711577Z","steps":["trace[1392446158] 'agreement among raft nodes before linearized reading' (duration: 208.925923ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:32:11.712067Z","caller":"traceutil/trace.go:171","msg":"trace[1680036357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"134.768575ms","start":"2026-05-19T05:32:11.577033Z","end":"2026-05-19T05:32:11.711802Z","steps":["trace[1680036357] 'agreement among raft nodes before linearized reading' (duration: 134.576441ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:32:11.712156Z","caller":"traceutil/trace.go:171","msg":"trace[777469978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2067; }","duration":"134.297947ms","start":"2026-05-19T05:32:11.57753Z","end":"2026-05-19T05:32:11.711828Z","steps":["trace[777469978] 'agreement among raft nodes before linearized reading' (duration: 134.056473ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:32:11.856279Z","caller":"traceutil/trace.go:171","msg":"trace[1526458358] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"137.41928ms","start":"2026-05-19T05:32:11.718843Z","end":"2026-05-19T05:32:11.856262Z","steps":["trace[1526458358] 'process raft request' (duration: 137.387049ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:32:11.856436Z","caller":"traceutil/trace.go:171","msg":"trace[1389585063] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"140.447292ms","start":"2026-05-19T05:32:11.715982Z","end":"2026-05-19T05:32:11.85643Z","steps":["trace[1389585063] 'process raft request' (duration: 128.5595ms)","trace[1389585063] 'compare' (duration: 11.583357ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:32:12.344021Z","caller":"traceutil/trace.go:171","msg":"trace[64435584] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"109.430785ms","start":"2026-05-19T05:32:12.234541Z","end":"2026-05-19T05:32:12.343972Z","steps":["trace[64435584] 'process raft request' (duration: 109.174731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:12.689095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.83631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:32:12.68937Z","caller":"traceutil/trace.go:171","msg":"trace[1853066243] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"305.260926ms","start":"2026-05-19T05:32:12.384056Z","end":"2026-05-19T05:32:12.689316Z","steps":["trace[1853066243] 'process raft request' (duration: 134.101501ms)","trace[1853066243] 'compare' (duration: 170.477934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:32:12.689632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:32:12.384031Z","time spent":"305.446829ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:32:13.947959Z","caller":"traceutil/trace.go:171","msg":"trace[1778270440] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"212.658863ms","start":"2026-05-19T05:32:13.73527Z","end":"2026-05-19T05:32:13.947929Z","steps":["trace[1778270440] 'read index received' (duration: 143.559386ms)","trace[1778270440] 'applied index is now lower than readState.Index' (duration: 69.097867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:32:13.948224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.936609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T05:32:13.948289Z","caller":"traceutil/trace.go:171","msg":"trace[787573092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2076; }","duration":"213.059371ms","start":"2026-05-19T05:32:13.735213Z","end":"2026-05-19T05:32:13.948272Z","steps":["trace[787573092] 'agreement among raft nodes before linearized reading' (duration: 212.870408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:32:13.948343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.831733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T05:32:13.948309Z","caller":"traceutil/trace.go:171","msg":"trace[42563244] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"232.199061ms","start":"2026-05-19T05:32:13.715911Z","end":"2026-05-19T05:32:13.94811Z","steps":["trace[42563244] 'process raft request' (duration: 163.050162ms)","trace[42563244] 'compare' (duration: 68.669451ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:32:13.9484Z","caller":"traceutil/trace.go:171","msg":"trace[90318216] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2076; }","duration":"167.931445ms","start":"2026-05-19T05:32:13.780447Z","end":"2026-05-19T05:32:13.948379Z","steps":["trace[90318216] 'agreement among raft nodes before linearized reading' (duration: 167.683531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:33:53.156891Z","caller":"traceutil/trace.go:171","msg":"trace[86075930] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"144.225027ms","start":"2026-05-19T05:33:53.01264Z","end":"2026-05-19T05:33:53.156865Z","steps":["trace[86075930] 'process raft request' (duration: 106.438398ms)","trace[86075930] 'compare' (duration: 37.168042ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:33:58.495904Z","caller":"traceutil/trace.go:171","msg":"trace[1645078224] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"122.854361ms","start":"2026-05-19T05:33:58.373007Z","end":"2026-05-19T05:33:58.495861Z","steps":["trace[1645078224] 'process raft request' (duration: 122.677719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:37:52.097405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.306159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T05:37:52.097528Z","caller":"traceutil/trace.go:171","msg":"trace[957840916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4657; }","duration":"145.507653ms","start":"2026-05-19T05:37:51.951996Z","end":"2026-05-19T05:37:52.097503Z","steps":["trace[957840916] 'range keys from in-memory index tree' (duration: 145.072364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:37:52.097836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.094374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-05-19T05:37:52.098548Z","caller":"traceutil/trace.go:171","msg":"trace[941814696] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4657; }","duration":"145.156675ms","start":"2026-05-19T05:37:51.952711Z","end":"2026-05-19T05:37:52.097868Z","steps":["trace[941814696] 'range keys from in-memory index tree' (duration: 144.996432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:37:52.098812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.35647ms","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-05-19T05:37:52.098853Z","caller":"traceutil/trace.go:171","msg":"trace[1667467680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4657; }","duration":"141.419161ms","start":"2026-05-19T05:37:51.957424Z","end":"2026-05-19T05:37:52.098843Z","steps":["trace[1667467680] 'range keys from in-memory index tree' (duration: 141.259288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:37:52.09905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.000287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T05:37:52.099089Z","caller":"traceutil/trace.go:171","msg":"trace[1619427347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4657; }","duration":"139.048338ms","start":"2026-05-19T05:37:51.960032Z","end":"2026-05-19T05:37:52.09908Z","steps":["trace[1619427347] 'range keys from in-memory index tree' (duration: 138.926315ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:37:57.923444Z","caller":"traceutil/trace.go:171","msg":"trace[618637615] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4887; }","duration":"144.225709ms","start":"2026-05-19T05:37:57.779185Z","end":"2026-05-19T05:37:57.923411Z","steps":["trace[618637615] 'read index received' (duration: 143.956593ms)","trace[618637615] 'applied index is now lower than readState.Index' (duration: 267.276µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:37:57.923572Z","caller":"traceutil/trace.go:171","msg":"trace[999128301] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"164.17795ms","start":"2026-05-19T05:37:57.759341Z","end":"2026-05-19T05:37:57.923519Z","steps":["trace[999128301] 'process raft request' (duration: 163.797321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:37:57.923729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.514177ms","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-05-19T05:37:57.923784Z","caller":"traceutil/trace.go:171","msg":"trace[72776943] 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:4708; }","duration":"144.64253ms","start":"2026-05-19T05:37:57.779127Z","end":"2026-05-19T05:37:57.923769Z","steps":["trace[72776943] 'agreement among raft nodes before linearized reading' (duration: 144.435415ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:37:57.941373Z","caller":"traceutil/trace.go:171","msg":"trace[2106475202] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"123.662926ms","start":"2026-05-19T05:37:57.817654Z","end":"2026-05-19T05:37:57.941317Z","steps":["trace[2106475202] 'process raft request' (duration: 123.000352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:37:58.301079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.874207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:37:58.301559Z","caller":"traceutil/trace.go:171","msg":"trace[1590661792] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"134.440977ms","start":"2026-05-19T05:37:58.167099Z","end":"2026-05-19T05:37:58.30154Z","steps":["trace[1590661792] 'read index received' (duration: 18.924526ms)","trace[1590661792] 'applied index is now lower than readState.Index' (duration: 115.514501ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:37:58.30177Z","caller":"traceutil/trace.go:171","msg":"trace[1828559924] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"200.691683ms","start":"2026-05-19T05:37:58.100941Z","end":"2026-05-19T05:37:58.301633Z","steps":["trace[1828559924] 'process raft request' (duration: 85.171422ms)","trace[1828559924] 'compare' (duration: 114.736934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:37:58.302124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.01065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T05:37:58.302206Z","caller":"traceutil/trace.go:171","msg":"trace[539286270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4718; }","duration":"135.125763ms","start":"2026-05-19T05:37:58.167064Z","end":"2026-05-19T05:37:58.30219Z","steps":["trace[539286270] 'agreement among raft nodes before linearized reading' (duration: 134.58361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:38:01.912389Z","caller":"traceutil/trace.go:171","msg":"trace[2085697859] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"101.26844ms","start":"2026-05-19T05:38:01.81108Z","end":"2026-05-19T05:38:01.912348Z","steps":["trace[2085697859] 'process raft request' (duration: 100.926953ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:38:44.847926Z","caller":"traceutil/trace.go:171","msg":"trace[1660569419] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"103.224238ms","start":"2026-05-19T05:38:44.744668Z","end":"2026-05-19T05:38:44.847893Z","steps":["trace[1660569419] 'process raft request' (duration: 88.994246ms)","trace[1660569419] 'compare' (duration: 14.10212ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:39:26.450523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-05-19T05:39:26.537818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"85.796034ms","hash":2083868577,"current-db-size-bytes":53850112,"current-db-size":"54 MB","current-db-size-in-use-bytes":28975104,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T05:39:26.537925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2083868577,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-05-19T05:42:15.585758Z","caller":"traceutil/trace.go:171","msg":"trace[1554564997] transaction","detail":"{read_only:false; response_revision:6725; number_of_response:1; }","duration":"182.617883ms","start":"2026-05-19T05:42:15.403117Z","end":"2026-05-19T05:42:15.585735Z","steps":["trace[1554564997] 'process raft request' (duration: 182.355579ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:42:28.162641Z","caller":"traceutil/trace.go:171","msg":"trace[318095082] transaction","detail":"{read_only:false; response_revision:6791; number_of_response:1; }","duration":"117.257927ms","start":"2026-05-19T05:42:28.045327Z","end":"2026-05-19T05:42:28.162585Z","steps":["trace[318095082] 'process raft request' (duration: 116.666787ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:43:26.540687Z","caller":"traceutil/trace.go:171","msg":"trace[108147552] transaction","detail":"{read_only:false; response_revision:7105; number_of_response:1; }","duration":"460.927143ms","start":"2026-05-19T05:43:26.079721Z","end":"2026-05-19T05:43:26.540648Z","steps":["trace[108147552] 'process raft request' (duration: 460.710883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:26.540948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:26.0797Z","time spent":"461.124012ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:43:26.541222Z","caller":"traceutil/trace.go:171","msg":"trace[1570533578] linearizableReadLoop","detail":"{readStateIndex:7366; appliedIndex:7365; }","duration":"275.779262ms","start":"2026-05-19T05:43:26.265412Z","end":"2026-05-19T05:43:26.541191Z","steps":["trace[1570533578] 'read index received' (duration: 275.555702ms)","trace[1570533578] 'applied index is now lower than readState.Index' (duration: 222.56µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:43:26.541321Z","caller":"traceutil/trace.go:171","msg":"trace[2138625321] transaction","detail":"{read_only:false; response_revision:7106; number_of_response:1; }","duration":"323.561358ms","start":"2026-05-19T05:43:26.217745Z","end":"2026-05-19T05:43:26.541306Z","steps":["trace[2138625321] 'process raft request' (duration: 323.282955ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:43:26.541351Z","caller":"traceutil/trace.go:171","msg":"trace[1757170875] transaction","detail":"{read_only:false; response_revision:7107; number_of_response:1; }","duration":"171.119715ms","start":"2026-05-19T05:43:26.370215Z","end":"2026-05-19T05:43:26.541335Z","steps":["trace[1757170875] 'process raft request' (duration: 170.938827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:26.541407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:26.217718Z","time spent":"323.644601ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:43:26.541651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.817141ms","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-05-19T05:43:26.541696Z","caller":"traceutil/trace.go:171","msg":"trace[1665330845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7107; }","duration":"111.895955ms","start":"2026-05-19T05:43:26.42979Z","end":"2026-05-19T05:43:26.541685Z","steps":["trace[1665330845] 'agreement among raft nodes before linearized reading' (duration: 111.731778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:26.541912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.947113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T05:43:26.54195Z","caller":"traceutil/trace.go:171","msg":"trace[2135910973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7107; }","duration":"110.006076ms","start":"2026-05-19T05:43:26.431936Z","end":"2026-05-19T05:43:26.541942Z","steps":["trace[2135910973] 'agreement among raft nodes before linearized reading' (duration: 109.907282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:26.542035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.59701ms","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-05-19T05:43:26.542094Z","caller":"traceutil/trace.go:171","msg":"trace[687753248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7107; }","duration":"276.729356ms","start":"2026-05-19T05:43:26.265349Z","end":"2026-05-19T05:43:26.542078Z","steps":["trace[687753248] 'agreement among raft nodes before linearized reading' (duration: 276.559279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:30.490737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320895476937579457,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T05:43:30.991095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320895476937579457,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T05:43:31.432256Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.44189547s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T05:43:31.432682Z","caller":"traceutil/trace.go:171","msg":"trace[276471847] transaction","detail":"{read_only:false; response_revision:7123; number_of_response:1; }","duration":"1.442358901s","start":"2026-05-19T05:43:29.990295Z","end":"2026-05-19T05:43:31.432654Z","steps":["trace[276471847] 'process raft request' (duration: 1.442202404s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.432879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:29.990282Z","time spent":"1.442486427s","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:43:31.435789Z","caller":"traceutil/trace.go:171","msg":"trace[1131015446] transaction","detail":"{read_only:false; response_revision:7125; number_of_response:1; }","duration":"866.142514ms","start":"2026-05-19T05:43:30.569615Z","end":"2026-05-19T05:43:31.435758Z","steps":["trace[1131015446] 'process raft request' (duration: 866.068651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.435959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.569568Z","time spent":"866.288591ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:43:31.435899Z","caller":"traceutil/trace.go:171","msg":"trace[537122147] transaction","detail":"{read_only:false; response_revision:7124; number_of_response:1; }","duration":"1.445394542s","start":"2026-05-19T05:43:29.990433Z","end":"2026-05-19T05:43:31.435828Z","steps":["trace[537122147] 'process raft request' (duration: 1.445081437s)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:43:31.436084Z","caller":"traceutil/trace.go:171","msg":"trace[370076253] transaction","detail":"{read_only:false; response_revision:7126; number_of_response:1; }","duration":"860.941773ms","start":"2026-05-19T05:43:30.57512Z","end":"2026-05-19T05:43:31.436062Z","steps":["trace[370076253] 'process raft request' (duration: 860.601058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.436249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.575098Z","time spent":"861.037238ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:43:31.436301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:29.990424Z","time spent":"1.445712856s","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:43:31.436494Z","caller":"traceutil/trace.go:171","msg":"trace[402978704] transaction","detail":"{read_only:false; response_revision:7127; number_of_response:1; }","duration":"605.222452ms","start":"2026-05-19T05:43:30.831262Z","end":"2026-05-19T05:43:31.436484Z","steps":["trace[402978704] 'process raft request' (duration: 604.711668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.436561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.831227Z","time spent":"605.299376ms","remote":"127.0.0.1:50582","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-05-19T05:43:31.437372Z","caller":"traceutil/trace.go:171","msg":"trace[747661678] linearizableReadLoop","detail":"{readStateIndex:7387; appliedIndex:7383; }","duration":"1.446900012s","start":"2026-05-19T05:43:29.990453Z","end":"2026-05-19T05:43:31.437353Z","steps":["trace[747661678] 'read index received' (duration: 1.442234326s)","trace[747661678] 'applied index is now lower than readState.Index' (duration: 4.664166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:43:31.437541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446928473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:43:31.437572Z","caller":"traceutil/trace.go:171","msg":"trace[23916517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7128; }","duration":"1.446974425s","start":"2026-05-19T05:43:29.990589Z","end":"2026-05-19T05:43:31.437564Z","steps":["trace[23916517] 'agreement among raft nodes before linearized reading' (duration: 1.446915543s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.437594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:29.990585Z","time spent":"1.447003386s","remote":"127.0.0.1:37600","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-05-19T05:43:31.437759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.942306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-19T05:43:31.437888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.143422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T05:43:31.437887Z","caller":"traceutil/trace.go:171","msg":"trace[263931959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7128; }","duration":"863.130775ms","start":"2026-05-19T05:43:30.574727Z","end":"2026-05-19T05:43:31.437858Z","steps":["trace[263931959] 'agreement among raft nodes before linearized reading' (duration: 862.786529ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:43:31.43792Z","caller":"traceutil/trace.go:171","msg":"trace[797007490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7128; }","duration":"864.205265ms","start":"2026-05-19T05:43:30.573705Z","end":"2026-05-19T05:43:31.43791Z","steps":["trace[797007490] 'agreement among raft nodes before linearized reading' (duration: 864.141962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.437942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.573681Z","time spent":"864.255547ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T05:43:31.437945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.574706Z","time spent":"863.225669ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T05:43:31.43804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.00951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details\" ","response":"range_response_count:1 size:13450"} {"level":"info","ts":"2026-05-19T05:43:31.438067Z","caller":"traceutil/trace.go:171","msg":"trace[194983143] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details; range_end:; response_count:1; response_revision:7128; }","duration":"893.069073ms","start":"2026-05-19T05:43:30.544989Z","end":"2026-05-19T05:43:31.438058Z","steps":["trace[194983143] 'agreement among raft nodes before linearized reading' (duration: 892.99959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.43809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.544971Z","time spent":"893.113304ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13474,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details\" "} {"level":"warn","ts":"2026-05-19T05:43:31.438117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.598942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:43:31.438153Z","caller":"traceutil/trace.go:171","msg":"trace[1057006727] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:7128; }","duration":"492.698677ms","start":"2026-05-19T05:43:30.945442Z","end":"2026-05-19T05:43:31.43814Z","steps":["trace[1057006727] 'agreement among raft nodes before linearized reading' (duration: 492.637254ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:43:31.438154Z","caller":"traceutil/trace.go:171","msg":"trace[326459751] transaction","detail":"{read_only:false; response_revision:7128; number_of_response:1; }","duration":"160.266312ms","start":"2026-05-19T05:43:31.277873Z","end":"2026-05-19T05:43:31.438139Z","steps":["trace[326459751] 'process raft request' (duration: 158.575063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.438181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.945389Z","time spent":"492.784151ms","remote":"127.0.0.1:50386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T05:43:31.437821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.819497ms","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-05-19T05:43:31.438526Z","caller":"traceutil/trace.go:171","msg":"trace[837562460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7128; }","duration":"864.451986ms","start":"2026-05-19T05:43:30.573954Z","end":"2026-05-19T05:43:31.438406Z","steps":["trace[837562460] 'agreement among raft nodes before linearized reading' (duration: 863.777085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.438233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.695675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:43:31.438684Z","caller":"traceutil/trace.go:171","msg":"trace[1848920262] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:7128; }","duration":"978.222409ms","start":"2026-05-19T05:43:30.460449Z","end":"2026-05-19T05:43:31.438671Z","steps":["trace[1848920262] 'agreement among raft nodes before linearized reading' (duration: 977.658673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.438732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.46043Z","time spent":"978.288511ms","remote":"127.0.0.1:50370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-19T05:43:31.438326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.671647ms","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-05-19T05:43:31.438858Z","caller":"traceutil/trace.go:171","msg":"trace[1197730776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7128; }","duration":"862.216372ms","start":"2026-05-19T05:43:30.576631Z","end":"2026-05-19T05:43:31.438847Z","steps":["trace[1197730776] 'agreement among raft nodes before linearized reading' (duration: 861.631445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.438899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.576621Z","time spent":"862.266485ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T05:43:31.438247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.439961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T05:43:31.439012Z","caller":"traceutil/trace.go:171","msg":"trace[1193210433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7128; }","duration":"847.222937ms","start":"2026-05-19T05:43:30.591778Z","end":"2026-05-19T05:43:31.439001Z","steps":["trace[1193210433] 'agreement among raft nodes before linearized reading' (duration: 846.41685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.439034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.591764Z","time spent":"847.263579ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T05:43:31.440014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:30.573938Z","time spent":"864.637585ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T05:43:31.441007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45054085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T05:43:31.441068Z","caller":"traceutil/trace.go:171","msg":"trace[172075029] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:7128; }","duration":"1.450625013s","start":"2026-05-19T05:43:29.990428Z","end":"2026-05-19T05:43:31.441053Z","steps":["trace[172075029] 'agreement among raft nodes before linearized reading' (duration: 1.448010422s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:43:31.441096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:43:29.990419Z","time spent":"1.450670876s","remote":"127.0.0.1:50308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.84\" "} {"level":"info","ts":"2026-05-19T05:44:26.456893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5558} {"level":"info","ts":"2026-05-19T05:44:26.553565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5558,"took":"95.302217ms","hash":3713278793,"current-db-size-bytes":53850112,"current-db-size":"54 MB","current-db-size-in-use-bytes":18194432,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T05:44:26.553673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3713278793,"revision":5558,"compact-revision":2891}