{"level":"warn","ts":"2026-05-19T03:58:46.592726Z","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-19T03:58:46.592807Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-19T03:58:46.592873Z","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-19T03:58:46.592885Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-19T03:58:46.592901Z","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-19T03:58:46.593553Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-19T03:58:46.593626Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-19T03:58:46.609468Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.638634ms"} {"level":"info","ts":"2026-05-19T03:58:46.633415Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-19T03:58:46.633509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T03:58:46.633534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-19T03:58:46.633548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T03:58:46.633558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-19T03:58:46.633651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-19T03:58:46.697277Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T03:58:46.707605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T03:58:46.794727Z","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-19T03:58:46.874044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T03:58:46.877335Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T03:58:46.875185Z","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-19T03:58:46.877544Z","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-19T03:58:46.877562Z","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-19T03:58:46.886079Z","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-19T03:58:46.886279Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T03:58:46.886305Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T03:58:46.886348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-19T03:58:46.886358Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-19T03:58:46.901962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-19T03:58:46.902328Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-19T03:58:46.934997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T03:58:46.935061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T03:58:46.935104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-19T03:58:46.935132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-19T03:58:46.93515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-19T03:58:46.935172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-19T03:58:46.935186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-19T03:58:46.967507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T03:58:46.96751Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T03:58:46.967544Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T03:58:46.967619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T03:58:46.967868Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T03:58:46.96791Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T03:58:46.971189Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-19T03:58:46.971346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T03:58:46.983998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T03:58:46.984106Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T03:58:46.984166Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T03:58:48.468747Z","caller":"traceutil/trace.go:171","msg":"trace[1927867210] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:23; }","duration":"109.615891ms","start":"2026-05-19T03:58:48.359103Z","end":"2026-05-19T03:58:48.468719Z","steps":["trace[1927867210] 'read index received' (duration: 49.642785ms)","trace[1927867210] 'applied index is now lower than readState.Index' (duration: 59.972206ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:48.468876Z","caller":"traceutil/trace.go:171","msg":"trace[613038605] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"110.679033ms","start":"2026-05-19T03:58:48.358186Z","end":"2026-05-19T03:58:48.468865Z","steps":["trace[613038605] 'process raft request' (duration: 50.493715ms)","trace[613038605] 'compare' (duration: 59.884955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:48.469117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.185633ms","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-19T03:58:48.469172Z","caller":"traceutil/trace.go:171","msg":"trace[1100177310] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"112.282134ms","start":"2026-05-19T03:58:48.356879Z","end":"2026-05-19T03:58:48.469161Z","steps":["trace[1100177310] 'agreement among raft nodes before linearized reading' (duration: 112.096192ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.496967Z","caller":"traceutil/trace.go:171","msg":"trace[1452927043] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"137.78689ms","start":"2026-05-19T03:58:48.359153Z","end":"2026-05-19T03:58:48.49694Z","steps":["trace[1452927043] 'process raft request' (duration: 137.608298ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.49701Z","caller":"traceutil/trace.go:171","msg":"trace[1423168982] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"137.782431ms","start":"2026-05-19T03:58:48.359193Z","end":"2026-05-19T03:58:48.496976Z","steps":["trace[1423168982] 'process raft request' (duration: 137.662489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.497168Z","caller":"traceutil/trace.go:171","msg":"trace[510979994] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"136.94808ms","start":"2026-05-19T03:58:48.360214Z","end":"2026-05-19T03:58:48.497162Z","steps":["trace[510979994] 'process raft request' (duration: 136.706767ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.497187Z","caller":"traceutil/trace.go:171","msg":"trace[1610019857] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"137.810651ms","start":"2026-05-19T03:58:48.359352Z","end":"2026-05-19T03:58:48.497163Z","steps":["trace[1610019857] 'process raft request' (duration: 137.534997ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.497308Z","caller":"traceutil/trace.go:171","msg":"trace[368889279] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"135.38411ms","start":"2026-05-19T03:58:48.361905Z","end":"2026-05-19T03:58:48.497289Z","steps":["trace[368889279] 'process raft request' (duration: 135.147097ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.497378Z","caller":"traceutil/trace.go:171","msg":"trace[1947832480] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"136.579415ms","start":"2026-05-19T03:58:48.360786Z","end":"2026-05-19T03:58:48.497366Z","steps":["trace[1947832480] 'process raft request' (duration: 136.23842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:48.497427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.8225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-19T03:58:48.497455Z","caller":"traceutil/trace.go:171","msg":"trace[1266760260] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27; }","duration":"133.877021ms","start":"2026-05-19T03:58:48.363572Z","end":"2026-05-19T03:58:48.497449Z","steps":["trace[1266760260] 'agreement among raft nodes before linearized reading' (duration: 133.777949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:48.497492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.77472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T03:58:48.497527Z","caller":"traceutil/trace.go:171","msg":"trace[1241731236] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"135.519302ms","start":"2026-05-19T03:58:48.362002Z","end":"2026-05-19T03:58:48.497522Z","steps":["trace[1241731236] 'process raft request' (duration: 135.081636ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.497542Z","caller":"traceutil/trace.go:171","msg":"trace[655014929] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:27; }","duration":"137.853891ms","start":"2026-05-19T03:58:48.359674Z","end":"2026-05-19T03:58:48.497528Z","steps":["trace[655014929] 'agreement among raft nodes before linearized reading' (duration: 137.78322ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.628919Z","caller":"traceutil/trace.go:171","msg":"trace[932728298] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"156.433649ms","start":"2026-05-19T03:58:48.472442Z","end":"2026-05-19T03:58:48.628876Z","steps":["trace[932728298] 'process raft request' (duration: 97.960362ms)","trace[932728298] 'compare' (duration: 58.298345ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:48.628942Z","caller":"traceutil/trace.go:171","msg":"trace[1216890845] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"129.93ms","start":"2026-05-19T03:58:48.498975Z","end":"2026-05-19T03:58:48.628905Z","steps":["trace[1216890845] 'process raft request' (duration: 129.832419ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.62917Z","caller":"traceutil/trace.go:171","msg":"trace[1100292537] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"126.969502ms","start":"2026-05-19T03:58:48.502192Z","end":"2026-05-19T03:58:48.629161Z","steps":["trace[1100292537] 'process raft request' (duration: 126.648868ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.628968Z","caller":"traceutil/trace.go:171","msg":"trace[1494679949] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"125.452593ms","start":"2026-05-19T03:58:48.503495Z","end":"2026-05-19T03:58:48.628947Z","steps":["trace[1494679949] 'process raft request' (duration: 125.430312ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.629381Z","caller":"traceutil/trace.go:171","msg":"trace[1857144650] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"126.570747ms","start":"2026-05-19T03:58:48.502786Z","end":"2026-05-19T03:58:48.629356Z","steps":["trace[1857144650] 'process raft request' (duration: 126.120991ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.629427Z","caller":"traceutil/trace.go:171","msg":"trace[833892321] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:31; }","duration":"132.25507ms","start":"2026-05-19T03:58:48.497115Z","end":"2026-05-19T03:58:48.629371Z","steps":["trace[833892321] 'read index received' (duration: 73.373768ms)","trace[833892321] 'applied index is now lower than readState.Index' (duration: 58.879062ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:48.629521Z","caller":"traceutil/trace.go:171","msg":"trace[1200395746] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"126.909192ms","start":"2026-05-19T03:58:48.502598Z","end":"2026-05-19T03:58:48.629507Z","steps":["trace[1200395746] 'process raft request' (duration: 126.272304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:48.629616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.153798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T03:58:48.62965Z","caller":"traceutil/trace.go:171","msg":"trace[1990072148] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:34; }","duration":"157.200519ms","start":"2026-05-19T03:58:48.472441Z","end":"2026-05-19T03:58:48.629642Z","steps":["trace[1990072148] 'agreement among raft nodes before linearized reading' (duration: 157.107027ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.629776Z","caller":"traceutil/trace.go:171","msg":"trace[1719119029] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"126.974153ms","start":"2026-05-19T03:58:48.502675Z","end":"2026-05-19T03:58:48.629649Z","steps":["trace[1719119029] 'process raft request' (duration: 126.214543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:48.631046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.71469ms","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-19T03:58:48.631138Z","caller":"traceutil/trace.go:171","msg":"trace[1464986308] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:34; }","duration":"130.848892ms","start":"2026-05-19T03:58:48.500258Z","end":"2026-05-19T03:58:48.631107Z","steps":["trace[1464986308] 'agreement among raft nodes before linearized reading' (duration: 130.716001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:48.631416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.684211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T03:58:48.631456Z","caller":"traceutil/trace.go:171","msg":"trace[1620415169] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:34; }","duration":"127.727042ms","start":"2026-05-19T03:58:48.503719Z","end":"2026-05-19T03:58:48.631446Z","steps":["trace[1620415169] 'agreement among raft nodes before linearized reading' (duration: 127.654781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:48.845593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.550883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T03:58:48.845807Z","caller":"traceutil/trace.go:171","msg":"trace[1397254531] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"145.234856ms","start":"2026-05-19T03:58:48.700549Z","end":"2026-05-19T03:58:48.845784Z","steps":["trace[1397254531] 'process raft request' (duration: 145.163535ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.84602Z","caller":"traceutil/trace.go:171","msg":"trace[465741212] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"152.422798ms","start":"2026-05-19T03:58:48.693563Z","end":"2026-05-19T03:58:48.845986Z","steps":["trace[465741212] 'process raft request' (duration: 39.88081ms)","trace[465741212] 'compare' (duration: 110.405711ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:48.999811Z","caller":"traceutil/trace.go:171","msg":"trace[605286183] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"113.431789ms","start":"2026-05-19T03:58:48.886348Z","end":"2026-05-19T03:58:48.999779Z","steps":["trace[605286183] 'process raft request' (duration: 113.370459ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:48.999892Z","caller":"traceutil/trace.go:171","msg":"trace[1852889604] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"134.446148ms","start":"2026-05-19T03:58:48.865395Z","end":"2026-05-19T03:58:48.999842Z","steps":["trace[1852889604] 'process raft request' (duration: 80.464379ms)","trace[1852889604] 'compare' (duration: 53.658715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:49.299971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.010937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T03:58:49.30019Z","caller":"traceutil/trace.go:171","msg":"trace[100271727] linearizableReadLoop","detail":"{readStateIndex:59; appliedIndex:57; }","duration":"158.686077ms","start":"2026-05-19T03:58:49.141479Z","end":"2026-05-19T03:58:49.300165Z","steps":["trace[100271727] 'read index received' (duration: 23.335595ms)","trace[100271727] 'applied index is now lower than readState.Index' (duration: 135.349302ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:49.300526Z","caller":"traceutil/trace.go:171","msg":"trace[1139619342] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"251.080396ms","start":"2026-05-19T03:58:49.049425Z","end":"2026-05-19T03:58:49.300506Z","steps":["trace[1139619342] 'process raft request' (duration: 115.42776ms)","trace[1139619342] 'compare' (duration: 134.798725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:49.300733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.704997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T03:58:49.30078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.246244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T03:58:49.300837Z","caller":"traceutil/trace.go:171","msg":"trace[1528987506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:55; }","duration":"158.87922ms","start":"2026-05-19T03:58:49.141933Z","end":"2026-05-19T03:58:49.300812Z","steps":["trace[1528987506] 'agreement among raft nodes before linearized reading' (duration: 158.706857ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:49.300897Z","caller":"traceutil/trace.go:171","msg":"trace[1637752869] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"209.609821ms","start":"2026-05-19T03:58:49.091255Z","end":"2026-05-19T03:58:49.300864Z","steps":["trace[1637752869] 'process raft request' (duration: 208.851442ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:49.300959Z","caller":"traceutil/trace.go:171","msg":"trace[811766556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:55; }","duration":"159.406946ms","start":"2026-05-19T03:58:49.141426Z","end":"2026-05-19T03:58:49.300833Z","steps":["trace[811766556] 'agreement among raft nodes before linearized reading' (duration: 159.127432ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:49.777048Z","caller":"traceutil/trace.go:171","msg":"trace[1890141570] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"147.654107ms","start":"2026-05-19T03:58:49.629354Z","end":"2026-05-19T03:58:49.777008Z","steps":["trace[1890141570] 'process raft request' (duration: 59.950488ms)","trace[1890141570] 'compare' (duration: 87.522397ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:50.340996Z","caller":"traceutil/trace.go:171","msg":"trace[191830809] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"203.187204ms","start":"2026-05-19T03:58:50.137767Z","end":"2026-05-19T03:58:50.340954Z","steps":["trace[191830809] 'process raft request' (duration: 115.717449ms)","trace[191830809] 'compare' (duration: 87.306553ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:50.502095Z","caller":"traceutil/trace.go:171","msg":"trace[885494686] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"117.510421ms","start":"2026-05-19T03:58:50.384564Z","end":"2026-05-19T03:58:50.502075Z","steps":["trace[885494686] 'process raft request' (duration: 117.42331ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:50.694074Z","caller":"traceutil/trace.go:171","msg":"trace[741282624] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"127.632438ms","start":"2026-05-19T03:58:50.566402Z","end":"2026-05-19T03:58:50.694034Z","steps":["trace[741282624] 'process raft request' (duration: 50.989369ms)","trace[741282624] 'compare' (duration: 76.452977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:51.021588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.560369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:58:51.021808Z","caller":"traceutil/trace.go:171","msg":"trace[1600374114] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"170.621323ms","start":"2026-05-19T03:58:50.851165Z","end":"2026-05-19T03:58:51.021786Z","steps":["trace[1600374114] 'process raft request' (duration: 68.785771ms)","trace[1600374114] 'compare' (duration: 101.392167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:51.509929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.747576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:58:51.510882Z","caller":"traceutil/trace.go:171","msg":"trace[1817421151] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"143.021562ms","start":"2026-05-19T03:58:51.36782Z","end":"2026-05-19T03:58:51.510842Z","steps":["trace[1817421151] 'process raft request' (duration: 142.725128ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:51.511822Z","caller":"traceutil/trace.go:171","msg":"trace[1858367213] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"144.833944ms","start":"2026-05-19T03:58:51.366899Z","end":"2026-05-19T03:58:51.511733Z","steps":["trace[1858367213] 'process raft request' (duration: 16.282832ms)","trace[1858367213] 'compare' (duration: 124.584304ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:51.51195Z","caller":"traceutil/trace.go:171","msg":"trace[1074049521] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"144.48134ms","start":"2026-05-19T03:58:51.367427Z","end":"2026-05-19T03:58:51.511908Z","steps":["trace[1074049521] 'process raft request' (duration: 142.86313ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:51.64466Z","caller":"traceutil/trace.go:171","msg":"trace[933276074] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"127.142156ms","start":"2026-05-19T03:58:51.517489Z","end":"2026-05-19T03:58:51.644631Z","steps":["trace[933276074] 'process raft request' (duration: 124.810818ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:52.886664Z","caller":"traceutil/trace.go:171","msg":"trace[1046183384] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"145.219502ms","start":"2026-05-19T03:58:52.741424Z","end":"2026-05-19T03:58:52.886643Z","steps":["trace[1046183384] 'process raft request' (duration: 83.993501ms)","trace[1046183384] 'compare' (duration: 61.062479ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:53.081499Z","caller":"traceutil/trace.go:171","msg":"trace[695748868] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"118.322604ms","start":"2026-05-19T03:58:52.963139Z","end":"2026-05-19T03:58:53.081462Z","steps":["trace[695748868] 'process raft request' (duration: 92.294107ms)","trace[695748868] 'compare' (duration: 25.636653ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:54.854825Z","caller":"traceutil/trace.go:171","msg":"trace[743433046] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"154.143757ms","start":"2026-05-19T03:58:54.700648Z","end":"2026-05-19T03:58:54.854791Z","steps":["trace[743433046] 'process raft request' (duration: 94.917754ms)","trace[743433046] 'compare' (duration: 59.015011ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:55.150983Z","caller":"traceutil/trace.go:171","msg":"trace[405901008] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"114.971601ms","start":"2026-05-19T03:58:55.035976Z","end":"2026-05-19T03:58:55.150948Z","steps":["trace[405901008] 'process raft request' (duration: 71.409121ms)","trace[405901008] 'compare' (duration: 43.366988ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:55.520536Z","caller":"traceutil/trace.go:171","msg":"trace[1249158282] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"133.539874ms","start":"2026-05-19T03:58:55.386954Z","end":"2026-05-19T03:58:55.520494Z","steps":["trace[1249158282] 'process raft request' (duration: 76.581224ms)","trace[1249158282] 'compare' (duration: 56.612796ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:55.67597Z","caller":"traceutil/trace.go:171","msg":"trace[1382898103] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"101.608204ms","start":"2026-05-19T03:58:55.574336Z","end":"2026-05-19T03:58:55.675944Z","steps":["trace[1382898103] 'read index received' (duration: 70.662374ms)","trace[1382898103] 'applied index is now lower than readState.Index' (duration: 30.94494ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:55.677606Z","caller":"traceutil/trace.go:171","msg":"trace[265712902] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"142.152818ms","start":"2026-05-19T03:58:55.535401Z","end":"2026-05-19T03:58:55.677554Z","steps":["trace[265712902] 'process raft request' (duration: 109.65871ms)","trace[265712902] 'compare' (duration: 30.774148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:55.678521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.175244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:58:55.678766Z","caller":"traceutil/trace.go:171","msg":"trace[1453564465] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:269; }","duration":"104.477038ms","start":"2026-05-19T03:58:55.574265Z","end":"2026-05-19T03:58:55.678742Z","steps":["trace[1453564465] 'agreement among raft nodes before linearized reading' (duration: 104.152964ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:56.833449Z","caller":"traceutil/trace.go:171","msg":"trace[372952906] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"109.930121ms","start":"2026-05-19T03:58:56.723492Z","end":"2026-05-19T03:58:56.833423Z","steps":["trace[372952906] 'process raft request' (duration: 40.353997ms)","trace[372952906] 'compare' (duration: 69.444002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:58:58.59728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624389848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:58:58.597341Z","caller":"traceutil/trace.go:171","msg":"trace[1354698349] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:285; }","duration":"1.624495759s","start":"2026-05-19T03:58:56.972831Z","end":"2026-05-19T03:58:58.597327Z","steps":["trace[1354698349] 'range keys from in-memory index tree' (duration: 1.624267386s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:58.597368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:56.97281Z","time spent":"1.624550409s","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-19T03:58:58.597792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027669451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:58:58.597816Z","caller":"traceutil/trace.go:171","msg":"trace[1726251021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:285; }","duration":"1.027725882s","start":"2026-05-19T03:58:57.570084Z","end":"2026-05-19T03:58:58.597809Z","steps":["trace[1726251021] 'range keys from in-memory index tree' (duration: 1.027603531s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:58.597833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:57.570064Z","time spent":"1.027764383s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T03:58:58.598129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.896349ms","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-19T03:58:58.598149Z","caller":"traceutil/trace.go:171","msg":"trace[860128524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"897.932989ms","start":"2026-05-19T03:58:57.700212Z","end":"2026-05-19T03:58:58.598145Z","steps":["trace[860128524] 'range keys from in-memory index tree' (duration: 897.834158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:58.598163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:57.700205Z","time spent":"897.953469ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T03:58:58.598233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.085851ms","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-19T03:58:58.598253Z","caller":"traceutil/trace.go:171","msg":"trace[979564540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"898.180141ms","start":"2026-05-19T03:58:57.700069Z","end":"2026-05-19T03:58:58.598249Z","steps":["trace[979564540] 'range keys from in-memory index tree' (duration: 897.929069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:58.598266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:57.700042Z","time spent":"898.220793ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T03:58:59.201861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410491045407656,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T03:58:59.281554Z","caller":"traceutil/trace.go:171","msg":"trace[1532768710] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"678.431307ms","start":"2026-05-19T03:58:58.603095Z","end":"2026-05-19T03:58:59.281527Z","steps":["trace[1532768710] 'process raft request' (duration: 678.295065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.281695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:58.603076Z","time spent":"678.563149ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T03:58:59.610891Z","caller":"traceutil/trace.go:171","msg":"trace[1163959544] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"1.006934858s","start":"2026-05-19T03:58:58.603926Z","end":"2026-05-19T03:58:59.61086Z","steps":["trace[1163959544] 'process raft request' (duration: 1.006609944s)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:58:59.610936Z","caller":"traceutil/trace.go:171","msg":"trace[1267788733] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"1.006831316s","start":"2026-05-19T03:58:58.604072Z","end":"2026-05-19T03:58:59.610904Z","steps":["trace[1267788733] 'process raft request' (duration: 1.006671454s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.611045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:58.603909Z","time spent":"1.007059339s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:58:59.611129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:58.604066Z","time spent":"1.006968868s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:58:59.611169Z","caller":"traceutil/trace.go:171","msg":"trace[326276058] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"1.005897995s","start":"2026-05-19T03:58:58.605247Z","end":"2026-05-19T03:58:59.611145Z","steps":["trace[326276058] 'process raft request' (duration: 1.005557561s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.611233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.92372ms","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-19T03:58:59.611259Z","caller":"traceutil/trace.go:171","msg":"trace[1605300610] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"616.648527ms","start":"2026-05-19T03:58:58.994593Z","end":"2026-05-19T03:58:59.611242Z","steps":["trace[1605300610] 'process raft request' (duration: 616.406634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.611257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:58.605242Z","time spent":"1.005972346s","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T03:58:59.611298Z","caller":"traceutil/trace.go:171","msg":"trace[1735259733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:290; }","duration":"910.035281ms","start":"2026-05-19T03:58:58.701251Z","end":"2026-05-19T03:58:59.611286Z","steps":["trace[1735259733] 'agreement among raft nodes before linearized reading' (duration: 909.924849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.61135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:58.994571Z","time spent":"616.724938ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:58:59.61136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:58.701212Z","time spent":"910.138542ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T03:58:59.61113Z","caller":"traceutil/trace.go:171","msg":"trace[580868707] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:300; }","duration":"909.811718ms","start":"2026-05-19T03:58:58.701301Z","end":"2026-05-19T03:58:59.611112Z","steps":["trace[580868707] 'read index received' (duration: 580.153544ms)","trace[580868707] 'applied index is now lower than readState.Index' (duration: 329.657404ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:58:59.615501Z","caller":"traceutil/trace.go:171","msg":"trace[1607225861] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"329.645804ms","start":"2026-05-19T03:58:59.285838Z","end":"2026-05-19T03:58:59.615484Z","steps":["trace[1607225861] 'process raft request' (duration: 329.579444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.61562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:59.285822Z","time spent":"329.737345ms","remote":"127.0.0.1:34008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T03:58:59.615908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.677386ms","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-19T03:58:59.616027Z","caller":"traceutil/trace.go:171","msg":"trace[2048057844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:291; }","duration":"330.859768ms","start":"2026-05-19T03:58:59.285142Z","end":"2026-05-19T03:58:59.616001Z","steps":["trace[2048057844] 'agreement among raft nodes before linearized reading' (duration: 330.512814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:58:59.616093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:58:59.28512Z","time spent":"330.962869ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T03:58:59.806059Z","caller":"traceutil/trace.go:171","msg":"trace[2097418345] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"123.700127ms","start":"2026-05-19T03:58:59.682328Z","end":"2026-05-19T03:58:59.806028Z","steps":["trace[2097418345] 'process raft request' (duration: 41.550649ms)","trace[2097418345] 'compare' (duration: 81.988107ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:00.17103Z","caller":"traceutil/trace.go:171","msg":"trace[615265454] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"232.476888ms","start":"2026-05-19T03:58:59.938522Z","end":"2026-05-19T03:59:00.170999Z","steps":["trace[615265454] 'process raft request' (duration: 175.804189ms)","trace[615265454] 'compare' (duration: 56.537577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:00.935186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.193828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-19T03:59:00.935275Z","caller":"traceutil/trace.go:171","msg":"trace[1437533944] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:317; }","duration":"259.330369ms","start":"2026-05-19T03:59:00.675925Z","end":"2026-05-19T03:59:00.935256Z","steps":["trace[1437533944] 'range keys from in-memory index tree' (duration: 259.091606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:00.93534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.280844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T03:59:00.935401Z","caller":"traceutil/trace.go:171","msg":"trace[504790647] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:317; }","duration":"360.397136ms","start":"2026-05-19T03:59:00.57499Z","end":"2026-05-19T03:59:00.935387Z","steps":["trace[504790647] 'range keys from in-memory index tree' (duration: 360.196544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:00.935381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.527349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-19T03:59:00.935433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:00.574976Z","time spent":"360.450947ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-19T03:59:00.935451Z","caller":"traceutil/trace.go:171","msg":"trace[1688713438] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:317; }","duration":"209.63821ms","start":"2026-05-19T03:59:00.725793Z","end":"2026-05-19T03:59:00.935431Z","steps":["trace[1688713438] 'range keys from in-memory index tree' (duration: 209.388987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:00.935434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.095828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-19T03:59:00.935531Z","caller":"traceutil/trace.go:171","msg":"trace[55298047] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:317; }","duration":"309.250981ms","start":"2026-05-19T03:59:00.626259Z","end":"2026-05-19T03:59:00.93551Z","steps":["trace[55298047] 'range keys from in-memory index tree' (duration: 308.998848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:00.935534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.406385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-05-19T03:59:00.935577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:00.626244Z","time spent":"309.322901ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-19T03:59:00.935602Z","caller":"traceutil/trace.go:171","msg":"trace[398653449] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:317; }","duration":"159.505597ms","start":"2026-05-19T03:59:00.776077Z","end":"2026-05-19T03:59:00.935582Z","steps":["trace[398653449] 'range keys from in-memory index tree' (duration: 159.319704ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:59:01.6945Z","caller":"traceutil/trace.go:171","msg":"trace[804841735] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"123.180439ms","start":"2026-05-19T03:59:01.571287Z","end":"2026-05-19T03:59:01.694467Z","steps":["trace[804841735] 'process raft request' (duration: 123.016257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:01.949567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.556898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:59:01.949799Z","caller":"traceutil/trace.go:171","msg":"trace[572132399] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"251.473467ms","start":"2026-05-19T03:59:01.698308Z","end":"2026-05-19T03:59:01.949781Z","steps":["trace[572132399] 'process raft request' (duration: 251.365045ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:59:01.949802Z","caller":"traceutil/trace.go:171","msg":"trace[1954765185] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"251.425386ms","start":"2026-05-19T03:59:01.698319Z","end":"2026-05-19T03:59:01.949745Z","steps":["trace[1954765185] 'process raft request' (duration: 65.598325ms)","trace[1954765185] 'compare' (duration: 185.417697ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:01.950055Z","caller":"traceutil/trace.go:171","msg":"trace[1675536451] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"251.701839ms","start":"2026-05-19T03:59:01.698332Z","end":"2026-05-19T03:59:01.950033Z","steps":["trace[1675536451] 'process raft request' (duration: 251.406376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:03.058275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.682645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:59:03.059353Z","caller":"traceutil/trace.go:171","msg":"trace[976203110] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"658.934263ms","start":"2026-05-19T03:59:02.400385Z","end":"2026-05-19T03:59:03.059319Z","steps":["trace[976203110] 'process raft request' (duration: 113.118015ms)","trace[976203110] 'compare' (duration: 544.159039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:03.059489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:02.400367Z","time spent":"659.069804ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:03.390156Z","caller":"traceutil/trace.go:171","msg":"trace[2050034810] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"219.648988ms","start":"2026-05-19T03:59:03.17048Z","end":"2026-05-19T03:59:03.390129Z","steps":["trace[2050034810] 'read index received' (duration: 178.310695ms)","trace[2050034810] 'applied index is now lower than readState.Index' (duration: 41.337483ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:03.390381Z","caller":"traceutil/trace.go:171","msg":"trace[661447966] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"324.074255ms","start":"2026-05-19T03:59:03.066276Z","end":"2026-05-19T03:59:03.390351Z","steps":["trace[661447966] 'process raft request' (duration: 282.52623ms)","trace[661447966] 'compare' (duration: 41.197061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:03.390499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:03.066255Z","time spent":"324.183047ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:59:03.390731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.095873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:03.3908Z","caller":"traceutil/trace.go:171","msg":"trace[1786968070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"220.350165ms","start":"2026-05-19T03:59:03.170434Z","end":"2026-05-19T03:59:03.390784Z","steps":["trace[1786968070] 'agreement among raft nodes before linearized reading' (duration: 219.792649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:04.648924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.060653ms","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-19T03:59:04.648983Z","caller":"traceutil/trace.go:171","msg":"trace[1983400735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"694.174304ms","start":"2026-05-19T03:59:03.954796Z","end":"2026-05-19T03:59:04.64897Z","steps":["trace[1983400735] 'range keys from in-memory index tree' (duration: 693.961962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:04.649012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:03.954776Z","time spent":"694.229514ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T03:59:04.648999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.864051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T03:59:04.649018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.537878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T03:59:04.649049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"info","ts":"2026-05-19T03:59:04.649074Z","caller":"traceutil/trace.go:171","msg":"trace[1697543709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:363; }","duration":"480.988522ms","start":"2026-05-19T03:59:04.168066Z","end":"2026-05-19T03:59:04.649055Z","steps":["trace[1697543709] 'range keys from in-memory index tree' (duration: 480.77627ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:59:04.649083Z","caller":"traceutil/trace.go:171","msg":"trace[94254751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"694.618179ms","start":"2026-05-19T03:59:03.95445Z","end":"2026-05-19T03:59:04.649068Z","steps":["trace[94254751] 'range keys from in-memory index tree' (duration: 694.364756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:04.649058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.487577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-19T03:59:04.649118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:04.168049Z","time spent":"481.060422ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T03:59:04.649156Z","caller":"traceutil/trace.go:171","msg":"trace[154812721] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"347.642031ms","start":"2026-05-19T03:59:04.301506Z","end":"2026-05-19T03:59:04.649148Z","steps":["trace[154812721] 'process raft request' (duration: 260.341341ms)","trace[154812721] 'compare' (duration: 87.007947ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:04.649162Z","caller":"traceutil/trace.go:171","msg":"trace[771627347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"694.634119ms","start":"2026-05-19T03:59:03.954503Z","end":"2026-05-19T03:59:04.649138Z","steps":["trace[771627347] 'range keys from in-memory index tree' (duration: 694.388037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:04.649244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:04.301483Z","time spent":"347.695542ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:59:04.649248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:03.954498Z","time spent":"694.71392ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T03:59:04.649103Z","caller":"traceutil/trace.go:171","msg":"trace[1815635999] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:363; }","duration":"388.167731ms","start":"2026-05-19T03:59:04.260922Z","end":"2026-05-19T03:59:04.64909Z","steps":["trace[1815635999] 'range keys from in-memory index tree' (duration: 387.967769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:04.649334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:04.260852Z","time spent":"388.478625ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4817,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-19T03:59:04.649114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:03.954382Z","time spent":"694.7267ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T03:59:05.186915Z","caller":"traceutil/trace.go:171","msg":"trace[1447736889] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"533.40937ms","start":"2026-05-19T03:59:04.653479Z","end":"2026-05-19T03:59:05.186889Z","steps":["trace[1447736889] 'process raft request' (duration: 533.206468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:05.187076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:04.65346Z","time spent":"533.532131ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:05.187387Z","caller":"traceutil/trace.go:171","msg":"trace[1205301703] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"533.214558ms","start":"2026-05-19T03:59:04.654112Z","end":"2026-05-19T03:59:05.187326Z","steps":["trace[1205301703] 'process raft request' (duration: 532.739042ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:59:05.187427Z","caller":"traceutil/trace.go:171","msg":"trace[1886119361] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"529.226563ms","start":"2026-05-19T03:59:04.658173Z","end":"2026-05-19T03:59:05.187399Z","steps":["trace[1886119361] 'process raft request' (duration: 529.050781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:05.187597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:04.654097Z","time spent":"533.376149ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:59:05.187642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:04.658163Z","time spent":"529.345035ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:10.889962Z","caller":"traceutil/trace.go:171","msg":"trace[1744697121] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"158.301758ms","start":"2026-05-19T03:59:10.731645Z","end":"2026-05-19T03:59:10.889946Z","steps":["trace[1744697121] 'process raft request' (duration: 158.206177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:11.439299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.836027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T03:59:11.439385Z","caller":"traceutil/trace.go:171","msg":"trace[1029323735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"230.972808ms","start":"2026-05-19T03:59:11.208392Z","end":"2026-05-19T03:59:11.439365Z","steps":["trace[1029323735] 'range keys from in-memory index tree' (duration: 230.691396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:11.439812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.206199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-19T03:59:11.439854Z","caller":"traceutil/trace.go:171","msg":"trace[941544797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"228.30337ms","start":"2026-05-19T03:59:11.21154Z","end":"2026-05-19T03:59:11.439844Z","steps":["trace[941544797] 'range keys from in-memory index tree' (duration: 227.512501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:22.085704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.084164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T03:59:22.085789Z","caller":"traceutil/trace.go:171","msg":"trace[1033836823] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:472; }","duration":"141.226985ms","start":"2026-05-19T03:59:21.944544Z","end":"2026-05-19T03:59:22.085771Z","steps":["trace[1033836823] 'range keys from in-memory index tree' (duration: 140.979143ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:59:23.827768Z","caller":"traceutil/trace.go:171","msg":"trace[317302722] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"123.423108ms","start":"2026-05-19T03:59:23.704245Z","end":"2026-05-19T03:59:23.827668Z","steps":["trace[317302722] 'process raft request' (duration: 120.401448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:24.679662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410491045408120,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T03:59:24.900926Z","caller":"traceutil/trace.go:171","msg":"trace[1045189833] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"959.849523ms","start":"2026-05-19T03:59:23.941051Z","end":"2026-05-19T03:59:24.900901Z","steps":["trace[1045189833] 'process raft request' (duration: 959.712061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:24.901148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:23.941033Z","time spent":"960.050435ms","remote":"127.0.0.1:33984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T03:59:25.882984Z","caller":"traceutil/trace.go:171","msg":"trace[1290298089] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.874238092s","start":"2026-05-19T03:59:24.008721Z","end":"2026-05-19T03:59:25.882959Z","steps":["trace[1290298089] 'process raft request' (duration: 1.872841788s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.883141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:24.008699Z","time spent":"1.874360313s","remote":"127.0.0.1:33984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:25.883526Z","caller":"traceutil/trace.go:171","msg":"trace[393370615] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"1.704130754s","start":"2026-05-19T03:59:24.179365Z","end":"2026-05-19T03:59:25.883496Z","steps":["trace[393370615] 'read index received' (duration: 721.450485ms)","trace[393370615] 'applied index is now lower than readState.Index' (duration: 982.678399ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:25.88363Z","caller":"traceutil/trace.go:171","msg":"trace[517372044] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.158763907s","start":"2026-05-19T03:59:24.724852Z","end":"2026-05-19T03:59:25.883616Z","steps":["trace[517372044] 'process raft request' (duration: 1.158038859s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.883714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:24.724828Z","time spent":"1.158846457s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:59:25.883855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704469419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:25.883898Z","caller":"traceutil/trace.go:171","msg":"trace[201597520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"1.704549689s","start":"2026-05-19T03:59:24.179336Z","end":"2026-05-19T03:59:25.883885Z","steps":["trace[201597520] 'agreement among raft nodes before linearized reading' (duration: 1.704293407s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.883926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:24.17932Z","time spent":"1.70459949s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T03:59:25.909567Z","caller":"traceutil/trace.go:171","msg":"trace[1520012463] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"851.705952ms","start":"2026-05-19T03:59:25.057835Z","end":"2026-05-19T03:59:25.909541Z","steps":["trace[1520012463] 'process raft request' (duration: 851.163916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.909828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:25.057815Z","time spent":"851.914584ms","remote":"127.0.0.1:33984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T03:59:25.91003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.279082ms","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-19T03:59:25.910086Z","caller":"traceutil/trace.go:171","msg":"trace[276407196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"408.375803ms","start":"2026-05-19T03:59:25.501697Z","end":"2026-05-19T03:59:25.910073Z","steps":["trace[276407196] 'agreement among raft nodes before linearized reading' (duration: 408.195031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.910113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:25.501679Z","time spent":"408.428164ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T03:59:25.910259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.520232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T03:59:25.910352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.330714ms","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-19T03:59:25.910379Z","caller":"traceutil/trace.go:171","msg":"trace[1456423820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"405.367984ms","start":"2026-05-19T03:59:25.505005Z","end":"2026-05-19T03:59:25.910373Z","steps":["trace[1456423820] 'agreement among raft nodes before linearized reading' (duration: 405.313473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.910397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:25.505Z","time spent":"405.393264ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T03:59:25.910369Z","caller":"traceutil/trace.go:171","msg":"trace[495749520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"918.696624ms","start":"2026-05-19T03:59:24.991653Z","end":"2026-05-19T03:59:25.910349Z","steps":["trace[495749520] 'agreement among raft nodes before linearized reading' (duration: 918.410611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:25.910513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:24.99163Z","time spent":"918.869335ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T03:59:31.600726Z","caller":"traceutil/trace.go:171","msg":"trace[1786525412] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"182.032069ms","start":"2026-05-19T03:59:31.41867Z","end":"2026-05-19T03:59:31.600703Z","steps":["trace[1786525412] 'read index received' (duration: 181.881811ms)","trace[1786525412] 'applied index is now lower than readState.Index' (duration: 149.378µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:31.600768Z","caller":"traceutil/trace.go:171","msg":"trace[37403192] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"211.922384ms","start":"2026-05-19T03:59:31.388823Z","end":"2026-05-19T03:59:31.600746Z","steps":["trace[37403192] 'process raft request' (duration: 211.741974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:31.600915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.169417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:31.600975Z","caller":"traceutil/trace.go:171","msg":"trace[1376172633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"182.241231ms","start":"2026-05-19T03:59:31.418721Z","end":"2026-05-19T03:59:31.600962Z","steps":["trace[1376172633] 'agreement among raft nodes before linearized reading' (duration: 182.115954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:31.601256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.539697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:31.601307Z","caller":"traceutil/trace.go:171","msg":"trace[1257012648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:586; }","duration":"182.648353ms","start":"2026-05-19T03:59:31.418647Z","end":"2026-05-19T03:59:31.601295Z","steps":["trace[1257012648] 'agreement among raft nodes before linearized reading' (duration: 182.538057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:31.601545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.51861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:31.601598Z","caller":"traceutil/trace.go:171","msg":"trace[713512606] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"156.584903ms","start":"2026-05-19T03:59:31.444998Z","end":"2026-05-19T03:59:31.601583Z","steps":["trace[713512606] 'agreement among raft nodes before linearized reading' (duration: 156.504489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:31.601872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.227354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:31.601925Z","caller":"traceutil/trace.go:171","msg":"trace[1124140119] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"156.288508ms","start":"2026-05-19T03:59:31.445623Z","end":"2026-05-19T03:59:31.601911Z","steps":["trace[1124140119] 'agreement among raft nodes before linearized reading' (duration: 156.218884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:31.602041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.972104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:31.602126Z","caller":"traceutil/trace.go:171","msg":"trace[1170920499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"157.08057ms","start":"2026-05-19T03:59:31.445033Z","end":"2026-05-19T03:59:31.602114Z","steps":["trace[1170920499] 'agreement among raft nodes before linearized reading' (duration: 156.88727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:32.373566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.055171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:59:32.373664Z","caller":"traceutil/trace.go:171","msg":"trace[702158711] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"203.924967ms","start":"2026-05-19T03:59:32.169724Z","end":"2026-05-19T03:59:32.373649Z","steps":["trace[702158711] 'read index received' (duration: 38.462µs)","trace[702158711] 'applied index is now lower than readState.Index' (duration: 203.885555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:32.373736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.020382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:32.373765Z","caller":"traceutil/trace.go:171","msg":"trace[100622738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"204.074995ms","start":"2026-05-19T03:59:32.169673Z","end":"2026-05-19T03:59:32.373748Z","steps":["trace[100622738] 'agreement among raft nodes before linearized reading' (duration: 204.015442ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:59:32.373796Z","caller":"traceutil/trace.go:171","msg":"trace[815812333] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"391.940776ms","start":"2026-05-19T03:59:31.981765Z","end":"2026-05-19T03:59:32.373706Z","steps":["trace[815812333] 'process raft request' (duration: 129.681714ms)","trace[815812333] 'compare' (duration: 261.932985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:32.373981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:31.981749Z","time spent":"392.143787ms","remote":"127.0.0.1:33974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:32.971896Z","caller":"traceutil/trace.go:171","msg":"trace[97554496] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"583.572187ms","start":"2026-05-19T03:59:32.388291Z","end":"2026-05-19T03:59:32.971863Z","steps":["trace[97554496] 'process raft request' (duration: 549.202864ms)","trace[97554496] 'compare' (duration: 33.533948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:32.973982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:32.388256Z","time spent":"585.52204ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:59:34.670361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410491045408311,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:59:35.047217Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017298451s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:59:35.047665Z","caller":"traceutil/trace.go:171","msg":"trace[1662700094] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.017808027s","start":"2026-05-19T03:59:34.029835Z","end":"2026-05-19T03:59:35.047643Z","steps":["trace[1662700094] 'process raft request' (duration: 1.017620057s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:35.047818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:34.029802Z","time spent":"1.017923463s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:35.317349Z","caller":"traceutil/trace.go:171","msg":"trace[115723314] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.28701351s","start":"2026-05-19T03:59:34.030309Z","end":"2026-05-19T03:59:35.317322Z","steps":["trace[115723314] 'process raft request' (duration: 1.205864603s)","trace[115723314] 'compare' (duration: 80.937967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:35.317576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:34.030293Z","time spent":"1.28720784s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:35.317577Z","caller":"traceutil/trace.go:171","msg":"trace[1977959079] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.287102865s","start":"2026-05-19T03:59:34.03044Z","end":"2026-05-19T03:59:35.317543Z","steps":["trace[1977959079] 'process raft request' (duration: 1.286836471s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:35.31769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:34.030423Z","time spent":"1.28720791s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:35.31779Z","caller":"traceutil/trace.go:171","msg":"trace[150961777] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.28720387s","start":"2026-05-19T03:59:34.03058Z","end":"2026-05-19T03:59:35.317784Z","steps":["trace[150961777] 'process raft request' (duration: 1.286888874s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:35.317853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:34.030573Z","time spent":"1.287239772s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:59:35.317767Z","caller":"traceutil/trace.go:171","msg":"trace[2113779950] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:630; }","duration":"1.14789736s","start":"2026-05-19T03:59:34.169821Z","end":"2026-05-19T03:59:35.317718Z","steps":["trace[2113779950] 'read index received' (duration: 877.658124ms)","trace[2113779950] 'applied index is now lower than readState.Index' (duration: 270.237916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:59:35.318022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148183814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:35.318089Z","caller":"traceutil/trace.go:171","msg":"trace[767425905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"1.14829566s","start":"2026-05-19T03:59:34.169776Z","end":"2026-05-19T03:59:35.318072Z","steps":["trace[767425905] 'agreement among raft nodes before linearized reading' (duration: 1.148177974s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:35.318132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:34.169756Z","time spent":"1.148369094s","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T03:59:36.951237Z","caller":"traceutil/trace.go:171","msg":"trace[2026625461] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"419.084637ms","start":"2026-05-19T03:59:36.532119Z","end":"2026-05-19T03:59:36.951204Z","steps":["trace[2026625461] 'process raft request' (duration: 416.97117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:36.951393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:36.532107Z","time spent":"419.215653ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T03:59:51.292532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410491045408608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:59:51.792885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410491045408608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:59:51.931015Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.451440289s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:59:51.931582Z","caller":"traceutil/trace.go:171","msg":"trace[735841384] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"1.13991919s","start":"2026-05-19T03:59:50.79163Z","end":"2026-05-19T03:59:51.931549Z","steps":["trace[735841384] 'read index received' (duration: 1.139509762s)","trace[735841384] 'applied index is now lower than readState.Index' (duration: 408.198µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:59:51.931631Z","caller":"traceutil/trace.go:171","msg":"trace[245713008] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.452134188s","start":"2026-05-19T03:59:50.479472Z","end":"2026-05-19T03:59:51.931606Z","steps":["trace[245713008] 'process raft request' (duration: 1.451894188s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.931882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.112669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T03:59:51.931914Z","caller":"traceutil/trace.go:171","msg":"trace[778905901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"912.176101ms","start":"2026-05-19T03:59:51.019728Z","end":"2026-05-19T03:59:51.931904Z","steps":["trace[778905901] 'agreement among raft nodes before linearized reading' (duration: 912.064786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.93195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.019708Z","time spent":"912.234594ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T03:59:51.932122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.236663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:51.932208Z","caller":"traceutil/trace.go:171","msg":"trace[2094595833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"763.340607ms","start":"2026-05-19T03:59:51.168853Z","end":"2026-05-19T03:59:51.932194Z","steps":["trace[2094595833] 'agreement among raft nodes before linearized reading' (duration: 763.18943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.932408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.168846Z","time spent":"763.548336ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T03:59:51.932121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:50.479453Z","time spent":"1.452425771s","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:59:51.932179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.079596ms","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-19T03:59:51.932651Z","caller":"traceutil/trace.go:171","msg":"trace[670936271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"539.601438ms","start":"2026-05-19T03:59:51.393033Z","end":"2026-05-19T03:59:51.932635Z","steps":["trace[670936271] 'agreement among raft nodes before linearized reading' (duration: 538.93655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.932692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.393017Z","time spent":"539.666892ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T03:59:51.931749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140091488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T03:59:51.932768Z","caller":"traceutil/trace.go:171","msg":"trace[880109729] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:753; }","duration":"1.141149603s","start":"2026-05-19T03:59:50.791598Z","end":"2026-05-19T03:59:51.932747Z","steps":["trace[880109729] 'agreement among raft nodes before linearized reading' (duration: 1.140075947s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.932226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.589952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T03:59:51.933179Z","caller":"traceutil/trace.go:171","msg":"trace[2143332687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"783.582925ms","start":"2026-05-19T03:59:51.149587Z","end":"2026-05-19T03:59:51.93317Z","steps":["trace[2143332687] 'agreement among raft nodes before linearized reading' (duration: 782.509359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.933206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.149561Z","time spent":"783.637368ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T03:59:51.932318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.174693ms","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-19T03:59:51.933278Z","caller":"traceutil/trace.go:171","msg":"trace[718643896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"534.196227ms","start":"2026-05-19T03:59:51.399076Z","end":"2026-05-19T03:59:51.933272Z","steps":["trace[718643896] 'agreement among raft nodes before linearized reading' (duration: 533.088029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.933296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.399052Z","time spent":"534.239549ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T03:59:51.932309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.819391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:59:51.933339Z","caller":"traceutil/trace.go:171","msg":"trace[639957152] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:753; }","duration":"662.935179ms","start":"2026-05-19T03:59:51.270398Z","end":"2026-05-19T03:59:51.933333Z","steps":["trace[639957152] 'agreement among raft nodes before linearized reading' (duration: 661.764009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.933358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.270334Z","time spent":"663.016913ms","remote":"127.0.0.1:34312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:59:51.933399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.346922ms","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-19T03:59:51.933441Z","caller":"traceutil/trace.go:171","msg":"trace[744656316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"538.109525ms","start":"2026-05-19T03:59:51.39532Z","end":"2026-05-19T03:59:51.933429Z","steps":["trace[744656316] 'agreement among raft nodes before linearized reading' (duration: 536.991097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:59:51.933477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:51.395301Z","time spent":"538.163207ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T03:59:51.93405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:59:50.791583Z","time spent":"1.142447029s","remote":"127.0.0.1:34344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:00:10.80215Z","caller":"traceutil/trace.go:171","msg":"trace[1899530050] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"184.917882ms","start":"2026-05-19T04:00:10.617204Z","end":"2026-05-19T04:00:10.802122Z","steps":["trace[1899530050] 'process raft request' (duration: 147.422465ms)","trace[1899530050] 'compare' (duration: 37.398123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:00:46.411472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.831884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:00:46.411833Z","caller":"traceutil/trace.go:171","msg":"trace[1920407959] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"153.98272ms","start":"2026-05-19T04:00:46.25782Z","end":"2026-05-19T04:00:46.411803Z","steps":["trace[1920407959] 'process raft request' (duration: 39.499239ms)","trace[1920407959] 'compare' (duration: 113.710611ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:00:46.41214Z","caller":"traceutil/trace.go:171","msg":"trace[219014413] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"152.650315ms","start":"2026-05-19T04:00:46.259472Z","end":"2026-05-19T04:00:46.412123Z","steps":["trace[219014413] 'read index received' (duration: 738.129µs)","trace[219014413] 'applied index is now lower than readState.Index' (duration: 151.911136ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:00:46.412293Z","caller":"traceutil/trace.go:171","msg":"trace[1976428448] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"152.652465ms","start":"2026-05-19T04:00:46.25961Z","end":"2026-05-19T04:00:46.412263Z","steps":["trace[1976428448] 'process raft request' (duration: 152.129331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:00:46.412376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.881571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T04:00:46.4125Z","caller":"traceutil/trace.go:171","msg":"trace[2086542725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"153.004294ms","start":"2026-05-19T04:00:46.259467Z","end":"2026-05-19T04:00:46.412471Z","steps":["trace[2086542725] 'agreement among raft nodes before linearized reading' (duration: 152.782529ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:01:46.872095Z","caller":"traceutil/trace.go:171","msg":"trace[1286724798] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"122.901381ms","start":"2026-05-19T04:01:46.749168Z","end":"2026-05-19T04:01:46.872069Z","steps":["trace[1286724798] 'read index received' (duration: 57.031698ms)","trace[1286724798] 'applied index is now lower than readState.Index' (duration: 65.868483ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:01:46.872217Z","caller":"traceutil/trace.go:171","msg":"trace[169897531] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"158.686335ms","start":"2026-05-19T04:01:46.713491Z","end":"2026-05-19T04:01:46.872177Z","steps":["trace[169897531] 'process raft request' (duration: 92.804612ms)","trace[169897531] 'compare' (duration: 65.664879ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:01:46.872345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.165157ms","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-19T04:01:46.872409Z","caller":"traceutil/trace.go:171","msg":"trace[1955468375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"123.282098ms","start":"2026-05-19T04:01:46.749114Z","end":"2026-05-19T04:01:46.872396Z","steps":["trace[1955468375] 'agreement among raft nodes before linearized reading' (duration: 123.063144ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:01:47.141395Z","caller":"traceutil/trace.go:171","msg":"trace[965771906] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"263.799745ms","start":"2026-05-19T04:01:46.877569Z","end":"2026-05-19T04:01:47.141369Z","steps":["trace[965771906] 'process raft request' (duration: 263.755885ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:01:47.141505Z","caller":"traceutil/trace.go:171","msg":"trace[1807200002] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"265.14631ms","start":"2026-05-19T04:01:46.876333Z","end":"2026-05-19T04:01:47.14148Z","steps":["trace[1807200002] 'process raft request' (duration: 241.057735ms)","trace[1807200002] 'compare' (duration: 23.81729ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:01:47.174251Z","caller":"traceutil/trace.go:171","msg":"trace[499444817] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"245.428623ms","start":"2026-05-19T04:01:46.928782Z","end":"2026-05-19T04:01:47.174211Z","steps":["trace[499444817] 'process raft request' (duration: 245.23476ms)"],"step_count":1}