{"level":"warn","ts":"2026-05-26T21:03:03.892688Z","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-26T21:03:03.892943Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-26T21:03:03.893129Z","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-26T21:03:03.893196Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-26T21:03:03.893294Z","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-26T21:03:03.894231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-05-26T21:03:03.894451Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-26T21:03:03.898639Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.823675ms"} {"level":"info","ts":"2026-05-26T21:03:03.907688Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-05-26T21:03:03.907797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T21:03:03.907843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-05-26T21:03:03.907871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T21:03:03.907889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-05-26T21:03:03.907952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-05-26T21:03:03.917408Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T21:03:03.919586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T21:03:03.920598Z","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-26T21:03:03.923217Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T21:03:03.923414Z","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-26T21:03:03.923521Z","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-26T21:03:03.923533Z","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-26T21:03:03.923754Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T21:03:03.924327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-05-26T21:03:03.924579Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-26T21:03:03.928042Z","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-26T21:03:03.928256Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T21:03:03.928291Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T21:03:03.929962Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-26T21:03:03.929986Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-26T21:03:04.208196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T21:03:04.208281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T21:03:04.208636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-05-26T21:03:04.208743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-05-26T21:03:04.208803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-26T21:03:04.208899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-05-26T21:03:04.208987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-26T21:03:04.210078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T21:03:04.210253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:03:04.210743Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:03:04.2109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:03:04.213695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T21:03:04.213703Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T21:03:04.213805Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T21:03:04.214Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:03:04.214103Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:03:04.214141Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:03:04.216923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-05-26T21:03:06.621979Z","caller":"traceutil/trace.go:171","msg":"trace[111514523] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:71; }","duration":"157.334708ms","start":"2026-05-26T21:03:06.464612Z","end":"2026-05-26T21:03:06.621946Z","steps":["trace[111514523] 'read index received' (duration: 63.288535ms)","trace[111514523] 'applied index is now lower than readState.Index' (duration: 94.044903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:06.622342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.373803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:06.622538Z","caller":"traceutil/trace.go:171","msg":"trace[1929800905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:68; }","duration":"130.649536ms","start":"2026-05-26T21:03:06.491866Z","end":"2026-05-26T21:03:06.622516Z","steps":["trace[1929800905] 'agreement among raft nodes before linearized reading' (duration: 130.326992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:06.6224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.755802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:06.62281Z","caller":"traceutil/trace.go:171","msg":"trace[163478358] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:68; }","duration":"158.182358ms","start":"2026-05-26T21:03:06.464596Z","end":"2026-05-26T21:03:06.622779Z","steps":["trace[163478358] 'agreement among raft nodes before linearized reading' (duration: 157.529971ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:06.622079Z","caller":"traceutil/trace.go:171","msg":"trace[31726668] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"158.569752ms","start":"2026-05-26T21:03:06.463469Z","end":"2026-05-26T21:03:06.622039Z","steps":["trace[31726668] 'process raft request' (duration: 64.407048ms)","trace[31726668] 'compare' (duration: 93.752929ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:06.830038Z","caller":"traceutil/trace.go:171","msg":"trace[1412123103] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"113.484782ms","start":"2026-05-26T21:03:06.716522Z","end":"2026-05-26T21:03:06.830007Z","steps":["trace[1412123103] 'process raft request' (duration: 92.442034ms)","trace[1412123103] 'compare' (duration: 20.907226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:07.124857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.363406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T21:03:07.125293Z","caller":"traceutil/trace.go:171","msg":"trace[203700265] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"202.913104ms","start":"2026-05-26T21:03:06.922351Z","end":"2026-05-26T21:03:07.125264Z","steps":["trace[203700265] 'process raft request' (duration: 31.570767ms)","trace[203700265] 'compare' (duration: 170.200824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:07.125607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.245505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:07.125655Z","caller":"traceutil/trace.go:171","msg":"trace[1771785670] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:83; }","duration":"101.333936ms","start":"2026-05-26T21:03:07.024305Z","end":"2026-05-26T21:03:07.125639Z","steps":["trace[1771785670] 'agreement among raft nodes before linearized reading' (duration: 101.238265ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:07.125963Z","caller":"traceutil/trace.go:171","msg":"trace[862843269] linearizableReadLoop","detail":"{readStateIndex:88; appliedIndex:86; }","duration":"101.022173ms","start":"2026-05-26T21:03:07.024337Z","end":"2026-05-26T21:03:07.12536Z","steps":["trace[862843269] 'read index received' (duration: 44.136295ms)","trace[862843269] 'applied index is now lower than readState.Index' (duration: 56.884878ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:07.426283Z","caller":"traceutil/trace.go:171","msg":"trace[632048138] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:100; }","duration":"109.308016ms","start":"2026-05-26T21:03:07.316949Z","end":"2026-05-26T21:03:07.426257Z","steps":["trace[632048138] 'read index received' (duration: 102.783043ms)","trace[632048138] 'applied index is now lower than readState.Index' (duration: 6.523953ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:07.426326Z","caller":"traceutil/trace.go:171","msg":"trace[440226857] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"122.916858ms","start":"2026-05-26T21:03:07.303376Z","end":"2026-05-26T21:03:07.426293Z","steps":["trace[440226857] 'process raft request' (duration: 116.349205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:07.426511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.510488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:07.426766Z","caller":"traceutil/trace.go:171","msg":"trace[2127291071] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:0; response_revision:96; }","duration":"109.855893ms","start":"2026-05-26T21:03:07.316899Z","end":"2026-05-26T21:03:07.426755Z","steps":["trace[2127291071] 'agreement among raft nodes before linearized reading' (duration: 109.485928ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:07.466803Z","caller":"traceutil/trace.go:171","msg":"trace[29826352] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"149.019592ms","start":"2026-05-26T21:03:07.317765Z","end":"2026-05-26T21:03:07.466785Z","steps":["trace[29826352] 'process raft request' (duration: 148.8847ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:08.238294Z","caller":"traceutil/trace.go:171","msg":"trace[1854559361] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"271.82653ms","start":"2026-05-26T21:03:07.966434Z","end":"2026-05-26T21:03:08.238261Z","steps":["trace[1854559361] 'process raft request' (duration: 182.382374ms)","trace[1854559361] 'compare' (duration: 89.270774ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:08.238279Z","caller":"traceutil/trace.go:171","msg":"trace[1565296486] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"271.085391ms","start":"2026-05-26T21:03:07.967158Z","end":"2026-05-26T21:03:08.238243Z","steps":["trace[1565296486] 'read index received' (duration: 181.633776ms)","trace[1565296486] 'applied index is now lower than readState.Index' (duration: 89.450285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:08.2387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.480146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:08.238804Z","caller":"traceutil/trace.go:171","msg":"trace[282715955] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:0; response_revision:115; }","duration":"271.656157ms","start":"2026-05-26T21:03:07.967121Z","end":"2026-05-26T21:03:08.238777Z","steps":["trace[282715955] 'agreement among raft nodes before linearized reading' (duration: 271.236493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:10.562021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002956256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-26T21:03:10.562149Z","caller":"traceutil/trace.go:171","msg":"trace[667342298] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.003147229s","start":"2026-05-26T21:03:08.558981Z","end":"2026-05-26T21:03:10.562128Z","steps":["trace[667342298] 'agreement among raft nodes before linearized reading' (duration: 2.002988487s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:10.562203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:08.55895Z","time spent":"2.003243029s","remote":"127.0.0.1:35938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/26 21:03:10 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-26T21:03:11.525635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.903421814s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-26T21:03:11.525768Z","caller":"traceutil/trace.go:171","msg":"trace[1226933827] linearizableReadLoop","detail":"{readStateIndex:124; appliedIndex:123; }","duration":"3.236372551s","start":"2026-05-26T21:03:08.289371Z","end":"2026-05-26T21:03:11.525744Z","steps":["trace[1226933827] 'read index received' (duration: 332.442481ms)","trace[1226933827] 'applied index is now lower than readState.Index' (duration: 2.903927829s)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:11.525882Z","caller":"traceutil/trace.go:171","msg":"trace[1627144213] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"3.23721937s","start":"2026-05-26T21:03:08.288646Z","end":"2026-05-26T21:03:11.525865Z","steps":["trace[1627144213] 'process raft request' (duration: 333.25381ms)","trace[1627144213] 'compare' (duration: 2.90303474s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:11.525985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:08.288633Z","time spent":"3.23728835s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:03:11.526339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.608018246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:11.526424Z","caller":"traceutil/trace.go:171","msg":"trace[497929709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:119; }","duration":"2.608149347s","start":"2026-05-26T21:03:08.918253Z","end":"2026-05-26T21:03:11.526402Z","steps":["trace[497929709] 'agreement among raft nodes before linearized reading' (duration: 2.607968975s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:11.52641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.999156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-26T21:03:11.526468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:08.918231Z","time spent":"2.608228427s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T21:03:11.526482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534946535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:11.526562Z","caller":"traceutil/trace.go:171","msg":"trace[1347111983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:119; }","duration":"1.535061796s","start":"2026-05-26T21:03:09.991485Z","end":"2026-05-26T21:03:11.526547Z","steps":["trace[1347111983] 'agreement among raft nodes before linearized reading' (duration: 1.534956345s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:11.526569Z","caller":"traceutil/trace.go:171","msg":"trace[523378702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:119; }","duration":"964.135887ms","start":"2026-05-26T21:03:10.562349Z","end":"2026-05-26T21:03:11.526485Z","steps":["trace[523378702] 'agreement among raft nodes before linearized reading' (duration: 963.952695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:11.526612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:09.991466Z","time spent":"1.535134637s","remote":"127.0.0.1:35938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T21:03:11.526658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:10.562324Z","time spent":"964.320188ms","remote":"127.0.0.1:35932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T21:03:11.526697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706464921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:11.526744Z","caller":"traceutil/trace.go:171","msg":"trace[1600238513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:119; }","duration":"1.706549102s","start":"2026-05-26T21:03:09.820184Z","end":"2026-05-26T21:03:11.526733Z","steps":["trace[1600238513] 'agreement among raft nodes before linearized reading' (duration: 1.706481022s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:11.526779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:09.820155Z","time spent":"1.706616513s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T21:03:11.526392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.237012637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:11.52708Z","caller":"traceutil/trace.go:171","msg":"trace[1163283607] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:0; response_revision:119; }","duration":"3.237736316s","start":"2026-05-26T21:03:08.289317Z","end":"2026-05-26T21:03:11.527054Z","steps":["trace[1163283607] 'agreement among raft nodes before linearized reading' (duration: 3.236992728s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:11.527209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:08.289302Z","time spent":"3.237889347s","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T21:03:12.059217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406183760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:03:12.559856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406183760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:03:13.060881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406183760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:03:13.269282Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.738141543s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T21:03:13.293001Z","caller":"traceutil/trace.go:171","msg":"trace[588790635] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"1.761938209s","start":"2026-05-26T21:03:11.531039Z","end":"2026-05-26T21:03:13.292977Z","steps":["trace[588790635] 'process raft request' (duration: 1.738642959s)","trace[588790635] 'compare' (duration: 23.139759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:13.293256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:11.531017Z","time spent":"1.762148291s","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:03:13.558755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000027348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-26T21:03:13.558879Z","caller":"traceutil/trace.go:171","msg":"trace[781356064] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000177429s","start":"2026-05-26T21:03:11.558682Z","end":"2026-05-26T21:03:13.55886Z","steps":["trace[781356064] 'agreement among raft nodes before linearized reading' (duration: 2.000023238s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:13.559083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:11.558646Z","time spent":"2.000420522s","remote":"127.0.0.1:35938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/26 21:03:13 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-26T21:03:13.983948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.832572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T21:03:13.9841Z","caller":"traceutil/trace.go:171","msg":"trace[2023721868] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:124; }","duration":"2.425392506s","start":"2026-05-26T21:03:11.558695Z","end":"2026-05-26T21:03:13.984088Z","steps":["trace[2023721868] 'read index received' (duration: 1.710802345s)","trace[2023721868] 'applied index is now lower than readState.Index' (duration: 714.589621ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:13.984164Z","caller":"traceutil/trace.go:171","msg":"trace[1743610203] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"2.451499741s","start":"2026-05-26T21:03:11.532659Z","end":"2026-05-26T21:03:13.984159Z","steps":["trace[1743610203] 'process raft request' (duration: 2.4513845s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:13.984175Z","caller":"traceutil/trace.go:171","msg":"trace[1901517801] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"2.451835335s","start":"2026-05-26T21:03:11.532317Z","end":"2026-05-26T21:03:13.984152Z","steps":["trace[1901517801] 'process raft request' (duration: 2.451697063s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:13.984304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.225867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:13.984329Z","caller":"traceutil/trace.go:171","msg":"trace[587919116] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:0; response_revision:123; }","duration":"688.279149ms","start":"2026-05-26T21:03:13.296042Z","end":"2026-05-26T21:03:13.984321Z","steps":["trace[587919116] 'agreement among raft nodes before linearized reading' (duration: 688.220828ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:13.984271Z","caller":"traceutil/trace.go:171","msg":"trace[1527276873] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"2.452570623s","start":"2026-05-26T21:03:11.531664Z","end":"2026-05-26T21:03:13.984235Z","steps":["trace[1527276873] 'process raft request' (duration: 1.883403577s)","trace[1527276873] 'compare' (duration: 568.688811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:13.984399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.221596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:13.984423Z","caller":"traceutil/trace.go:171","msg":"trace[341843969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:123; }","duration":"425.272888ms","start":"2026-05-26T21:03:13.559145Z","end":"2026-05-26T21:03:13.984418Z","steps":["trace[341843969] 'agreement among raft nodes before linearized reading' (duration: 425.237557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:13.984358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.678706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-26T21:03:13.984447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:13.559124Z","time spent":"425.319018ms","remote":"127.0.0.1:35932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T21:03:13.98441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099116609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:13.984489Z","caller":"traceutil/trace.go:171","msg":"trace[501242803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:123; }","duration":"992.857287ms","start":"2026-05-26T21:03:12.991604Z","end":"2026-05-26T21:03:13.984462Z","steps":["trace[501242803] 'agreement among raft nodes before linearized reading' (duration: 992.666945ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:13.984517Z","caller":"traceutil/trace.go:171","msg":"trace[1642760867] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:123; }","duration":"1.099307291s","start":"2026-05-26T21:03:12.885189Z","end":"2026-05-26T21:03:13.984496Z","steps":["trace[1642760867] 'agreement among raft nodes before linearized reading' (duration: 1.099055868s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:13.984511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:11.531641Z","time spent":"2.452764715s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:03:13.984571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:12.991586Z","time spent":"992.971529ms","remote":"127.0.0.1:35938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T21:03:13.984569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:12.885169Z","time spent":"1.099385642s","remote":"127.0.0.1:36066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-05-26T21:03:13.98435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:13.296026Z","time spent":"688.317929ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" "} {"level":"warn","ts":"2026-05-26T21:03:13.984313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:11.532305Z","time spent":"2.451971786s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:03:13.984223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:11.532649Z","time spent":"2.451539691s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:03:14.128834Z","caller":"traceutil/trace.go:171","msg":"trace[734857426] linearizableReadLoop","detail":"{readStateIndex:132; appliedIndex:128; }","duration":"139.979348ms","start":"2026-05-26T21:03:13.988807Z","end":"2026-05-26T21:03:14.128786Z","steps":["trace[734857426] 'read index received' (duration: 26.643766ms)","trace[734857426] 'applied index is now lower than readState.Index' (duration: 113.334562ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:14.129124Z","caller":"traceutil/trace.go:171","msg":"trace[928959837] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"140.531504ms","start":"2026-05-26T21:03:13.988576Z","end":"2026-05-26T21:03:14.129107Z","steps":["trace[928959837] 'process raft request' (duration: 140.17585ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:14.129218Z","caller":"traceutil/trace.go:171","msg":"trace[1576791272] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"141.145581ms","start":"2026-05-26T21:03:13.988Z","end":"2026-05-26T21:03:14.129146Z","steps":["trace[1576791272] 'process raft request' (duration: 140.713186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:14.129303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.392042ms","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-26T21:03:14.12943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.584064ms","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-26T21:03:14.129483Z","caller":"traceutil/trace.go:171","msg":"trace[525657730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:127; }","duration":"139.659875ms","start":"2026-05-26T21:03:13.989801Z","end":"2026-05-26T21:03:14.129461Z","steps":["trace[525657730] 'agreement among raft nodes before linearized reading' (duration: 139.596734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:14.129267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.442643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b3396456286e7e\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-26T21:03:14.129545Z","caller":"traceutil/trace.go:171","msg":"trace[56956204] range","detail":"{range_begin:/registry/events/default/instance.18b3396456286e7e; range_end:; response_count:1; response_revision:127; }","duration":"140.754167ms","start":"2026-05-26T21:03:13.988765Z","end":"2026-05-26T21:03:14.129519Z","steps":["trace[56956204] 'agreement among raft nodes before linearized reading' (duration: 140.398773ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:14.129541Z","caller":"traceutil/trace.go:171","msg":"trace[1876408476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:127; }","duration":"140.642595ms","start":"2026-05-26T21:03:13.988806Z","end":"2026-05-26T21:03:14.129448Z","steps":["trace[1876408476] 'agreement among raft nodes before linearized reading' (duration: 140.423143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:14.129639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.595203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T21:03:14.129762Z","caller":"traceutil/trace.go:171","msg":"trace[550946285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:127; }","duration":"137.810035ms","start":"2026-05-26T21:03:13.991903Z","end":"2026-05-26T21:03:14.129713Z","steps":["trace[550946285] 'agreement among raft nodes before linearized reading' (duration: 137.468191ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:14.130338Z","caller":"traceutil/trace.go:171","msg":"trace[1817156065] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"142.878129ms","start":"2026-05-26T21:03:13.98743Z","end":"2026-05-26T21:03:14.130308Z","steps":["trace[1817156065] 'process raft request' (duration: 135.647222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:26.822575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.549849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:03:26.822696Z","caller":"traceutil/trace.go:171","msg":"trace[1036619742] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"205.152998ms","start":"2026-05-26T21:03:26.617524Z","end":"2026-05-26T21:03:26.822677Z","steps":["trace[1036619742] 'process raft request' (duration: 103.419187ms)","trace[1036619742] 'compare' (duration: 101.421147ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:26.975403Z","caller":"traceutil/trace.go:171","msg":"trace[735097290] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"139.839863ms","start":"2026-05-26T21:03:26.835535Z","end":"2026-05-26T21:03:26.975375Z","steps":["trace[735097290] 'process raft request' (duration: 126.287877ms)","trace[735097290] 'compare' (duration: 13.379375ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:26.975539Z","caller":"traceutil/trace.go:171","msg":"trace[1283522614] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"106.036324ms","start":"2026-05-26T21:03:26.869479Z","end":"2026-05-26T21:03:26.975515Z","steps":["trace[1283522614] 'process raft request' (duration: 105.841082ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:27.467703Z","caller":"traceutil/trace.go:171","msg":"trace[476940849] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"247.137328ms","start":"2026-05-26T21:03:27.220538Z","end":"2026-05-26T21:03:27.467675Z","steps":["trace[476940849] 'read index received' (duration: 231.169799ms)","trace[476940849] 'applied index is now lower than readState.Index' (duration: 15.966249ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:27.467794Z","caller":"traceutil/trace.go:171","msg":"trace[890834552] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"435.490329ms","start":"2026-05-26T21:03:27.032269Z","end":"2026-05-26T21:03:27.467759Z","steps":["trace[890834552] 'process raft request' (duration: 419.439999ms)","trace[890834552] 'compare' (duration: 15.831028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:27.467873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.32436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:27.46792Z","caller":"traceutil/trace.go:171","msg":"trace[1178073107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"247.417961ms","start":"2026-05-26T21:03:27.220486Z","end":"2026-05-26T21:03:27.467904Z","steps":["trace[1178073107] 'agreement among raft nodes before linearized reading' (duration: 247.33683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:27.467966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:27.03225Z","time spent":"435.62052ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:03:27.467965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.950358ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:27.468101Z","caller":"traceutil/trace.go:171","msg":"trace[452733857] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:416; }","duration":"129.11201ms","start":"2026-05-26T21:03:27.33897Z","end":"2026-05-26T21:03:27.468082Z","steps":["trace[452733857] 'agreement among raft nodes before linearized reading' (duration: 128.919588ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:41.913829Z","caller":"traceutil/trace.go:171","msg":"trace[1224427542] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"138.917135ms","start":"2026-05-26T21:03:41.774893Z","end":"2026-05-26T21:03:41.91381Z","steps":["trace[1224427542] 'process raft request' (duration: 138.807649ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:42.920583Z","caller":"traceutil/trace.go:171","msg":"trace[1407818347] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"163.509012ms","start":"2026-05-26T21:03:42.757044Z","end":"2026-05-26T21:03:42.920553Z","steps":["trace[1407818347] 'process raft request' (duration: 163.351044ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:46.677347Z","caller":"traceutil/trace.go:171","msg":"trace[1708555554] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"147.083915ms","start":"2026-05-26T21:03:46.530244Z","end":"2026-05-26T21:03:46.677328Z","steps":["trace[1708555554] 'process raft request' (duration: 146.97462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:46.846052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.490182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:03:46.846211Z","caller":"traceutil/trace.go:171","msg":"trace[166299344] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:511; }","duration":"242.480655ms","start":"2026-05-26T21:03:46.603714Z","end":"2026-05-26T21:03:46.846195Z","steps":["trace[166299344] 'read index received' (duration: 73.580554ms)","trace[166299344] 'applied index is now lower than readState.Index' (duration: 168.841018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:46.84629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.570069ms","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-26T21:03:46.846326Z","caller":"traceutil/trace.go:171","msg":"trace[998924615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"242.642873ms","start":"2026-05-26T21:03:46.603671Z","end":"2026-05-26T21:03:46.846314Z","steps":["trace[998924615] 'agreement among raft nodes before linearized reading' (duration: 242.575769ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:46.846353Z","caller":"traceutil/trace.go:171","msg":"trace[871028485] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"243.091596ms","start":"2026-05-26T21:03:46.603221Z","end":"2026-05-26T21:03:46.846313Z","steps":["trace[871028485] 'process raft request' (duration: 113.252426ms)","trace[871028485] 'compare' (duration: 129.344335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:48.71847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406184539,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T21:03:48.764014Z","caller":"traceutil/trace.go:171","msg":"trace[1812238848] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"752.575098ms","start":"2026-05-26T21:03:48.011426Z","end":"2026-05-26T21:03:48.764001Z","steps":["trace[1812238848] 'process raft request' (duration: 752.340876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:48.76411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.566183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:48.763997Z","caller":"traceutil/trace.go:171","msg":"trace[1433436780] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"546.429567ms","start":"2026-05-26T21:03:48.217538Z","end":"2026-05-26T21:03:48.763967Z","steps":["trace[1433436780] 'read index received' (duration: 546.133903ms)","trace[1433436780] 'applied index is now lower than readState.Index' (duration: 294.734µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:48.764147Z","caller":"traceutil/trace.go:171","msg":"trace[1668028906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"546.632677ms","start":"2026-05-26T21:03:48.217508Z","end":"2026-05-26T21:03:48.76414Z","steps":["trace[1668028906] 'agreement among raft nodes before linearized reading' (duration: 546.544562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:48.764198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:48.217466Z","time spent":"546.723532ms","remote":"127.0.0.1:35938","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-26T21:03:48.764121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:48.011399Z","time spent":"752.666883ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:03:50.90978Z","caller":"traceutil/trace.go:171","msg":"trace[2137285449] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"225.54858ms","start":"2026-05-26T21:03:50.684176Z","end":"2026-05-26T21:03:50.909725Z","steps":["trace[2137285449] 'process raft request' (duration: 167.193058ms)","trace[2137285449] 'compare' (duration: 58.071968ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:50.91619Z","caller":"traceutil/trace.go:171","msg":"trace[1413100980] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"219.356342ms","start":"2026-05-26T21:03:50.696796Z","end":"2026-05-26T21:03:50.916152Z","steps":["trace[1413100980] 'process raft request' (duration: 218.855168ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916272Z","caller":"traceutil/trace.go:171","msg":"trace[1364974260] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"209.003145ms","start":"2026-05-26T21:03:50.707246Z","end":"2026-05-26T21:03:50.91625Z","steps":["trace[1364974260] 'process raft request' (duration: 208.600336ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916284Z","caller":"traceutil/trace.go:171","msg":"trace[986292449] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"218.990245ms","start":"2026-05-26T21:03:50.697273Z","end":"2026-05-26T21:03:50.916263Z","steps":["trace[986292449] 'process raft request' (duration: 218.411757ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916287Z","caller":"traceutil/trace.go:171","msg":"trace[985931533] transaction","detail":"{read_only:false; number_of_response:1; response_revision:541; }","duration":"231.970898ms","start":"2026-05-26T21:03:50.684279Z","end":"2026-05-26T21:03:50.91625Z","steps":["trace[985931533] 'process raft request' (duration: 231.213612ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916414Z","caller":"traceutil/trace.go:171","msg":"trace[1822047861] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"218.91545ms","start":"2026-05-26T21:03:50.697488Z","end":"2026-05-26T21:03:50.916404Z","steps":["trace[1822047861] 'process raft request' (duration: 218.227817ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916556Z","caller":"traceutil/trace.go:171","msg":"trace[2006128841] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"215.15168ms","start":"2026-05-26T21:03:50.701391Z","end":"2026-05-26T21:03:50.916543Z","steps":["trace[2006128841] 'process raft request' (duration: 214.409714ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.91658Z","caller":"traceutil/trace.go:171","msg":"trace[1528186457] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"228.237788ms","start":"2026-05-26T21:03:50.688332Z","end":"2026-05-26T21:03:50.91657Z","steps":["trace[1528186457] 'process raft request' (duration: 227.290003ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916194Z","caller":"traceutil/trace.go:171","msg":"trace[211220474] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:561; }","duration":"148.883259ms","start":"2026-05-26T21:03:50.767294Z","end":"2026-05-26T21:03:50.916177Z","steps":["trace[211220474] 'read index received' (duration: 84.184482ms)","trace[211220474] 'applied index is now lower than readState.Index' (duration: 64.698056ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:50.916629Z","caller":"traceutil/trace.go:171","msg":"trace[1534331874] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"164.409734ms","start":"2026-05-26T21:03:50.752115Z","end":"2026-05-26T21:03:50.916525Z","steps":["trace[1534331874] 'process raft request' (duration: 163.796445ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916762Z","caller":"traceutil/trace.go:171","msg":"trace[1322371968] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"232.464352ms","start":"2026-05-26T21:03:50.684279Z","end":"2026-05-26T21:03:50.916743Z","steps":["trace[1322371968] 'process raft request' (duration: 231.115857ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.916795Z","caller":"traceutil/trace.go:171","msg":"trace[1235511006] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"203.05785ms","start":"2026-05-26T21:03:50.713728Z","end":"2026-05-26T21:03:50.916786Z","steps":["trace[1235511006] 'process raft request' (duration: 202.146206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:50.917039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.7369ms","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-05-26T21:03:50.917084Z","caller":"traceutil/trace.go:171","msg":"trace[2137861605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"149.828594ms","start":"2026-05-26T21:03:50.767245Z","end":"2026-05-26T21:03:50.917073Z","steps":["trace[2137861605] 'agreement among raft nodes before linearized reading' (duration: 148.984863ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:50.917581Z","caller":"traceutil/trace.go:171","msg":"trace[1407568175] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"227.977186ms","start":"2026-05-26T21:03:50.68809Z","end":"2026-05-26T21:03:50.916067Z","steps":["trace[1407568175] 'process raft request' (duration: 227.493953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:50.925621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.077588ms","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-26T21:03:50.925694Z","caller":"traceutil/trace.go:171","msg":"trace[1106274331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"154.187623ms","start":"2026-05-26T21:03:50.771491Z","end":"2026-05-26T21:03:50.925678Z","steps":["trace[1106274331] 'agreement among raft nodes before linearized reading' (duration: 154.070397ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:51.190622Z","caller":"traceutil/trace.go:171","msg":"trace[1694651506] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"180.012058ms","start":"2026-05-26T21:03:51.01056Z","end":"2026-05-26T21:03:51.190572Z","steps":["trace[1694651506] 'process raft request' (duration: 166.946518ms)","trace[1694651506] 'compare' (duration: 12.581507ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:51.190661Z","caller":"traceutil/trace.go:171","msg":"trace[314911099] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"175.387458ms","start":"2026-05-26T21:03:51.015235Z","end":"2026-05-26T21:03:51.190622Z","steps":["trace[314911099] 'process raft request' (duration: 174.981489ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:51.190722Z","caller":"traceutil/trace.go:171","msg":"trace[1207344973] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"175.311315ms","start":"2026-05-26T21:03:51.015389Z","end":"2026-05-26T21:03:51.1907Z","steps":["trace[1207344973] 'process raft request' (duration: 174.868444ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:51.19267Z","caller":"traceutil/trace.go:171","msg":"trace[2130276226] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"176.540133ms","start":"2026-05-26T21:03:51.016103Z","end":"2026-05-26T21:03:51.192643Z","steps":["trace[2130276226] 'process raft request' (duration: 174.192942ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:51.193582Z","caller":"traceutil/trace.go:171","msg":"trace[1112381235] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"176.154824ms","start":"2026-05-26T21:03:51.017394Z","end":"2026-05-26T21:03:51.193548Z","steps":["trace[1112381235] 'process raft request' (duration: 174.050254ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:51.195275Z","caller":"traceutil/trace.go:171","msg":"trace[485707508] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"173.290259ms","start":"2026-05-26T21:03:51.021925Z","end":"2026-05-26T21:03:51.195216Z","steps":["trace[485707508] 'process raft request' (duration: 169.730719ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:51.197474Z","caller":"traceutil/trace.go:171","msg":"trace[1652988544] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"143.314296ms","start":"2026-05-26T21:03:51.054095Z","end":"2026-05-26T21:03:51.197409Z","steps":["trace[1652988544] 'process raft request' (duration: 139.044803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:51.881676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406184658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:03:52.382484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406184658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:03:52.417498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131668872s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T21:03:52.424192Z","caller":"traceutil/trace.go:171","msg":"trace[1857848384] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.138767506s","start":"2026-05-26T21:03:51.285399Z","end":"2026-05-26T21:03:52.424167Z","steps":["trace[1857848384] 'process raft request' (duration: 1.132276391s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.424405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.28538Z","time spent":"1.138893272s","remote":"127.0.0.1:35984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:03:52.426388Z","caller":"traceutil/trace.go:171","msg":"trace[456679930] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"1.045130559s","start":"2026-05-26T21:03:51.381232Z","end":"2026-05-26T21:03:52.426362Z","steps":["trace[456679930] 'read index received' (duration: 1.036497283s)","trace[456679930] 'applied index is now lower than readState.Index' (duration: 8.632046ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:52.426536Z","caller":"traceutil/trace.go:171","msg":"trace[1462299502] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.124459315s","start":"2026-05-26T21:03:51.302058Z","end":"2026-05-26T21:03:52.426517Z","steps":["trace[1462299502] 'process raft request' (duration: 1.124162771s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.42667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.302033Z","time spent":"1.12457413s","remote":"127.0.0.1:35984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:03:52.426761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045311817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.426804Z","caller":"traceutil/trace.go:171","msg":"trace[326332573] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:578; }","duration":"1.045379401s","start":"2026-05-26T21:03:51.381414Z","end":"2026-05-26T21:03:52.426793Z","steps":["trace[326332573] 'agreement among raft nodes before linearized reading' (duration: 1.045289237s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.426829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.381406Z","time spent":"1.045416532s","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-26T21:03:52.427018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02726252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:03:52.427029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026704953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427043Z","caller":"traceutil/trace.go:171","msg":"trace[1392722317] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:578; }","duration":"1.027297932s","start":"2026-05-26T21:03:51.399737Z","end":"2026-05-26T21:03:52.427035Z","steps":["trace[1392722317] 'agreement among raft nodes before linearized reading' (duration: 1.027262331s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.399733Z","time spent":"1.027328503s","remote":"127.0.0.1:49666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:03:52.427068Z","caller":"traceutil/trace.go:171","msg":"trace[1677072617] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:578; }","duration":"1.026745805s","start":"2026-05-26T21:03:51.400311Z","end":"2026-05-26T21:03:52.427056Z","steps":["trace[1677072617] 'agreement among raft nodes before linearized reading' (duration: 1.026685142s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.400293Z","time spent":"1.026798868s","remote":"127.0.0.1:49666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-26T21:03:52.427109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.110449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:03:52.427163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027566465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427183Z","caller":"traceutil/trace.go:171","msg":"trace[188531103] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:578; }","duration":"1.027614897s","start":"2026-05-26T21:03:51.399563Z","end":"2026-05-26T21:03:52.427177Z","steps":["trace[188531103] 'agreement among raft nodes before linearized reading' (duration: 1.027583895s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:52.427183Z","caller":"traceutil/trace.go:171","msg":"trace[792730438] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:578; }","duration":"997.223413ms","start":"2026-05-26T21:03:51.429941Z","end":"2026-05-26T21:03:52.427164Z","steps":["trace[792730438] 'agreement among raft nodes before linearized reading' (duration: 997.048515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.399546Z","time spent":"1.027651529s","remote":"127.0.0.1:49666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-26T21:03:52.427233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.429934Z","time spent":"997.283907ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-26T21:03:52.427277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045691645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427304Z","caller":"traceutil/trace.go:171","msg":"trace[1446305335] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:578; }","duration":"1.045729117s","start":"2026-05-26T21:03:51.38157Z","end":"2026-05-26T21:03:52.427299Z","steps":["trace[1446305335] 'agreement among raft nodes before linearized reading' (duration: 1.045693675s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.381565Z","time spent":"1.045758669s","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-26T21:03:52.427338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015831037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427368Z","caller":"traceutil/trace.go:171","msg":"trace[904786953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:578; }","duration":"1.015876859s","start":"2026-05-26T21:03:51.411482Z","end":"2026-05-26T21:03:52.427359Z","steps":["trace[904786953] 'agreement among raft nodes before linearized reading' (duration: 1.015837408s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.42741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.41147Z","time spent":"1.015928701s","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-26T21:03:52.427277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015730752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427446Z","caller":"traceutil/trace.go:171","msg":"trace[186414035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:578; }","duration":"1.015913291s","start":"2026-05-26T21:03:51.411526Z","end":"2026-05-26T21:03:52.427439Z","steps":["trace[186414035] 'agreement among raft nodes before linearized reading' (duration: 1.015733403s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.411521Z","time spent":"1.015945793s","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-26T21:03:52.427505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046267193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427539Z","caller":"traceutil/trace.go:171","msg":"trace[1802003860] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:578; }","duration":"1.046326785s","start":"2026-05-26T21:03:51.381203Z","end":"2026-05-26T21:03:52.42753Z","steps":["trace[1802003860] 'agreement among raft nodes before linearized reading' (duration: 1.046271903s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.381188Z","time spent":"1.046371477s","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:03:52.427709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.764758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.42774Z","caller":"traceutil/trace.go:171","msg":"trace[1769783502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"207.825821ms","start":"2026-05-26T21:03:52.219906Z","end":"2026-05-26T21:03:52.427732Z","steps":["trace[1769783502] 'agreement among raft nodes before linearized reading' (duration: 207.784959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.935547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427775Z","caller":"traceutil/trace.go:171","msg":"trace[600215182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:578; }","duration":"973.97835ms","start":"2026-05-26T21:03:51.45379Z","end":"2026-05-26T21:03:52.427769Z","steps":["trace[600215182] 'agreement among raft nodes before linearized reading' (duration: 973.939398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.453782Z","time spent":"974.010301ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-26T21:03:52.427827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.104468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:03:52.427715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.724838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:03:52.427875Z","caller":"traceutil/trace.go:171","msg":"trace[1878600355] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:578; }","duration":"973.166831ms","start":"2026-05-26T21:03:51.454697Z","end":"2026-05-26T21:03:52.427864Z","steps":["trace[1878600355] 'agreement among raft nodes before linearized reading' (duration: 973.093668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.73524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:03:52.42791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.454691Z","time spent":"973.209643ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"info","ts":"2026-05-26T21:03:52.42791Z","caller":"traceutil/trace.go:171","msg":"trace[1002581917] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:578; }","duration":"997.926347ms","start":"2026-05-26T21:03:51.42997Z","end":"2026-05-26T21:03:52.427896Z","steps":["trace[1002581917] 'agreement among raft nodes before linearized reading' (duration: 997.723767ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:52.427933Z","caller":"traceutil/trace.go:171","msg":"trace[74592282] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:578; }","duration":"996.776612ms","start":"2026-05-26T21:03:51.431148Z","end":"2026-05-26T21:03:52.427925Z","steps":["trace[74592282] 'agreement among raft nodes before linearized reading' (duration: 996.73656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.427958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.431142Z","time spent":"996.810723ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-26T21:03:52.427962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.429966Z","time spent":"997.980419ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:03:52.427239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015666759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:03:52.427756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.864104ms","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-26T21:03:52.428058Z","caller":"traceutil/trace.go:171","msg":"trace[739917435] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:578; }","duration":"974.236322ms","start":"2026-05-26T21:03:51.453816Z","end":"2026-05-26T21:03:52.428053Z","steps":["trace[739917435] 'agreement among raft nodes before linearized reading' (duration: 973.910207ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:52.428054Z","caller":"traceutil/trace.go:171","msg":"trace[1856395175] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:578; }","duration":"1.016494319s","start":"2026-05-26T21:03:51.41155Z","end":"2026-05-26T21:03:52.428044Z","steps":["trace[1856395175] 'agreement among raft nodes before linearized reading' (duration: 1.01566445s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:52.42808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.453813Z","time spent":"974.260463ms","remote":"127.0.0.1:49714","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-26T21:03:52.428123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:51.411545Z","time spent":"1.01653504s","remote":"127.0.0.1:49682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:03:55.030963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.862336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:03:55.031188Z","caller":"traceutil/trace.go:171","msg":"trace[1540184285] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"168.065938ms","start":"2026-05-26T21:03:54.8631Z","end":"2026-05-26T21:03:55.031166Z","steps":["trace[1540184285] 'read index received' (duration: 35.882578ms)","trace[1540184285] 'applied index is now lower than readState.Index' (duration: 132.18236ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:03:55.031211Z","caller":"traceutil/trace.go:171","msg":"trace[1785132797] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"206.647251ms","start":"2026-05-26T21:03:54.824537Z","end":"2026-05-26T21:03:55.031184Z","steps":["trace[1785132797] 'process raft request' (duration: 206.547736ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:55.031286Z","caller":"traceutil/trace.go:171","msg":"trace[1303480522] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"206.702263ms","start":"2026-05-26T21:03:54.824539Z","end":"2026-05-26T21:03:55.031241Z","steps":["trace[1303480522] 'process raft request' (duration: 74.491222ms)","trace[1303480522] 'compare' (duration: 131.690518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:03:55.031397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.286569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T21:03:55.031463Z","caller":"traceutil/trace.go:171","msg":"trace[1319380830] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:620; }","duration":"168.378343ms","start":"2026-05-26T21:03:54.863068Z","end":"2026-05-26T21:03:55.031446Z","steps":["trace[1319380830] 'agreement among raft nodes before linearized reading' (duration: 168.220946ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:03:57.5512Z","caller":"traceutil/trace.go:171","msg":"trace[242794863] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"108.763259ms","start":"2026-05-26T21:03:57.442405Z","end":"2026-05-26T21:03:57.551168Z","steps":["trace[242794863] 'process raft request' (duration: 101.619831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:03:59.720981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406184801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:04:00.222174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406184801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:04:00.343986Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.183275349s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-26T21:04:00.394762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247683831s","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-05-26T21:04:00.394857Z","caller":"traceutil/trace.go:171","msg":"trace[954396631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"1.247827267s","start":"2026-05-26T21:03:59.147002Z","end":"2026-05-26T21:04:00.394829Z","steps":["trace[954396631] 'range keys from in-memory index tree' (duration: 1.247527934s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:00.394908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.14698Z","time spent":"1.247914561s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T21:04:00.395003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244183578s","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-26T21:04:00.395084Z","caller":"traceutil/trace.go:171","msg":"trace[484049859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.244297013s","start":"2026-05-26T21:03:59.150766Z","end":"2026-05-26T21:04:00.395063Z","steps":["trace[484049859] 'range keys from in-memory index tree' (duration: 1.244029842s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:00.395081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244175148s","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-26T21:04:00.395131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.150755Z","time spent":"1.244364096s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T21:04:00.395183Z","caller":"traceutil/trace.go:171","msg":"trace[1277415799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.244321704s","start":"2026-05-26T21:03:59.15083Z","end":"2026-05-26T21:04:00.395152Z","steps":["trace[1277415799] 'range keys from in-memory index tree' (duration: 1.244051053s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:00.395228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.150801Z","time spent":"1.244417438s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T21:04:00.395433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243277369s","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-05-26T21:04:00.395476Z","caller":"traceutil/trace.go:171","msg":"trace[35513615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"1.243357152s","start":"2026-05-26T21:03:59.152106Z","end":"2026-05-26T21:04:00.395464Z","steps":["trace[35513615] 'range keys from in-memory index tree' (duration: 1.243061969s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:00.39553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.152087Z","time spent":"1.243428045s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T21:04:00.395567Z","caller":"traceutil/trace.go:171","msg":"trace[1480215849] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.236493763s","start":"2026-05-26T21:03:59.159057Z","end":"2026-05-26T21:04:00.395551Z","steps":["trace[1480215849] 'process raft request' (duration: 1.185384831s)","trace[1480215849] 'compare' (duration: 49.846118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:00.395669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.159037Z","time spent":"1.236567587s","remote":"127.0.0.1:36028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:01.186243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.099424ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-26T21:04:01.186363Z","caller":"traceutil/trace.go:171","msg":"trace[1885070016] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"1.966130231s","start":"2026-05-26T21:03:59.220211Z","end":"2026-05-26T21:04:01.186341Z","steps":["trace[1885070016] 'read index received' (duration: 1.12404537s)","trace[1885070016] 'applied index is now lower than readState.Index' (duration: 842.082961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:01.186513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966282977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:04:01.186645Z","caller":"traceutil/trace.go:171","msg":"trace[1216882243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.966431533s","start":"2026-05-26T21:03:59.220177Z","end":"2026-05-26T21:04:01.186608Z","steps":["trace[1216882243] 'agreement among raft nodes before linearized reading' (duration: 1.966272076s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:01.186739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.22016Z","time spent":"1.966561329s","remote":"127.0.0.1:35938","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-26T21:04:01.22771Z","caller":"traceutil/trace.go:171","msg":"trace[254203831] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"826.742661ms","start":"2026-05-26T21:04:00.40095Z","end":"2026-05-26T21:04:01.227693Z","steps":["trace[254203831] 'process raft request' (duration: 826.609305ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:01.227946Z","caller":"traceutil/trace.go:171","msg":"trace[1984391845] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"825.976838ms","start":"2026-05-26T21:04:00.401956Z","end":"2026-05-26T21:04:01.227933Z","steps":["trace[1984391845] 'process raft request' (duration: 825.661925ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:01.227938Z","caller":"traceutil/trace.go:171","msg":"trace[1397878972] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"779.050425ms","start":"2026-05-26T21:04:00.448868Z","end":"2026-05-26T21:04:01.227918Z","steps":["trace[1397878972] 'process raft request' (duration: 778.770733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:01.228002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:00.401936Z","time spent":"826.04115ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:04:01.228188Z","caller":"traceutil/trace.go:171","msg":"trace[167478240] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"826.439457ms","start":"2026-05-26T21:04:00.401735Z","end":"2026-05-26T21:04:01.228174Z","steps":["trace[167478240] 'process raft request' (duration: 825.861973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:01.22829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:00.448846Z","time spent":"779.244494ms","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:04:01.228328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:00.401707Z","time spent":"826.525881ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:01.228642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57914592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dmwzc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-26T21:04:01.228675Z","caller":"traceutil/trace.go:171","msg":"trace[948161703] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dmwzc; range_end:; response_count:1; response_revision:653; }","duration":"1.579178671s","start":"2026-05-26T21:03:59.649488Z","end":"2026-05-26T21:04:01.228667Z","steps":["trace[948161703] 'agreement among raft nodes before linearized reading' (duration: 1.579083917s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:01.228693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:03:59.649444Z","time spent":"1.579244594s","remote":"127.0.0.1:36106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-dmwzc\" "} {"level":"info","ts":"2026-05-26T21:04:01.2277Z","caller":"traceutil/trace.go:171","msg":"trace[1472673401] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"827.65976ms","start":"2026-05-26T21:04:00.400008Z","end":"2026-05-26T21:04:01.227668Z","steps":["trace[1472673401] 'process raft request' (duration: 827.407029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:01.228826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:00.400925Z","time spent":"826.838035ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:01.228882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:00.399986Z","time spent":"828.861611ms","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:02.327935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.999713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:04:02.328051Z","caller":"traceutil/trace.go:171","msg":"trace[290361115] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.082738658s","start":"2026-05-26T21:04:01.245289Z","end":"2026-05-26T21:04:02.328027Z","steps":["trace[290361115] 'process raft request' (duration: 980.039015ms)","trace[290361115] 'compare' (duration: 101.807975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:02.328136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:01.245256Z","time spent":"1.082837863s","remote":"127.0.0.1:36106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:04:02.358405Z","caller":"traceutil/trace.go:171","msg":"trace[493226323] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.080859515s","start":"2026-05-26T21:04:01.277512Z","end":"2026-05-26T21:04:02.358371Z","steps":["trace[493226323] 'process raft request' (duration: 1.080617164s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:02.358466Z","caller":"traceutil/trace.go:171","msg":"trace[313722115] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:680; }","duration":"138.859689ms","start":"2026-05-26T21:04:02.219579Z","end":"2026-05-26T21:04:02.358439Z","steps":["trace[313722115] 'read index received' (duration: 5.664519ms)","trace[313722115] 'applied index is now lower than readState.Index' (duration: 133.194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:02.358574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:01.277485Z","time spent":"1.0809917s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T21:04:02.358637Z","caller":"traceutil/trace.go:171","msg":"trace[393509418] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.081057573s","start":"2026-05-26T21:04:01.277565Z","end":"2026-05-26T21:04:02.358622Z","steps":["trace[393509418] 'process raft request' (duration: 1.080690838s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:02.358675Z","caller":"traceutil/trace.go:171","msg":"trace[1589007867] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.077672739s","start":"2026-05-26T21:04:01.280976Z","end":"2026-05-26T21:04:02.358648Z","steps":["trace[1589007867] 'process raft request' (duration: 1.077368876s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:02.358764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:01.277548Z","time spent":"1.081123456s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:02.35884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:01.280961Z","time spent":"1.077792294s","remote":"127.0.0.1:36106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:02.358927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.34536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:04:02.359084Z","caller":"traceutil/trace.go:171","msg":"trace[25496531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"139.515947ms","start":"2026-05-26T21:04:02.219535Z","end":"2026-05-26T21:04:02.35905Z","steps":["trace[25496531] 'agreement among raft nodes before linearized reading' (duration: 139.000295ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:02.359289Z","caller":"traceutil/trace.go:171","msg":"trace[133617701] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.080881876s","start":"2026-05-26T21:04:01.277662Z","end":"2026-05-26T21:04:02.358544Z","steps":["trace[133617701] 'process raft request' (duration: 1.080651416s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:02.35939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:01.277633Z","time spent":"1.081709661s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:04:05.136788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9172833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:04:05.136888Z","caller":"traceutil/trace.go:171","msg":"trace[1006486360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"1.917400885s","start":"2026-05-26T21:04:03.219464Z","end":"2026-05-26T21:04:05.136865Z","steps":["trace[1006486360] 'range keys from in-memory index tree' (duration: 1.917196257s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:05.136939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:03.219405Z","time spent":"1.91752266s","remote":"127.0.0.1:35938","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-26T21:04:05.13695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904169643s","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-26T21:04:05.137038Z","caller":"traceutil/trace.go:171","msg":"trace[1454171581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"1.904284808s","start":"2026-05-26T21:04:03.232729Z","end":"2026-05-26T21:04:05.137014Z","steps":["trace[1454171581] 'range keys from in-memory index tree' (duration: 1.904000235s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:05.137138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:03.232665Z","time spent":"1.904456454s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T21:04:05.137179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.823517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T21:04:05.137227Z","caller":"traceutil/trace.go:171","msg":"trace[2047120297] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:659; }","duration":"272.91451ms","start":"2026-05-26T21:04:04.864302Z","end":"2026-05-26T21:04:05.137216Z","steps":["trace[2047120297] 'range keys from in-memory index tree' (duration: 272.648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:05.137244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903756525s","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-05-26T21:04:05.137297Z","caller":"traceutil/trace.go:171","msg":"trace[1493143522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"1.903838599s","start":"2026-05-26T21:04:03.233445Z","end":"2026-05-26T21:04:05.137283Z","steps":["trace[1493143522] 'range keys from in-memory index tree' (duration: 1.90363264s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:05.137321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:03.233434Z","time spent":"1.90388086s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T21:04:05.137485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902296554s","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-05-26T21:04:05.137505Z","caller":"traceutil/trace.go:171","msg":"trace[1980579500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"1.902336546s","start":"2026-05-26T21:04:03.23516Z","end":"2026-05-26T21:04:05.137496Z","steps":["trace[1980579500] 'range keys from in-memory index tree' (duration: 1.90220264s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:05.137519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:03.235142Z","time spent":"1.902373457s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T21:04:05.1376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902229731s","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-26T21:04:05.137645Z","caller":"traceutil/trace.go:171","msg":"trace[1674153229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"1.902293843s","start":"2026-05-26T21:04:03.235338Z","end":"2026-05-26T21:04:05.137632Z","steps":["trace[1674153229] 'range keys from in-memory index tree' (duration: 1.902065924s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:05.137681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:03.235327Z","time spent":"1.902345806s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T21:04:06.844175Z","caller":"traceutil/trace.go:171","msg":"trace[1580430018] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"170.973061ms","start":"2026-05-26T21:04:06.673176Z","end":"2026-05-26T21:04:06.844149Z","steps":["trace[1580430018] 'process raft request' (duration: 170.838666ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:07.061307Z","caller":"traceutil/trace.go:171","msg":"trace[773627730] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"377.937808ms","start":"2026-05-26T21:04:06.68334Z","end":"2026-05-26T21:04:07.061278Z","steps":["trace[773627730] 'process raft request' (duration: 343.066821ms)","trace[773627730] 'compare' (duration: 34.748772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:07.061671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:06.683312Z","time spent":"378.103534ms","remote":"127.0.0.1:36106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:04:07.337295Z","caller":"traceutil/trace.go:171","msg":"trace[724321375] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"115.708468ms","start":"2026-05-26T21:04:07.221574Z","end":"2026-05-26T21:04:07.337282Z","steps":["trace[724321375] 'process raft request' (duration: 115.623605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:07.337444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.488169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:04:07.337497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.989014ms","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-05-26T21:04:07.337534Z","caller":"traceutil/trace.go:171","msg":"trace[741984460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"117.616244ms","start":"2026-05-26T21:04:07.219895Z","end":"2026-05-26T21:04:07.337512Z","steps":["trace[741984460] 'agreement among raft nodes before linearized reading' (duration: 117.426007ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:07.337564Z","caller":"traceutil/trace.go:171","msg":"trace[714053294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"183.078469ms","start":"2026-05-26T21:04:07.154471Z","end":"2026-05-26T21:04:07.337549Z","steps":["trace[714053294] 'agreement among raft nodes before linearized reading' (duration: 182.87207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:07.546128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.377548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:04:07.5464Z","caller":"traceutil/trace.go:171","msg":"trace[1449722154] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"205.684933ms","start":"2026-05-26T21:04:07.340697Z","end":"2026-05-26T21:04:07.546382Z","steps":["trace[1449722154] 'process raft request' (duration: 205.6198ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:07.546409Z","caller":"traceutil/trace.go:171","msg":"trace[349551701] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"209.073159ms","start":"2026-05-26T21:04:07.337308Z","end":"2026-05-26T21:04:07.546381Z","steps":["trace[349551701] 'read index received' (duration: 48.366648ms)","trace[349551701] 'applied index is now lower than readState.Index' (duration: 160.703531ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:04:07.546477Z","caller":"traceutil/trace.go:171","msg":"trace[1354871971] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"299.41085ms","start":"2026-05-26T21:04:07.247023Z","end":"2026-05-26T21:04:07.546434Z","steps":["trace[1354871971] 'process raft request' (duration: 138.637647ms)","trace[1354871971] 'compare' (duration: 160.241621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:07.54661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.905182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:04:07.54666Z","caller":"traceutil/trace.go:171","msg":"trace[389848132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"207.999215ms","start":"2026-05-26T21:04:07.338645Z","end":"2026-05-26T21:04:07.546644Z","steps":["trace[389848132] 'agreement among raft nodes before linearized reading' (duration: 207.910142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:07.546752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.530687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T21:04:07.547249Z","caller":"traceutil/trace.go:171","msg":"trace[1007897761] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:675; }","duration":"212.227585ms","start":"2026-05-26T21:04:07.334996Z","end":"2026-05-26T21:04:07.547224Z","steps":["trace[1007897761] 'agreement among raft nodes before linearized reading' (duration: 211.537668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:08.679384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.654173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:04:08.679497Z","caller":"traceutil/trace.go:171","msg":"trace[2039523629] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"531.837888ms","start":"2026-05-26T21:04:08.147643Z","end":"2026-05-26T21:04:08.679481Z","steps":["trace[2039523629] 'process raft request' (duration: 126.02537ms)","trace[2039523629] 'compare' (duration: 405.534637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:08.679557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:08.147616Z","time spent":"531.906671ms","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:04:12.012017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.938243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-26T21:04:12.012135Z","caller":"traceutil/trace.go:171","msg":"trace[1266179235] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:737; }","duration":"140.09537ms","start":"2026-05-26T21:04:11.872023Z","end":"2026-05-26T21:04:12.012118Z","steps":["trace[1266179235] 'range keys from in-memory index tree' (duration: 139.801248ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:19.014817Z","caller":"traceutil/trace.go:171","msg":"trace[1546625016] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"257.221536ms","start":"2026-05-26T21:04:18.757563Z","end":"2026-05-26T21:04:19.014785Z","steps":["trace[1546625016] 'process raft request' (duration: 256.934446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:24.122563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406185228,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:04:24.623282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726535406185228,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:04:24.904816Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.579361922s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T21:04:24.905324Z","caller":"traceutil/trace.go:171","msg":"trace[1933902530] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"1.579840399s","start":"2026-05-26T21:04:23.325338Z","end":"2026-05-26T21:04:24.905179Z","steps":["trace[1933902530] 'process raft request' (duration: 1.579667453s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:24.905466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:23.325204Z","time spent":"1.580190311s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:04:24.930706Z","caller":"traceutil/trace.go:171","msg":"trace[540897508] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"1.602177457s","start":"2026-05-26T21:04:23.328506Z","end":"2026-05-26T21:04:24.930683Z","steps":["trace[540897508] 'process raft request' (duration: 1.60198217s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:24.930842Z","caller":"traceutil/trace.go:171","msg":"trace[1562765412] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:855; }","duration":"1.308684318s","start":"2026-05-26T21:04:23.622122Z","end":"2026-05-26T21:04:24.930807Z","steps":["trace[1562765412] 'read index received' (duration: 1.282873081s)","trace[1562765412] 'applied index is now lower than readState.Index' (duration: 25.807117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:04:24.930868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:23.328485Z","time spent":"1.602307622s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:04:24.930953Z","caller":"traceutil/trace.go:171","msg":"trace[621135129] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.147189367s","start":"2026-05-26T21:04:23.783752Z","end":"2026-05-26T21:04:24.930941Z","steps":["trace[621135129] 'process raft request' (duration: 1.146874456s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:24.931051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:23.783732Z","time spent":"1.147264839s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:04:24.931081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308529282s","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-26T21:04:24.931114Z","caller":"traceutil/trace.go:171","msg":"trace[1989136496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:826; }","duration":"1.308576404s","start":"2026-05-26T21:04:23.622526Z","end":"2026-05-26T21:04:24.931103Z","steps":["trace[1989136496] 'agreement among raft nodes before linearized reading' (duration: 1.30845691s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:24.931144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:23.622518Z","time spent":"1.308619476s","remote":"127.0.0.1:36180","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-26T21:04:24.931236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.289432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:04:24.93126Z","caller":"traceutil/trace.go:171","msg":"trace[1491512151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:826; }","duration":"710.342013ms","start":"2026-05-26T21:04:24.220909Z","end":"2026-05-26T21:04:24.931251Z","steps":["trace[1491512151] 'agreement among raft nodes before linearized reading' (duration: 710.310082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:24.931286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:24.220885Z","time spent":"710.393375ms","remote":"127.0.0.1:35938","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-26T21:04:24.931446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125030796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T21:04:24.931439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309304439s","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-05-26T21:04:24.931477Z","caller":"traceutil/trace.go:171","msg":"trace[1836562639] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:826; }","duration":"1.125086398s","start":"2026-05-26T21:04:23.806381Z","end":"2026-05-26T21:04:24.931467Z","steps":["trace[1836562639] 'agreement among raft nodes before linearized reading' (duration: 1.125026846s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:04:24.931494Z","caller":"traceutil/trace.go:171","msg":"trace[1192814749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"1.309410253s","start":"2026-05-26T21:04:23.62207Z","end":"2026-05-26T21:04:24.931481Z","steps":["trace[1192814749] 'agreement among raft nodes before linearized reading' (duration: 1.309261738s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:24.931505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:23.806361Z","time spent":"1.125136709s","remote":"127.0.0.1:36432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:04:24.931525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:04:23.622052Z","time spent":"1.309466125s","remote":"127.0.0.1:36180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-26T21:04:39.167602Z","caller":"traceutil/trace.go:171","msg":"trace[1906767320] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"159.320153ms","start":"2026-05-26T21:04:39.008262Z","end":"2026-05-26T21:04:39.167582Z","steps":["trace[1906767320] 'read index received' (duration: 159.134037ms)","trace[1906767320] 'applied index is now lower than readState.Index' (duration: 183.466µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:04:39.167926Z","caller":"traceutil/trace.go:171","msg":"trace[1649357526] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"198.178022ms","start":"2026-05-26T21:04:38.96973Z","end":"2026-05-26T21:04:39.167908Z","steps":["trace[1649357526] 'process raft request' (duration: 197.743029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:39.168274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.999724ms","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-26T21:04:39.168329Z","caller":"traceutil/trace.go:171","msg":"trace[749487692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"160.062806ms","start":"2026-05-26T21:04:39.008251Z","end":"2026-05-26T21:04:39.168314Z","steps":["trace[749487692] 'agreement among raft nodes before linearized reading' (duration: 159.918791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:39.168941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.194871ms","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-05-26T21:04:39.169012Z","caller":"traceutil/trace.go:171","msg":"trace[588092468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"153.301884ms","start":"2026-05-26T21:04:39.015692Z","end":"2026-05-26T21:04:39.168994Z","steps":["trace[588092468] 'agreement among raft nodes before linearized reading' (duration: 152.648825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:04:39.169128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.770278ms","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-26T21:04:39.16919Z","caller":"traceutil/trace.go:171","msg":"trace[1136152176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"152.851411ms","start":"2026-05-26T21:04:39.016324Z","end":"2026-05-26T21:04:39.169175Z","steps":["trace[1136152176] 'agreement among raft nodes before linearized reading' (duration: 152.721967ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:05:25.651909Z","caller":"traceutil/trace.go:171","msg":"trace[588739442] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"206.961589ms","start":"2026-05-26T21:05:25.444929Z","end":"2026-05-26T21:05:25.651891Z","steps":["trace[588739442] 'process raft request' (duration: 203.82529ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:05:25.651909Z","caller":"traceutil/trace.go:171","msg":"trace[2093882811] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"202.86089ms","start":"2026-05-26T21:05:25.449027Z","end":"2026-05-26T21:05:25.651888Z","steps":["trace[2093882811] 'process raft request' (duration: 202.802038ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:05:25.651999Z","caller":"traceutil/trace.go:171","msg":"trace[1571883958] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"203.680297ms","start":"2026-05-26T21:05:25.448282Z","end":"2026-05-26T21:05:25.651962Z","steps":["trace[1571883958] 'process raft request' (duration: 203.518214ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:05:48.505281Z","caller":"traceutil/trace.go:171","msg":"trace[284548554] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"161.532228ms","start":"2026-05-26T21:05:48.343723Z","end":"2026-05-26T21:05:48.505256Z","steps":["trace[284548554] 'process raft request' (duration: 161.356454ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:06:14.776707Z","caller":"traceutil/trace.go:171","msg":"trace[196462538] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"172.812771ms","start":"2026-05-26T21:06:14.60387Z","end":"2026-05-26T21:06:14.776683Z","steps":["trace[196462538] 'process raft request' (duration: 172.667858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:06:15.341566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.819431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:06:15.341652Z","caller":"traceutil/trace.go:171","msg":"trace[1437673211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"121.962444ms","start":"2026-05-26T21:06:15.219669Z","end":"2026-05-26T21:06:15.341631Z","steps":["trace[1437673211] 'range keys from in-memory index tree' (duration: 121.726379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:06:15.341568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.441303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:06:15.341785Z","caller":"traceutil/trace.go:171","msg":"trace[2082643057] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1350; }","duration":"322.688678ms","start":"2026-05-26T21:06:15.019054Z","end":"2026-05-26T21:06:15.341743Z","steps":["trace[2082643057] 'count revisions from in-memory index tree' (duration: 322.272631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:06:15.341927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:06:15.019032Z","time spent":"322.859751ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:06:15.34274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.977871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T21:06:15.342927Z","caller":"traceutil/trace.go:171","msg":"trace[1032483229] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1350; }","duration":"472.155455ms","start":"2026-05-26T21:06:14.870665Z","end":"2026-05-26T21:06:15.342821Z","steps":["trace[1032483229] 'range keys from in-memory index tree' (duration: 471.104046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:06:15.343163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:06:14.870649Z","time spent":"472.43888ms","remote":"127.0.0.1:35964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-05-26T21:06:16.048101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.911044ms","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-05-26T21:06:16.048166Z","caller":"traceutil/trace.go:171","msg":"trace[1479102402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1352; }","duration":"108.025855ms","start":"2026-05-26T21:06:15.940126Z","end":"2026-05-26T21:06:16.048152Z","steps":["trace[1479102402] 'range keys from in-memory index tree' (duration: 107.73071ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:06:16.300709Z","caller":"traceutil/trace.go:171","msg":"trace[1859881147] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"146.58617ms","start":"2026-05-26T21:06:16.154098Z","end":"2026-05-26T21:06:16.300684Z","steps":["trace[1859881147] 'read index received' (duration: 139.561267ms)","trace[1859881147] 'applied index is now lower than readState.Index' (duration: 7.023783ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:06:16.300898Z","caller":"traceutil/trace.go:171","msg":"trace[1412534907] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"163.742562ms","start":"2026-05-26T21:06:16.137145Z","end":"2026-05-26T21:06:16.300887Z","steps":["trace[1412534907] 'process raft request' (duration: 156.583356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:06:16.30109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.982737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-26T21:06:16.301113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.128031ms","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-26T21:06:16.301346Z","caller":"traceutil/trace.go:171","msg":"trace[905360427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"147.282942ms","start":"2026-05-26T21:06:16.154041Z","end":"2026-05-26T21:06:16.301324Z","steps":["trace[905360427] 'agreement among raft nodes before linearized reading' (duration: 146.922406ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:06:16.301583Z","caller":"traceutil/trace.go:171","msg":"trace[2030114770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1354; }","duration":"142.400197ms","start":"2026-05-26T21:06:16.158932Z","end":"2026-05-26T21:06:16.301332Z","steps":["trace[2030114770] 'agreement among raft nodes before linearized reading' (duration: 142.080021ms)"],"step_count":1}