{"level":"warn","ts":"2026-05-31T20:38:33.253241Z","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-31T20:38:33.253358Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163: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.19.213.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163: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-31T20:38:33.253454Z","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-31T20:38:33.25347Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-05-31T20:38:33.253492Z","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-31T20:38:33.254378Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-05-31T20:38:33.254485Z","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.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163: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-31T20:38:33.259867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.561854ms"} {"level":"info","ts":"2026-05-31T20:38:33.267094Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-05-31T20:38:33.267244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-05-31T20:38:33.267393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-05-31T20:38:33.267415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-31T20:38:33.267433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-05-31T20:38:33.267538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-05-31T20:38:33.275171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-31T20:38:33.276706Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-31T20:38:33.277649Z","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-31T20:38:33.280162Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-31T20:38:33.280599Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-31T20:38:33.28085Z","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-31T20:38:33.280946Z","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-31T20:38:33.280976Z","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-31T20:38:33.281148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-05-31T20:38:33.281301Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-05-31T20:38:33.287965Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-05-31T20:38:33.288099Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-05-31T20:38:33.287824Z","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-31T20:38:33.288748Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-31T20:38:33.288806Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-31T20:38:33.668656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-05-31T20:38:33.668825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-31T20:38:33.669026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-05-31T20:38:33.66917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-05-31T20:38:33.66927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-05-31T20:38:33.669372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-05-31T20:38:33.669484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-05-31T20:38:33.671475Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T20:38:33.672756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T20:38:33.672755Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-05-31T20:38:33.673256Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T20:38:33.673462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T20:38:33.673565Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T20:38:33.673652Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T20:38:33.674322Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-31T20:38:33.674397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-31T20:38:33.676757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-31T20:38:33.678995Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"warn","ts":"2026-05-31T20:38:35.796903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196739836556151,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T20:38:36.042409Z","caller":"traceutil/trace.go:171","msg":"trace[981123803] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"758.716248ms","start":"2026-05-31T20:38:35.283659Z","end":"2026-05-31T20:38:36.042375Z","steps":["trace[981123803] 'process raft request' (duration: 758.45764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:36.043014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.283644Z","time spent":"758.847183ms","remote":"127.0.0.1:57738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:38:38.640572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99960578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-31T20:38:38.683748Z","caller":"traceutil/trace.go:171","msg":"trace[1747938034] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.042870687s","start":"2026-05-31T20:38:36.64084Z","end":"2026-05-31T20:38:38.683711Z","steps":["trace[1747938034] 'agreement among raft nodes before linearized reading' (duration: 1.999640331s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:38.683858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:36.640814Z","time spent":"2.043007461s","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/31 20:38:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-31T20:38:39.31968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.277636661s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T20:38:39.320488Z","caller":"traceutil/trace.go:171","msg":"trace[1414575643] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"3.962371975s","start":"2026-05-31T20:38:35.358079Z","end":"2026-05-31T20:38:39.320451Z","steps":["trace[1414575643] 'process raft request' (duration: 3.962128818s)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:38:39.320532Z","caller":"traceutil/trace.go:171","msg":"trace[1629562811] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"3.962309723s","start":"2026-05-31T20:38:35.358172Z","end":"2026-05-31T20:38:39.320482Z","steps":["trace[1629562811] 'process raft request' (duration: 3.962136518s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.320653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.358053Z","time spent":"3.962511299s","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:39.320645Z","caller":"traceutil/trace.go:171","msg":"trace[230179190] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"3.966534905s","start":"2026-05-31T20:38:35.354069Z","end":"2026-05-31T20:38:39.320603Z","steps":["trace[230179190] 'process raft request' (duration: 3.966082832s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.320811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.358162Z","time spent":"3.962518339s","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:38:39.320836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.354048Z","time spent":"3.966696152s","remote":"127.0.0.1:58116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:39.320749Z","caller":"traceutil/trace.go:171","msg":"trace[2006894971] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"4.033574161s","start":"2026-05-31T20:38:35.287038Z","end":"2026-05-31T20:38:39.320612Z","steps":["trace[2006894971] 'process raft request' (duration: 4.032940311s)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:38:39.321091Z","caller":"traceutil/trace.go:171","msg":"trace[659338100] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"3.962842329s","start":"2026-05-31T20:38:35.358188Z","end":"2026-05-31T20:38:39.32103Z","steps":["trace[659338100] 'process raft request' (duration: 3.962152428s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.321116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.287021Z","time spent":"4.033895442s","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:38:39.321172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.358179Z","time spent":"3.962960193s","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:39.321181Z","caller":"traceutil/trace.go:171","msg":"trace[1814273880] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"3.962794059s","start":"2026-05-31T20:38:35.358119Z","end":"2026-05-31T20:38:39.320913Z","steps":["trace[1814273880] 'process raft request' (duration: 3.962130608s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.321374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.358108Z","time spent":"3.96318756s","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:39.321491Z","caller":"traceutil/trace.go:171","msg":"trace[1102225190] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"3.962964574s","start":"2026-05-31T20:38:35.358496Z","end":"2026-05-31T20:38:39.32146Z","steps":["trace[1102225190] 'process raft request' (duration: 3.96188453s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.321595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.358485Z","time spent":"3.963062217s","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:39.322106Z","caller":"traceutil/trace.go:171","msg":"trace[2024110730] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"4.025437704s","start":"2026-05-31T20:38:35.296639Z","end":"2026-05-31T20:38:39.322076Z","steps":["trace[2024110730] 'read index received' (duration: 745.49253ms)","trace[2024110730] 'applied index is now lower than readState.Index' (duration: 3.279943974s)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:38:39.322235Z","caller":"traceutil/trace.go:171","msg":"trace[1685755693] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"3.945567095s","start":"2026-05-31T20:38:35.376653Z","end":"2026-05-31T20:38:39.32222Z","steps":["trace[1685755693] 'process raft request' (duration: 3.94476282s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.3223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.376623Z","time spent":"3.945647738s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:39.322327Z","caller":"traceutil/trace.go:171","msg":"trace[151389502] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"3.948519038s","start":"2026-05-31T20:38:35.373796Z","end":"2026-05-31T20:38:39.322315Z","steps":["trace[151389502] 'process raft request' (duration: 3.946620818s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.322413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.37374Z","time spent":"3.948643502s","remote":"127.0.0.1:57860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:38:39.322555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.025906579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.322599Z","caller":"traceutil/trace.go:171","msg":"trace[983273389] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"4.025986091s","start":"2026-05-31T20:38:35.296602Z","end":"2026-05-31T20:38:39.322588Z","steps":["trace[983273389] 'agreement among raft nodes before linearized reading' (duration: 4.025864868s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.322637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.296582Z","time spent":"4.026047174s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-31T20:38:39.322776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.987162617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.322816Z","caller":"traceutil/trace.go:171","msg":"trace[825637311] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.987240521s","start":"2026-05-31T20:38:35.335565Z","end":"2026-05-31T20:38:39.322805Z","steps":["trace[825637311] 'agreement among raft nodes before linearized reading' (duration: 3.987169428s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.322869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.335538Z","time spent":"3.987325283s","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-31T20:38:39.322955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.899873964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.322994Z","caller":"traceutil/trace.go:171","msg":"trace[1043814228] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"3.899941906s","start":"2026-05-31T20:38:35.423042Z","end":"2026-05-31T20:38:39.322984Z","steps":["trace[1043814228] 'agreement among raft nodes before linearized reading' (duration: 3.899883755s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.323035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.912006687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-31T20:38:39.323036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.423025Z","time spent":"3.900003398s","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-05-31T20:38:39.323065Z","caller":"traceutil/trace.go:171","msg":"trace[1058654] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.912080058s","start":"2026-05-31T20:38:35.410975Z","end":"2026-05-31T20:38:39.323055Z","steps":["trace[1058654] 'agreement among raft nodes before linearized reading' (duration: 3.912039247s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.323112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.410905Z","time spent":"3.912201632s","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-31T20:38:39.323167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.069694478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.323201Z","caller":"traceutil/trace.go:171","msg":"trace[1375940043] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"3.069727299s","start":"2026-05-31T20:38:36.253465Z","end":"2026-05-31T20:38:39.323193Z","steps":["trace[1375940043] 'agreement among raft nodes before linearized reading' (duration: 3.069683617s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.323233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:36.25343Z","time spent":"3.069797121s","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-31T20:38:39.32328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.968952422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.323314Z","caller":"traceutil/trace.go:171","msg":"trace[1440536618] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.968984733s","start":"2026-05-31T20:38:35.354322Z","end":"2026-05-31T20:38:39.323307Z","steps":["trace[1440536618] 'agreement among raft nodes before linearized reading' (duration: 3.968937252s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.323419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.070018277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-31T20:38:39.32336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.354282Z","time spent":"3.969072416s","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-31T20:38:39.323225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.967875658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.323613Z","caller":"traceutil/trace.go:171","msg":"trace[2092992003] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"3.968299793s","start":"2026-05-31T20:38:35.355297Z","end":"2026-05-31T20:38:39.323597Z","steps":["trace[2092992003] 'agreement among raft nodes before linearized reading' (duration: 3.967886369s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.323673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.355265Z","time spent":"3.968394186s","remote":"127.0.0.1:57770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-31T20:38:39.323132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.076107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.323754Z","caller":"traceutil/trace.go:171","msg":"trace[1790551212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"182.844091ms","start":"2026-05-31T20:38:39.140893Z","end":"2026-05-31T20:38:39.323737Z","steps":["trace[1790551212] 'agreement among raft nodes before linearized reading' (duration: 182.216851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.32377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.971314198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.163\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:39.323465Z","caller":"traceutil/trace.go:171","msg":"trace[379905097] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"3.07011957s","start":"2026-05-31T20:38:36.253332Z","end":"2026-05-31T20:38:39.323452Z","steps":["trace[379905097] 'agreement among raft nodes before linearized reading' (duration: 3.070048828s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.323875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:36.253301Z","time spent":"3.070557005s","remote":"127.0.0.1:58032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-05-31T20:38:39.323802Z","caller":"traceutil/trace.go:171","msg":"trace[1408793914] range","detail":"{range_begin:/registry/masterleases/199.19.213.163; range_end:; response_count:0; response_revision:11; }","duration":"3.97138495s","start":"2026-05-31T20:38:35.352405Z","end":"2026-05-31T20:38:39.32379Z","steps":["trace[1408793914] 'agreement among raft nodes before linearized reading' (duration: 3.971130942s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:39.324546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:35.352374Z","time spent":"3.972158474s","remote":"127.0.0.1:57708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.163\" "} {"level":"warn","ts":"2026-05-31T20:38:39.833438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196739836556164,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T20:38:40.164787Z","caller":"traceutil/trace.go:171","msg":"trace[117466321] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"833.026732ms","start":"2026-05-31T20:38:39.331736Z","end":"2026-05-31T20:38:40.164763Z","steps":["trace[117466321] 'process raft request' (duration: 830.672508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.1649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.331716Z","time spent":"833.133504ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:40.172277Z","caller":"traceutil/trace.go:171","msg":"trace[1894513164] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"840.161207ms","start":"2026-05-31T20:38:39.3321Z","end":"2026-05-31T20:38:40.172262Z","steps":["trace[1894513164] 'process raft request' (duration: 840.041493ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:38:40.172395Z","caller":"traceutil/trace.go:171","msg":"trace[363924203] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"840.037422ms","start":"2026-05-31T20:38:39.332342Z","end":"2026-05-31T20:38:40.17238Z","steps":["trace[363924203] 'read index received' (duration: 830.170041ms)","trace[363924203] 'applied index is now lower than readState.Index' (duration: 9.866351ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:38:40.172401Z","caller":"traceutil/trace.go:171","msg":"trace[969048288] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"840.560339ms","start":"2026-05-31T20:38:39.331812Z","end":"2026-05-31T20:38:40.172373Z","steps":["trace[969048288] 'process raft request' (duration: 840.232499ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:38:40.172426Z","caller":"traceutil/trace.go:171","msg":"trace[1216467086] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"839.726952ms","start":"2026-05-31T20:38:39.332683Z","end":"2026-05-31T20:38:40.17241Z","steps":["trace[1216467086] 'process raft request' (duration: 839.547027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.172475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332053Z","time spent":"840.2697ms","remote":"127.0.0.1:58116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:40.172494Z","caller":"traceutil/trace.go:171","msg":"trace[1027723836] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"839.154554ms","start":"2026-05-31T20:38:39.333333Z","end":"2026-05-31T20:38:40.172487Z","steps":["trace[1027723836] 'process raft request' (duration: 838.918517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.17251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.331796Z","time spent":"840.660443ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:40.172545Z","caller":"traceutil/trace.go:171","msg":"trace[320963155] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"840.265019ms","start":"2026-05-31T20:38:39.332274Z","end":"2026-05-31T20:38:40.172539Z","steps":["trace[320963155] 'process raft request' (duration: 839.891558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.172553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.333317Z","time spent":"839.214396ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:38:40.172576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332244Z","time spent":"840.316141ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:40.172674Z","caller":"traceutil/trace.go:171","msg":"trace[63733542] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"840.094434ms","start":"2026-05-31T20:38:39.332572Z","end":"2026-05-31T20:38:40.172666Z","steps":["trace[63733542] 'process raft request' (duration: 839.6165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.17271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332559Z","time spent":"840.135736ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:38:40.172511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332665Z","time spent":"839.794855ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:38:40.172799Z","caller":"traceutil/trace.go:171","msg":"trace[763894951] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"840.190538ms","start":"2026-05-31T20:38:39.332598Z","end":"2026-05-31T20:38:40.172789Z","steps":["trace[763894951] 'process raft request' (duration: 839.615099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.172831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332584Z","time spent":"840.23393ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:38:40.172991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.634172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-05-31T20:38:40.172995Z","caller":"traceutil/trace.go:171","msg":"trace[1754861304] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"835.099517ms","start":"2026-05-31T20:38:39.337883Z","end":"2026-05-31T20:38:40.172983Z","steps":["trace[1754861304] 'process raft request' (duration: 834.394864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.17301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.476097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:40.173036Z","caller":"traceutil/trace.go:171","msg":"trace[349455667] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"835.122437ms","start":"2026-05-31T20:38:39.337909Z","end":"2026-05-31T20:38:40.173031Z","steps":["trace[349455667] 'process raft request' (duration: 834.421575ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:38:40.173038Z","caller":"traceutil/trace.go:171","msg":"trace[2075271717] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20; }","duration":"840.540429ms","start":"2026-05-31T20:38:39.33249Z","end":"2026-05-31T20:38:40.17303Z","steps":["trace[2075271717] 'agreement among raft nodes before linearized reading' (duration: 840.492098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.173066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.337847Z","time spent":"835.177049ms","remote":"127.0.0.1:57860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:38:40.173089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332466Z","time spent":"840.616912ms","remote":"127.0.0.1:58044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-05-31T20:38:40.173145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.337864Z","time spent":"835.184079ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:38:40.173258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.675373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-2lc88\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-31T20:38:40.173307Z","caller":"traceutil/trace.go:171","msg":"trace[1511832706] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-2lc88; range_end:; response_count:1; response_revision:20; }","duration":"840.720635ms","start":"2026-05-31T20:38:39.332559Z","end":"2026-05-31T20:38:40.173279Z","steps":["trace[1511832706] 'agreement among raft nodes before linearized reading' (duration: 840.668813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.173335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332551Z","time spent":"840.777156ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-2lc88\" "} {"level":"info","ts":"2026-05-31T20:38:40.173016Z","caller":"traceutil/trace.go:171","msg":"trace[1889929682] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"840.687364ms","start":"2026-05-31T20:38:39.332323Z","end":"2026-05-31T20:38:40.17301Z","steps":["trace[1889929682] 'agreement among raft nodes before linearized reading' (duration: 840.608661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.17336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.497593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:40.173405Z","caller":"traceutil/trace.go:171","msg":"trace[518299465] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"640.571186ms","start":"2026-05-31T20:38:39.532822Z","end":"2026-05-31T20:38:40.173393Z","steps":["trace[518299465] 'agreement among raft nodes before linearized reading' (duration: 640.496814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.173416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.332312Z","time spent":"841.076635ms","remote":"127.0.0.1:57860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-31T20:38:40.173434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.532782Z","time spent":"640.645129ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-31T20:38:40.173621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.284442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-31T20:38:40.173652Z","caller":"traceutil/trace.go:171","msg":"trace[1818659642] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"785.338234ms","start":"2026-05-31T20:38:39.388306Z","end":"2026-05-31T20:38:40.173644Z","steps":["trace[1818659642] 'agreement among raft nodes before linearized reading' (duration: 785.293732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.173672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.38829Z","time spent":"785.376984ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-31T20:38:40.173267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.785889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-31T20:38:40.17376Z","caller":"traceutil/trace.go:171","msg":"trace[522658273] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"787.312926ms","start":"2026-05-31T20:38:39.386433Z","end":"2026-05-31T20:38:40.173746Z","steps":["trace[522658273] 'agreement among raft nodes before linearized reading' (duration: 786.787549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:38:40.173845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:38:39.386413Z","time spent":"787.420039ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-05-31T20:39:16.908296Z","caller":"traceutil/trace.go:171","msg":"trace[479646789] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"240.925699ms","start":"2026-05-31T20:39:16.667337Z","end":"2026-05-31T20:39:16.908263Z","steps":["trace[479646789] 'process raft request' (duration: 240.752103ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:16.909702Z","caller":"traceutil/trace.go:171","msg":"trace[1429851325] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"240.97876ms","start":"2026-05-31T20:39:16.668698Z","end":"2026-05-31T20:39:16.909676Z","steps":["trace[1429851325] 'process raft request' (duration: 240.890547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:21.276572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.07166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-31T20:39:21.276557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.038818ms","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-31T20:39:21.27665Z","caller":"traceutil/trace.go:171","msg":"trace[1964143486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"126.201303ms","start":"2026-05-31T20:39:21.150431Z","end":"2026-05-31T20:39:21.276632Z","steps":["trace[1964143486] 'range keys from in-memory index tree' (duration: 125.967847ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:21.276673Z","caller":"traceutil/trace.go:171","msg":"trace[35931886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"178.231925ms","start":"2026-05-31T20:39:21.098419Z","end":"2026-05-31T20:39:21.276651Z","steps":["trace[35931886] 'range keys from in-memory index tree' (duration: 177.860844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.361363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:21.94068Z","time spent":"420.675346ms","remote":"127.0.0.1:57708","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-31T20:39:22.535347Z","caller":"traceutil/trace.go:171","msg":"trace[146619666] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"526.303501ms","start":"2026-05-31T20:39:22.009005Z","end":"2026-05-31T20:39:22.535308Z","steps":["trace[146619666] 'process raft request' (duration: 525.625619ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.535433Z","caller":"traceutil/trace.go:171","msg":"trace[269205191] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"313.567665ms","start":"2026-05-31T20:39:22.22184Z","end":"2026-05-31T20:39:22.535408Z","steps":["trace[269205191] 'process raft request' (duration: 313.441941ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.535563Z","caller":"traceutil/trace.go:171","msg":"trace[86057921] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"519.328775ms","start":"2026-05-31T20:39:22.016219Z","end":"2026-05-31T20:39:22.535548Z","steps":["trace[86057921] 'process raft request' (duration: 518.575141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.535641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.016193Z","time spent":"519.396588ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.5357Z","caller":"traceutil/trace.go:171","msg":"trace[1017456426] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"509.747289ms","start":"2026-05-31T20:39:22.025942Z","end":"2026-05-31T20:39:22.53569Z","steps":["trace[1017456426] 'process raft request' (duration: 508.941223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.535756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.025914Z","time spent":"509.821512ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.535838Z","caller":"traceutil/trace.go:171","msg":"trace[2115456595] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"523.926025ms","start":"2026-05-31T20:39:22.011901Z","end":"2026-05-31T20:39:22.535827Z","steps":["trace[2115456595] 'process raft request' (duration: 522.765207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.535895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.011851Z","time spent":"524.017008ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.535963Z","caller":"traceutil/trace.go:171","msg":"trace[983933744] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"508.110726ms","start":"2026-05-31T20:39:22.02784Z","end":"2026-05-31T20:39:22.53595Z","steps":["trace[983933744] 'process raft request' (duration: 507.142946ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.535986Z","caller":"traceutil/trace.go:171","msg":"trace[306842314] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"523.864922ms","start":"2026-05-31T20:39:22.012111Z","end":"2026-05-31T20:39:22.535976Z","steps":["trace[306842314] 'process raft request' (duration: 522.646343ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.535997Z","caller":"traceutil/trace.go:171","msg":"trace[421828183] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"172.156031ms","start":"2026-05-31T20:39:22.363827Z","end":"2026-05-31T20:39:22.535983Z","steps":["trace[421828183] 'process raft request' (duration: 172.020637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.536018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.027826Z","time spent":"508.171648ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.536067Z","caller":"traceutil/trace.go:171","msg":"trace[1534308309] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"436.670731ms","start":"2026-05-31T20:39:22.099387Z","end":"2026-05-31T20:39:22.536058Z","steps":["trace[1534308309] 'process raft request' (duration: 435.727111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.536028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.012043Z","time spent":"523.963625ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.536208Z","caller":"traceutil/trace.go:171","msg":"trace[631236691] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"550.363983ms","start":"2026-05-31T20:39:21.985834Z","end":"2026-05-31T20:39:22.536198Z","steps":["trace[631236691] 'process raft request' (duration: 548.479773ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.536232Z","caller":"traceutil/trace.go:171","msg":"trace[222392917] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"542.534972ms","start":"2026-05-31T20:39:21.993688Z","end":"2026-05-31T20:39:22.536223Z","steps":["trace[222392917] 'process raft request' (duration: 540.861438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.536246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:21.985803Z","time spent":"550.424715ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.536286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:21.993685Z","time spent":"542.572703ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.535631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.221804Z","time spent":"313.751971ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.536224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.008972Z","time spent":"526.475076ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.536109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.09934Z","time spent":"436.752733ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.536371Z","caller":"traceutil/trace.go:171","msg":"trace[1514797873] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"528.544552ms","start":"2026-05-31T20:39:22.007787Z","end":"2026-05-31T20:39:22.536332Z","steps":["trace[1514797873] 'process raft request' (duration: 526.801026ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.536175Z","caller":"traceutil/trace.go:171","msg":"trace[1486336843] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"542.550882ms","start":"2026-05-31T20:39:21.993616Z","end":"2026-05-31T20:39:22.536167Z","steps":["trace[1486336843] 'process raft request' (duration: 540.845588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.536464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:21.993592Z","time spent":"542.852023ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.536484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.007765Z","time spent":"528.679928ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.874447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.463273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-31T20:39:22.874666Z","caller":"traceutil/trace.go:171","msg":"trace[944077363] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"324.258189ms","start":"2026-05-31T20:39:22.550363Z","end":"2026-05-31T20:39:22.874622Z","steps":["trace[944077363] 'process raft request' (duration: 127.234318ms)","trace[944077363] 'compare' (duration: 196.110661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T20:39:22.87479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.550331Z","time spent":"324.375783ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.877489Z","caller":"traceutil/trace.go:171","msg":"trace[651595894] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"326.342495ms","start":"2026-05-31T20:39:22.551108Z","end":"2026-05-31T20:39:22.87745Z","steps":["trace[651595894] 'process raft request' (duration: 325.626512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.877721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.55107Z","time spent":"326.547923ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.877728Z","caller":"traceutil/trace.go:171","msg":"trace[708796935] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"325.676634ms","start":"2026-05-31T20:39:22.552012Z","end":"2026-05-31T20:39:22.877689Z","steps":["trace[708796935] 'process raft request' (duration: 325.091535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.877862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.551982Z","time spent":"325.815829ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.87787Z","caller":"traceutil/trace.go:171","msg":"trace[955472603] transaction","detail":"{read_only:false; number_of_response:1; response_revision:556; }","duration":"318.176392ms","start":"2026-05-31T20:39:22.559607Z","end":"2026-05-31T20:39:22.877783Z","steps":["trace[955472603] 'process raft request' (duration: 317.809641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.878119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.559575Z","time spent":"318.390629ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:22.877725Z","caller":"traceutil/trace.go:171","msg":"trace[364164827] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"325.455557ms","start":"2026-05-31T20:39:22.552196Z","end":"2026-05-31T20:39:22.877651Z","steps":["trace[364164827] 'process raft request' (duration: 325.149968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.878635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.552189Z","time spent":"326.403337ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:22.878858Z","caller":"traceutil/trace.go:171","msg":"trace[987009358] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"319.123754ms","start":"2026-05-31T20:39:22.559713Z","end":"2026-05-31T20:39:22.878837Z","steps":["trace[987009358] 'process raft request' (duration: 317.743199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.878949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.559679Z","time spent":"319.222657ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:22.879041Z","caller":"traceutil/trace.go:171","msg":"trace[670142675] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"318.985139ms","start":"2026-05-31T20:39:22.56004Z","end":"2026-05-31T20:39:22.879026Z","steps":["trace[670142675] 'process raft request' (duration: 318.724461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.879099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.560032Z","time spent":"319.042951ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T20:39:22.879223Z","caller":"traceutil/trace.go:171","msg":"trace[1313739997] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"312.232022ms","start":"2026-05-31T20:39:22.566979Z","end":"2026-05-31T20:39:22.879211Z","steps":["trace[1313739997] 'process raft request' (duration: 311.903422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.879291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.566958Z","time spent":"312.298893ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:22.879207Z","caller":"traceutil/trace.go:171","msg":"trace[395520830] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"327.334047ms","start":"2026-05-31T20:39:22.551848Z","end":"2026-05-31T20:39:22.879182Z","steps":["trace[395520830] 'read index received' (duration: 125.721479ms)","trace[395520830] 'applied index is now lower than readState.Index' (duration: 201.611588ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:22.879384Z","caller":"traceutil/trace.go:171","msg":"trace[1886064266] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"317.288814ms","start":"2026-05-31T20:39:22.562084Z","end":"2026-05-31T20:39:22.879373Z","steps":["trace[1886064266] 'process raft request' (duration: 316.742257ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:22.879419Z","caller":"traceutil/trace.go:171","msg":"trace[510912212] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"310.342691ms","start":"2026-05-31T20:39:22.569064Z","end":"2026-05-31T20:39:22.879406Z","steps":["trace[510912212] 'process raft request' (duration: 309.944639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.879439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.562061Z","time spent":"317.357356ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T20:39:22.879541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.569033Z","time spent":"310.419864ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:22.879722Z","caller":"traceutil/trace.go:171","msg":"trace[187622517] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"304.474862ms","start":"2026-05-31T20:39:22.575204Z","end":"2026-05-31T20:39:22.879679Z","steps":["trace[187622517] 'process raft request' (duration: 303.873923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.880244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.575172Z","time spent":"304.987319ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:22.879824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.968577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T20:39:22.880435Z","caller":"traceutil/trace.go:171","msg":"trace[1271233230] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:562; }","duration":"328.579717ms","start":"2026-05-31T20:39:22.55184Z","end":"2026-05-31T20:39:22.88042Z","steps":["trace[1271233230] 'agreement among raft nodes before linearized reading' (duration: 327.847694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.880486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.551802Z","time spent":"328.67113ms","remote":"127.0.0.1:57708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-31T20:39:22.898863Z","caller":"traceutil/trace.go:171","msg":"trace[1526390287] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"212.534949ms","start":"2026-05-31T20:39:22.686297Z","end":"2026-05-31T20:39:22.898832Z","steps":["trace[1526390287] 'process raft request' (duration: 212.326602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.899108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.771346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:22.899151Z","caller":"traceutil/trace.go:171","msg":"trace[1974745128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"329.865169ms","start":"2026-05-31T20:39:22.569271Z","end":"2026-05-31T20:39:22.899137Z","steps":["trace[1974745128] 'agreement among raft nodes before linearized reading' (duration: 329.733584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:22.899172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:22.569263Z","time spent":"329.902941ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-31T20:39:30.883542Z","caller":"traceutil/trace.go:171","msg":"trace[295820754] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"163.459262ms","start":"2026-05-31T20:39:30.720056Z","end":"2026-05-31T20:39:30.883515Z","steps":["trace[295820754] 'process raft request' (duration: 153.040236ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:31.620017Z","caller":"traceutil/trace.go:171","msg":"trace[326150877] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"541.89767ms","start":"2026-05-31T20:39:31.078088Z","end":"2026-05-31T20:39:31.619985Z","steps":["trace[326150877] 'process raft request' (duration: 507.597395ms)","trace[326150877] 'compare' (duration: 34.200611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T20:39:31.62015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:31.078067Z","time spent":"542.016724ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:33.21456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.80866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T20:39:33.214646Z","caller":"traceutil/trace.go:171","msg":"trace[767922500] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"1.776207807s","start":"2026-05-31T20:39:31.438425Z","end":"2026-05-31T20:39:33.214633Z","steps":["trace[767922500] 'read index received' (duration: 147.267042ms)","trace[767922500] 'applied index is now lower than readState.Index' (duration: 1.628939935s)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:33.214752Z","caller":"traceutil/trace.go:171","msg":"trace[1611635416] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"2.136102895s","start":"2026-05-31T20:39:31.07864Z","end":"2026-05-31T20:39:33.214743Z","steps":["trace[1611635416] 'process raft request' (duration: 1.310025787s)","trace[1611635416] 'compare' (duration: 825.692617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T20:39:33.214794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:31.078632Z","time spent":"2.136140527s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:33.214956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.776530737s","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-31T20:39:33.214984Z","caller":"traceutil/trace.go:171","msg":"trace[1077397570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"1.776586419s","start":"2026-05-31T20:39:31.438389Z","end":"2026-05-31T20:39:33.214976Z","steps":["trace[1077397570] 'agreement among raft nodes before linearized reading' (duration: 1.776485105s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:33.215004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:31.438369Z","time spent":"1.77663032s","remote":"127.0.0.1:57948","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-31T20:39:33.215114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289875527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-54n79\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-31T20:39:33.215133Z","caller":"traceutil/trace.go:171","msg":"trace[902305991] range","detail":"{range_begin:/registry/pods/kube-system/cilium-54n79; range_end:; response_count:1; response_revision:661; }","duration":"1.289924088s","start":"2026-05-31T20:39:31.925203Z","end":"2026-05-31T20:39:33.215127Z","steps":["trace[902305991] 'agreement among raft nodes before linearized reading' (duration: 1.289861216s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:33.215113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773745648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-31T20:39:33.21515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:31.925187Z","time spent":"1.28995854s","remote":"127.0.0.1:57870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-54n79\" "} {"level":"info","ts":"2026-05-31T20:39:33.215167Z","caller":"traceutil/trace.go:171","msg":"trace[139194246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"1.773836351s","start":"2026-05-31T20:39:31.441316Z","end":"2026-05-31T20:39:33.215152Z","steps":["trace[139194246] 'agreement among raft nodes before linearized reading' (duration: 1.773652064s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:33.215198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:31.441301Z","time spent":"1.773889662s","remote":"127.0.0.1:57948","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-31T20:39:33.215196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.601585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:33.215244Z","caller":"traceutil/trace.go:171","msg":"trace[1972943453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"640.698057ms","start":"2026-05-31T20:39:32.574532Z","end":"2026-05-31T20:39:33.21523Z","steps":["trace[1972943453] 'agreement among raft nodes before linearized reading' (duration: 640.623045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:33.215269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:32.574514Z","time spent":"640.750769ms","remote":"127.0.0.1:57652","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-31T20:39:33.215212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275274617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.163\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T20:39:33.215336Z","caller":"traceutil/trace.go:171","msg":"trace[865848937] range","detail":"{range_begin:/registry/masterleases/199.19.213.163; range_end:; response_count:1; response_revision:661; }","duration":"1.275442732s","start":"2026-05-31T20:39:31.939875Z","end":"2026-05-31T20:39:33.215318Z","steps":["trace[865848937] 'agreement among raft nodes before linearized reading' (duration: 1.275168704s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:33.215373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:31.939857Z","time spent":"1.275506223s","remote":"127.0.0.1:57708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.163\" "} {"level":"warn","ts":"2026-05-31T20:39:33.824814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.93835ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-31T20:39:33.825073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.216001Z","time spent":"609.066672ms","remote":"127.0.0.1:57708","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-31T20:39:34.070085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196739836557428,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T20:39:34.570402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196739836557428,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T20:39:34.956744Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.607050169s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-31T20:39:35.554305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.185506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T20:39:35.554619Z","caller":"traceutil/trace.go:171","msg":"trace[312331137] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"2.33446898s","start":"2026-05-31T20:39:33.220133Z","end":"2026-05-31T20:39:35.554602Z","steps":["trace[312331137] 'process raft request' (duration: 2.334288035s)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:35.554638Z","caller":"traceutil/trace.go:171","msg":"trace[1458013811] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"2.334469481s","start":"2026-05-31T20:39:33.220093Z","end":"2026-05-31T20:39:35.554563Z","steps":["trace[1458013811] 'process raft request' (duration: 1.736944364s)","trace[1458013811] 'compare' (duration: 597.059923ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:35.554802Z","caller":"traceutil/trace.go:171","msg":"trace[1776614090] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"2.327838177s","start":"2026-05-31T20:39:33.226929Z","end":"2026-05-31T20:39:35.554768Z","steps":["trace[1776614090] 'process raft request' (duration: 2.327591579s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:35.554855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.220076Z","time spent":"2.334728049s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:35.554974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.226902Z","time spent":"2.327983152s","remote":"127.0.0.1:57870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:35.554721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.220116Z","time spent":"2.334556793s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:35.569862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000542073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-31T20:39:35.569942Z","caller":"traceutil/trace.go:171","msg":"trace[372511846] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000665097s","start":"2026-05-31T20:39:33.569258Z","end":"2026-05-31T20:39:35.569924Z","steps":["trace[372511846] 'agreement among raft nodes before linearized reading' (duration: 2.000568094s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:35.569988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.569243Z","time spent":"2.00073803s","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-31T20:39:36.040565Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083561352s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-31T20:39:37.018184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.340015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T20:39:37.01834Z","caller":"traceutil/trace.go:171","msg":"trace[968646508] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:687; }","duration":"3.449032094s","start":"2026-05-31T20:39:33.569294Z","end":"2026-05-31T20:39:37.018326Z","steps":["trace[968646508] 'read index received' (duration: 1.387758634s)","trace[968646508] 'applied index is now lower than readState.Index' (duration: 2.06127233s)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:37.018417Z","caller":"traceutil/trace.go:171","msg":"trace[357935454] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"3.192367386s","start":"2026-05-31T20:39:33.826039Z","end":"2026-05-31T20:39:37.018407Z","steps":["trace[357935454] 'process raft request' (duration: 2.21469062s)","trace[357935454] 'compare' (duration: 976.655683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T20:39:37.018464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.826021Z","time spent":"3.192415238s","remote":"127.0.0.1:57708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:37.018474Z","caller":"traceutil/trace.go:171","msg":"trace[330844218] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"2.63571762s","start":"2026-05-31T20:39:34.382746Z","end":"2026-05-31T20:39:37.018464Z","steps":["trace[330844218] 'process raft request' (duration: 2.635528334s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:37.018522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:34.382711Z","time spent":"2.635783633s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:37.018671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.392238366s","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-31T20:39:37.018708Z","caller":"traceutil/trace.go:171","msg":"trace[1167770136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"3.392307638s","start":"2026-05-31T20:39:33.626389Z","end":"2026-05-31T20:39:37.018697Z","steps":["trace[1167770136] 'agreement among raft nodes before linearized reading' (duration: 3.392155872s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:37.018676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448085429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T20:39:37.018738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:33.62637Z","time spent":"3.39236113s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T20:39:37.018752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457199552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-54n79\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-05-31T20:39:37.018955Z","caller":"traceutil/trace.go:171","msg":"trace[642429590] range","detail":"{range_begin:/registry/pods/kube-system/cilium-54n79; range_end:; response_count:1; response_revision:666; }","duration":"1.45743059s","start":"2026-05-31T20:39:35.561518Z","end":"2026-05-31T20:39:37.018948Z","steps":["trace[642429590] 'agreement among raft nodes before linearized reading' (duration: 1.457177872s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:37.018974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:35.561497Z","time spent":"1.45747232s","remote":"127.0.0.1:57870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-54n79\" "} {"level":"warn","ts":"2026-05-31T20:39:37.018979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.79968658s","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-31T20:39:37.018973Z","caller":"traceutil/trace.go:171","msg":"trace[720341947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.448241314s","start":"2026-05-31T20:39:35.570514Z","end":"2026-05-31T20:39:37.018756Z","steps":["trace[720341947] 'agreement among raft nodes before linearized reading' (duration: 1.448041376s)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:37.019Z","caller":"traceutil/trace.go:171","msg":"trace[1183507306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"1.799743772s","start":"2026-05-31T20:39:35.219249Z","end":"2026-05-31T20:39:37.018993Z","steps":["trace[1183507306] 'agreement among raft nodes before linearized reading' (duration: 1.799495894s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:37.019021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:35.219231Z","time spent":"1.799785954s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T20:39:37.019023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:35.570498Z","time spent":"1.448515962s","remote":"127.0.0.1:57668","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-31T20:39:37.01872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373835325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T20:39:37.019069Z","caller":"traceutil/trace.go:171","msg":"trace[1788926344] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:666; }","duration":"1.374211827s","start":"2026-05-31T20:39:35.644844Z","end":"2026-05-31T20:39:37.019056Z","steps":["trace[1788926344] 'agreement among raft nodes before linearized reading' (duration: 1.373849466s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:37.019106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:35.644825Z","time spent":"1.374268379s","remote":"127.0.0.1:58182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-31T20:39:37.131555Z","caller":"traceutil/trace.go:171","msg":"trace[1525825600] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"106.709142ms","start":"2026-05-31T20:39:37.024824Z","end":"2026-05-31T20:39:37.131533Z","steps":["trace[1525825600] 'process raft request' (duration: 104.001944ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:37.131638Z","caller":"traceutil/trace.go:171","msg":"trace[1600052890] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:692; }","duration":"104.656485ms","start":"2026-05-31T20:39:37.026968Z","end":"2026-05-31T20:39:37.131625Z","steps":["trace[1600052890] 'read index received' (duration: 30.10046ms)","trace[1600052890] 'applied index is now lower than readState.Index' (duration: 74.554875ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:37.131689Z","caller":"traceutil/trace.go:171","msg":"trace[1748687030] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"106.848145ms","start":"2026-05-31T20:39:37.024832Z","end":"2026-05-31T20:39:37.13168Z","steps":["trace[1748687030] 'process raft request' (duration: 106.634179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:37.131745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.770089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-31T20:39:37.131774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.505759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:37.131776Z","caller":"traceutil/trace.go:171","msg":"trace[763241923] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:669; }","duration":"104.83484ms","start":"2026-05-31T20:39:37.026934Z","end":"2026-05-31T20:39:37.131769Z","steps":["trace[763241923] 'agreement among raft nodes before linearized reading' (duration: 104.751497ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:37.131792Z","caller":"traceutil/trace.go:171","msg":"trace[339424483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"104.551131ms","start":"2026-05-31T20:39:37.027235Z","end":"2026-05-31T20:39:37.131787Z","steps":["trace[339424483] 'agreement among raft nodes before linearized reading' (duration: 104.51743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:38.458968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.761386ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:38.459203Z","caller":"traceutil/trace.go:171","msg":"trace[1755709421] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:675; }","duration":"215.006934ms","start":"2026-05-31T20:39:38.244145Z","end":"2026-05-31T20:39:38.459152Z","steps":["trace[1755709421] 'range keys from in-memory index tree' (duration: 214.731005ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:39.107404Z","caller":"traceutil/trace.go:171","msg":"trace[558089854] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"141.035918ms","start":"2026-05-31T20:39:38.966341Z","end":"2026-05-31T20:39:39.107377Z","steps":["trace[558089854] 'read index received' (duration: 137.07874ms)","trace[558089854] 'applied index is now lower than readState.Index' (duration: 3.956168ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:39.107433Z","caller":"traceutil/trace.go:171","msg":"trace[1766045466] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"148.244592ms","start":"2026-05-31T20:39:38.959151Z","end":"2026-05-31T20:39:39.107396Z","steps":["trace[1766045466] 'process raft request' (duration: 144.45179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:39.107778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.420832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-54n79\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-05-31T20:39:39.107832Z","caller":"traceutil/trace.go:171","msg":"trace[661548390] range","detail":"{range_begin:/registry/pods/kube-system/cilium-54n79; range_end:; response_count:1; response_revision:678; }","duration":"141.539226ms","start":"2026-05-31T20:39:38.966276Z","end":"2026-05-31T20:39:39.107815Z","steps":["trace[661548390] 'agreement among raft nodes before linearized reading' (duration: 141.278517ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:39.325926Z","caller":"traceutil/trace.go:171","msg":"trace[1674746459] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:710; }","duration":"149.809331ms","start":"2026-05-31T20:39:39.176089Z","end":"2026-05-31T20:39:39.325898Z","steps":["trace[1674746459] 'read index received' (duration: 1.416735ms)","trace[1674746459] 'applied index is now lower than readState.Index' (duration: 148.391226ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:39.325985Z","caller":"traceutil/trace.go:171","msg":"trace[859413383] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"150.68344ms","start":"2026-05-31T20:39:39.175275Z","end":"2026-05-31T20:39:39.325959Z","steps":["trace[859413383] 'process raft request' (duration: 116.25315ms)","trace[859413383] 'compare' (duration: 34.071399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T20:39:39.32621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.099462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-31T20:39:39.326228Z","caller":"traceutil/trace.go:171","msg":"trace[375147491] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"150.938088ms","start":"2026-05-31T20:39:39.175279Z","end":"2026-05-31T20:39:39.326217Z","steps":["trace[375147491] 'process raft request' (duration: 150.529025ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:39.326258Z","caller":"traceutil/trace.go:171","msg":"trace[30176103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"150.178584ms","start":"2026-05-31T20:39:39.176066Z","end":"2026-05-31T20:39:39.326244Z","steps":["trace[30176103] 'agreement among raft nodes before linearized reading' (duration: 149.932386ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:39.45563Z","caller":"traceutil/trace.go:171","msg":"trace[1125558285] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"109.241444ms","start":"2026-05-31T20:39:39.346363Z","end":"2026-05-31T20:39:39.455604Z","steps":["trace[1125558285] 'process raft request' (duration: 107.582101ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:39.464436Z","caller":"traceutil/trace.go:171","msg":"trace[671590310] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"113.197431ms","start":"2026-05-31T20:39:39.351141Z","end":"2026-05-31T20:39:39.464338Z","steps":["trace[671590310] 'process raft request' (duration: 112.821459ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:39.918859Z","caller":"traceutil/trace.go:171","msg":"trace[1457572457] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"151.506727ms","start":"2026-05-31T20:39:39.76729Z","end":"2026-05-31T20:39:39.918797Z","steps":["trace[1457572457] 'process raft request' (duration: 151.326551ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:40.547597Z","caller":"traceutil/trace.go:171","msg":"trace[45243539] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"198.269687ms","start":"2026-05-31T20:39:40.349295Z","end":"2026-05-31T20:39:40.547565Z","steps":["trace[45243539] 'process raft request' (duration: 197.138311ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:40.549614Z","caller":"traceutil/trace.go:171","msg":"trace[1046108194] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"180.231244ms","start":"2026-05-31T20:39:40.369359Z","end":"2026-05-31T20:39:40.54959Z","steps":["trace[1046108194] 'process raft request' (duration: 178.127586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:42.41273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.726349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T20:39:42.412823Z","caller":"traceutil/trace.go:171","msg":"trace[477644466] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:740; }","duration":"218.892515ms","start":"2026-05-31T20:39:42.19391Z","end":"2026-05-31T20:39:42.412803Z","steps":["trace[477644466] 'count revisions from in-memory index tree' (duration: 218.536113ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:49.287918Z","caller":"traceutil/trace.go:171","msg":"trace[1243052453] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"113.523629ms","start":"2026-05-31T20:39:49.174359Z","end":"2026-05-31T20:39:49.287882Z","steps":["trace[1243052453] 'read index received' (duration: 112.123824ms)","trace[1243052453] 'applied index is now lower than readState.Index' (duration: 1.398495ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:39:49.28796Z","caller":"traceutil/trace.go:171","msg":"trace[1176784659] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"120.169114ms","start":"2026-05-31T20:39:49.167761Z","end":"2026-05-31T20:39:49.28791Z","steps":["trace[1176784659] 'process raft request' (duration: 118.838761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:49.288403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.91763ms","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-31T20:39:49.28848Z","caller":"traceutil/trace.go:171","msg":"trace[812960295] 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:794; }","duration":"114.023513ms","start":"2026-05-31T20:39:49.17444Z","end":"2026-05-31T20:39:49.288463Z","steps":["trace[812960295] 'agreement among raft nodes before linearized reading' (duration: 113.740235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:49.288428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.015164ms","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-31T20:39:49.288847Z","caller":"traceutil/trace.go:171","msg":"trace[87416996] 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:794; }","duration":"114.48024ms","start":"2026-05-31T20:39:49.174334Z","end":"2026-05-31T20:39:49.288814Z","steps":["trace[87416996] 'agreement among raft nodes before linearized reading' (duration: 113.718306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:49.28891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.666783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T20:39:49.288971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.780937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:49.289019Z","caller":"traceutil/trace.go:171","msg":"trace[14033065] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:794; }","duration":"100.847958ms","start":"2026-05-31T20:39:49.188156Z","end":"2026-05-31T20:39:49.289004Z","steps":["trace[14033065] 'agreement among raft nodes before linearized reading' (duration: 100.788156ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:39:49.288975Z","caller":"traceutil/trace.go:171","msg":"trace[367322855] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:794; }","duration":"100.764186ms","start":"2026-05-31T20:39:49.188182Z","end":"2026-05-31T20:39:49.288946Z","steps":["trace[367322855] 'agreement among raft nodes before linearized reading' (duration: 100.681704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:49.288887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.075404ms","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-31T20:39:49.289195Z","caller":"traceutil/trace.go:171","msg":"trace[881632890] 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:794; }","duration":"113.498748ms","start":"2026-05-31T20:39:49.175687Z","end":"2026-05-31T20:39:49.289185Z","steps":["trace[881632890] 'agreement among raft nodes before linearized reading' (duration: 113.088475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:54.254099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196739836557842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T20:39:54.756101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196739836557842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T20:39:55.029057Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294747673s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T20:39:55.029478Z","caller":"traceutil/trace.go:171","msg":"trace[1371811571] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"1.295335683s","start":"2026-05-31T20:39:53.734104Z","end":"2026-05-31T20:39:55.02944Z","steps":["trace[1371811571] 'process raft request' (duration: 1.295122806s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.029613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:53.734087Z","time spent":"1.295440006s","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:39:55.207089Z","caller":"traceutil/trace.go:171","msg":"trace[1937174150] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"1.453085745s","start":"2026-05-31T20:39:53.753965Z","end":"2026-05-31T20:39:55.20705Z","steps":["trace[1937174150] 'read index received' (duration: 1.275635585s)","trace[1937174150] 'applied index is now lower than readState.Index' (duration: 177.44874ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T20:39:55.207386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237943477s","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":"info","ts":"2026-05-31T20:39:55.207433Z","caller":"traceutil/trace.go:171","msg":"trace[902735069] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:830; }","duration":"1.238053952s","start":"2026-05-31T20:39:53.969368Z","end":"2026-05-31T20:39:55.207422Z","steps":["trace[902735069] 'agreement among raft nodes before linearized reading' (duration: 1.237947469s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.207461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:53.969342Z","time spent":"1.238110484s","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-05-31T20:39:55.207115Z","caller":"traceutil/trace.go:171","msg":"trace[1756770905] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"1.129291244s","start":"2026-05-31T20:39:54.077784Z","end":"2026-05-31T20:39:55.207076Z","steps":["trace[1756770905] 'process raft request' (duration: 1.129091958s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.207665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.237293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:55.207699Z","caller":"traceutil/trace.go:171","msg":"trace[228457331] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:830; }","duration":"647.327286ms","start":"2026-05-31T20:39:54.560362Z","end":"2026-05-31T20:39:55.207689Z","steps":["trace[228457331] 'agreement among raft nodes before linearized reading' (duration: 647.260124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.207708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.563724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T20:39:55.207735Z","caller":"traceutil/trace.go:171","msg":"trace[1108589333] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:830; }","duration":"971.633246ms","start":"2026-05-31T20:39:54.236094Z","end":"2026-05-31T20:39:55.207727Z","steps":["trace[1108589333] 'agreement among raft nodes before linearized reading' (duration: 971.530502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.207755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:54.560321Z","time spent":"647.396708ms","remote":"127.0.0.1:57832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-31T20:39:55.207768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:54.236064Z","time spent":"971.693627ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-31T20:39:55.208015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.830834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:39:55.208044Z","caller":"traceutil/trace.go:171","msg":"trace[394732046] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:830; }","duration":"523.909966ms","start":"2026-05-31T20:39:54.684126Z","end":"2026-05-31T20:39:55.208036Z","steps":["trace[394732046] 'agreement among raft nodes before linearized reading' (duration: 523.851714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.208065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:54.684099Z","time spent":"523.960188ms","remote":"127.0.0.1:57770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-31T20:39:55.20802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:54.077755Z","time spent":"1.129902014s","remote":"127.0.0.1:57918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T20:39:55.207553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453487137s","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-31T20:39:55.2084Z","caller":"traceutil/trace.go:171","msg":"trace[290768654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:830; }","duration":"1.45450297s","start":"2026-05-31T20:39:53.753888Z","end":"2026-05-31T20:39:55.208391Z","steps":["trace[290768654] 'agreement among raft nodes before linearized reading' (duration: 1.453313392s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:39:55.20866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:39:53.753857Z","time spent":"1.454769309s","remote":"127.0.0.1:57948","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-31T20:40:15.357551Z","caller":"traceutil/trace.go:171","msg":"trace[2146139871] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"147.744381ms","start":"2026-05-31T20:40:15.209769Z","end":"2026-05-31T20:40:15.357513Z","steps":["trace[2146139871] 'process raft request' (duration: 147.482677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:40:15.674451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.085388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T20:40:15.674626Z","caller":"traceutil/trace.go:171","msg":"trace[227117442] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"138.086058ms","start":"2026-05-31T20:40:15.536514Z","end":"2026-05-31T20:40:15.6746Z","steps":["trace[227117442] 'process raft request' (duration: 18.720785ms)","trace[227117442] 'compare' (duration: 118.934325ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:40:15.870385Z","caller":"traceutil/trace.go:171","msg":"trace[1139423232] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"124.627892ms","start":"2026-05-31T20:40:15.745716Z","end":"2026-05-31T20:40:15.870344Z","steps":["trace[1139423232] 'process raft request' (duration: 123.814337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:41:36.044563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.091135ms","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-31T20:41:36.044781Z","caller":"traceutil/trace.go:171","msg":"trace[2050522309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"109.374853ms","start":"2026-05-31T20:41:35.935376Z","end":"2026-05-31T20:41:36.044751Z","steps":["trace[2050522309] 'range keys from in-memory index tree' (duration: 108.836888ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:41:38.913834Z","caller":"traceutil/trace.go:171","msg":"trace[201147063] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"121.309819ms","start":"2026-05-31T20:41:38.792486Z","end":"2026-05-31T20:41:38.913796Z","steps":["trace[201147063] 'process raft request' (duration: 121.101894ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:41:40.171315Z","caller":"traceutil/trace.go:171","msg":"trace[29089307] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"102.198941ms","start":"2026-05-31T20:41:40.069071Z","end":"2026-05-31T20:41:40.17127Z","steps":["trace[29089307] 'process raft request' (duration: 101.868212ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:41:40.171382Z","caller":"traceutil/trace.go:171","msg":"trace[2014606110] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"102.261102ms","start":"2026-05-31T20:41:40.069078Z","end":"2026-05-31T20:41:40.17134Z","steps":["trace[2014606110] 'process raft request' (duration: 102.050318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:41:56.728338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.623659ms","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-31T20:41:56.728456Z","caller":"traceutil/trace.go:171","msg":"trace[1485859432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"229.986439ms","start":"2026-05-31T20:41:56.498433Z","end":"2026-05-31T20:41:56.728419Z","steps":["trace[1485859432] 'range keys from in-memory index tree' (duration: 229.381314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:41:56.72848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.60285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f35a58ac-85b1-49e8-b99b-de13f348c485\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-05-31T20:41:56.728516Z","caller":"traceutil/trace.go:171","msg":"trace[694521208] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f35a58ac-85b1-49e8-b99b-de13f348c485; range_end:; response_count:1; response_revision:1373; }","duration":"132.642241ms","start":"2026-05-31T20:41:56.595862Z","end":"2026-05-31T20:41:56.728505Z","steps":["trace[694521208] 'range keys from in-memory index tree' (duration: 132.476065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:41:56.728669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.023128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:41:56.728734Z","caller":"traceutil/trace.go:171","msg":"trace[248550605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"160.132061ms","start":"2026-05-31T20:41:56.568596Z","end":"2026-05-31T20:41:56.728728Z","steps":["trace[248550605] 'range keys from in-memory index tree' (duration: 159.938016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:41:56.728946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.303272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f35a58ac-85b1-49e8-b99b-de13f348c485\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-05-31T20:41:56.729043Z","caller":"traceutil/trace.go:171","msg":"trace[375293014] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f35a58ac-85b1-49e8-b99b-de13f348c485; range_end:; response_count:1; response_revision:1373; }","duration":"148.465226ms","start":"2026-05-31T20:41:56.580551Z","end":"2026-05-31T20:41:56.729017Z","steps":["trace[375293014] 'range keys from in-memory index tree' (duration: 148.090056ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:41:59.632418Z","caller":"traceutil/trace.go:171","msg":"trace[528006838] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"133.106191ms","start":"2026-05-31T20:41:59.499272Z","end":"2026-05-31T20:41:59.632378Z","steps":["trace[528006838] 'process raft request' (duration: 125.192756ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T20:42:02.78774Z","caller":"traceutil/trace.go:171","msg":"trace[1957299421] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"376.516416ms","start":"2026-05-31T20:42:02.411194Z","end":"2026-05-31T20:42:02.78771Z","steps":["trace[1957299421] 'read index received' (duration: 376.284309ms)","trace[1957299421] 'applied index is now lower than readState.Index' (duration: 231.056µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T20:42:02.787888Z","caller":"traceutil/trace.go:171","msg":"trace[1666228336] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"505.279127ms","start":"2026-05-31T20:42:02.28256Z","end":"2026-05-31T20:42:02.787839Z","steps":["trace[1666228336] 'process raft request' (duration: 504.874177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:42:02.788036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.227319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-31T20:42:02.788104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:42:02.282541Z","time spent":"505.463251ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T20:42:02.788134Z","caller":"traceutil/trace.go:171","msg":"trace[1367328985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1407; }","duration":"336.365692ms","start":"2026-05-31T20:42:02.451751Z","end":"2026-05-31T20:42:02.788116Z","steps":["trace[1367328985] 'agreement among raft nodes before linearized reading' (duration: 336.200798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:42:02.788174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:42:02.451723Z","time spent":"336.443074ms","remote":"127.0.0.1:57948","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-31T20:42:02.788179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.993422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T20:42:02.788347Z","caller":"traceutil/trace.go:171","msg":"trace[9691150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1407; }","duration":"219.337651ms","start":"2026-05-31T20:42:02.568973Z","end":"2026-05-31T20:42:02.788311Z","steps":["trace[9691150] 'agreement among raft nodes before linearized reading' (duration: 218.991942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:42:02.788036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.843773ms","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-31T20:42:02.788508Z","caller":"traceutil/trace.go:171","msg":"trace[851867343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1407; }","duration":"377.301134ms","start":"2026-05-31T20:42:02.411176Z","end":"2026-05-31T20:42:02.788477Z","steps":["trace[851867343] 'agreement among raft nodes before linearized reading' (duration: 376.7192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T20:42:02.788609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T20:42:02.411108Z","time spent":"377.435868ms","remote":"127.0.0.1:57948","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-31T20:42:11.811415Z","caller":"traceutil/trace.go:171","msg":"trace[232742070] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"122.147158ms","start":"2026-05-31T20:42:11.689238Z","end":"2026-05-31T20:42:11.811385Z","steps":["trace[232742070] 'process raft request' (duration: 121.989514ms)"],"step_count":1}