{"level":"warn","ts":"2026-03-30T17:51:06.136949Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T17:51:06.137104Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-30T17:51:06.137187Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T17:51:06.137208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-03-30T17:51:06.137249Z","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-03-30T17:51:06.137885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-03-30T17:51:06.137986Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-30T17:51:06.147749Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.538768ms"} {"level":"info","ts":"2026-03-30T17:51:06.189301Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-03-30T17:51:06.189495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-03-30T17:51:06.18955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-03-30T17:51:06.189777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-30T17:51:06.189818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-03-30T17:51:06.189884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-03-30T17:51:06.208271Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-30T17:51:06.217753Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-30T17:51:06.221021Z","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-03-30T17:51:06.224145Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-30T17:51:06.227269Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-30T17:51:06.227399Z","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-03-30T17:51:06.236208Z","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-03-30T17:51:06.236267Z","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-03-30T17:51:06.227687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-03-30T17:51:06.236639Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-03-30T17:51:06.238677Z","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-03-30T17:51:06.23885Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-03-30T17:51:06.23895Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-03-30T17:51:06.239223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-30T17:51:06.239289Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-30T17:51:07.190888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-03-30T17:51:07.190952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-30T17:51:07.190986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-03-30T17:51:07.191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-03-30T17:51:07.191009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-03-30T17:51:07.19102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-03-30T17:51:07.191072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-03-30T17:51:07.232453Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-03-30T17:51:07.232509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:51:07.23265Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:51:07.232698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:51:07.233038Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-30T17:51:07.233076Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-30T17:51:07.235629Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-30T17:51:07.236118Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:51:07.236365Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:51:07.236449Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:51:07.236963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"info","ts":"2026-03-30T17:51:08.684774Z","caller":"traceutil/trace.go:171","msg":"trace[801736676] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"140.841118ms","start":"2026-03-30T17:51:08.543892Z","end":"2026-03-30T17:51:08.684734Z","steps":["trace[801736676] 'process raft request' (duration: 125.886948ms)","trace[801736676] 'compare' (duration: 14.759065ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:08.685153Z","caller":"traceutil/trace.go:171","msg":"trace[970218369] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:7; }","duration":"125.703274ms","start":"2026-03-30T17:51:08.559121Z","end":"2026-03-30T17:51:08.684824Z","steps":["trace[970218369] 'read index received' (duration: 110.748924ms)","trace[970218369] 'applied index is now lower than readState.Index' (duration: 14.95342ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:08.685055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.249298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-30T17:51:08.685172Z","caller":"traceutil/trace.go:171","msg":"trace[562853184] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"134.303645ms","start":"2026-03-30T17:51:08.550834Z","end":"2026-03-30T17:51:08.685138Z","steps":["trace[562853184] 'process raft request' (duration: 133.929017ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.68525Z","caller":"traceutil/trace.go:171","msg":"trace[270853705] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:0; response_revision:11; }","duration":"141.489183ms","start":"2026-03-30T17:51:08.543739Z","end":"2026-03-30T17:51:08.685229Z","steps":["trace[270853705] 'agreement among raft nodes before linearized reading' (duration: 141.213547ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.68539Z","caller":"traceutil/trace.go:171","msg":"trace[796462063] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"128.455618ms","start":"2026-03-30T17:51:08.556878Z","end":"2026-03-30T17:51:08.685334Z","steps":["trace[796462063] 'process raft request' (duration: 127.912245ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.685389Z","caller":"traceutil/trace.go:171","msg":"trace[2092226122] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"141.37492ms","start":"2026-03-30T17:51:08.543985Z","end":"2026-03-30T17:51:08.68536Z","steps":["trace[2092226122] 'process raft request' (duration: 140.698855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:08.685517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.308019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-30T17:51:08.68556Z","caller":"traceutil/trace.go:171","msg":"trace[459259831] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"141.375012ms","start":"2026-03-30T17:51:08.544174Z","end":"2026-03-30T17:51:08.685549Z","steps":["trace[459259831] 'agreement among raft nodes before linearized reading' (duration: 141.30032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:08.685693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.492757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-03-30T17:51:08.685769Z","caller":"traceutil/trace.go:171","msg":"trace[1889480017] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"141.166026ms","start":"2026-03-30T17:51:08.544592Z","end":"2026-03-30T17:51:08.685758Z","steps":["trace[1889480017] 'process raft request' (duration: 140.142442ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.685768Z","caller":"traceutil/trace.go:171","msg":"trace[297032225] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"139.582839ms","start":"2026-03-30T17:51:08.546159Z","end":"2026-03-30T17:51:08.685742Z","steps":["trace[297032225] 'agreement among raft nodes before linearized reading' (duration: 139.477487ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.685805Z","caller":"traceutil/trace.go:171","msg":"trace[277246990] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"141.75863ms","start":"2026-03-30T17:51:08.544022Z","end":"2026-03-30T17:51:08.685781Z","steps":["trace[277246990] 'process raft request' (duration: 140.688974ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.685225Z","caller":"traceutil/trace.go:171","msg":"trace[897672207] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"141.300859ms","start":"2026-03-30T17:51:08.543904Z","end":"2026-03-30T17:51:08.685205Z","steps":["trace[897672207] 'process raft request' (duration: 140.750596ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:08.689893Z","caller":"traceutil/trace.go:171","msg":"trace[1229989121] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"127.847224ms","start":"2026-03-30T17:51:08.562021Z","end":"2026-03-30T17:51:08.689868Z","steps":["trace[1229989121] 'process raft request' (duration: 127.729572ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:18.03407Z","caller":"traceutil/trace.go:171","msg":"trace[426297104] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"103.399001ms","start":"2026-03-30T17:51:17.930625Z","end":"2026-03-30T17:51:18.034024Z","steps":["trace[426297104] 'process raft request' (duration: 98.535506ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:18.034127Z","caller":"traceutil/trace.go:171","msg":"trace[1846215421] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"101.351518ms","start":"2026-03-30T17:51:17.932655Z","end":"2026-03-30T17:51:18.034006Z","steps":["trace[1846215421] 'process raft request' (duration: 101.295957ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:30.256651Z","caller":"traceutil/trace.go:171","msg":"trace[1379971852] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"141.726529ms","start":"2026-03-30T17:51:30.114898Z","end":"2026-03-30T17:51:30.256624Z","steps":["trace[1379971852] 'process raft request' (duration: 141.177298ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:35.451309Z","caller":"traceutil/trace.go:171","msg":"trace[104279150] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"110.083598ms","start":"2026-03-30T17:51:35.341182Z","end":"2026-03-30T17:51:35.451266Z","steps":["trace[104279150] 'process raft request' (duration: 109.825323ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:35.451595Z","caller":"traceutil/trace.go:171","msg":"trace[1105587264] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"107.322605ms","start":"2026-03-30T17:51:35.34426Z","end":"2026-03-30T17:51:35.451583Z","steps":["trace[1105587264] 'process raft request' (duration: 106.911877ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:35.568286Z","caller":"traceutil/trace.go:171","msg":"trace[123487368] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"118.650943ms","start":"2026-03-30T17:51:35.449591Z","end":"2026-03-30T17:51:35.568242Z","steps":["trace[123487368] 'process raft request' (duration: 54.133772ms)","trace[123487368] 'compare' (duration: 64.161804ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:35.568429Z","caller":"traceutil/trace.go:171","msg":"trace[615443067] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"100.699557ms","start":"2026-03-30T17:51:35.467716Z","end":"2026-03-30T17:51:35.568416Z","steps":["trace[615443067] 'process raft request' (duration: 100.460913ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:35.568373Z","caller":"traceutil/trace.go:171","msg":"trace[1878477018] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"115.810598ms","start":"2026-03-30T17:51:35.452538Z","end":"2026-03-30T17:51:35.568348Z","steps":["trace[1878477018] 'read index received' (duration: 51.161855ms)","trace[1878477018] 'applied index is now lower than readState.Index' (duration: 64.645673ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:35.568637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.082083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:51:35.568686Z","caller":"traceutil/trace.go:171","msg":"trace[1013696573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"116.177016ms","start":"2026-03-30T17:51:35.4525Z","end":"2026-03-30T17:51:35.568677Z","steps":["trace[1013696573] 'agreement among raft nodes before linearized reading' (duration: 116.080735ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:35.568873Z","caller":"traceutil/trace.go:171","msg":"trace[391383670] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"101.132126ms","start":"2026-03-30T17:51:35.467719Z","end":"2026-03-30T17:51:35.568851Z","steps":["trace[391383670] 'process raft request' (duration: 100.749618ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:35.785796Z","caller":"traceutil/trace.go:171","msg":"trace[854376346] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"192.592315ms","start":"2026-03-30T17:51:35.593181Z","end":"2026-03-30T17:51:35.785774Z","steps":["trace[854376346] 'process raft request' (duration: 149.623899ms)","trace[854376346] 'compare' (duration: 42.811263ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:35.78605Z","caller":"traceutil/trace.go:171","msg":"trace[3521797] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"147.333445ms","start":"2026-03-30T17:51:35.638684Z","end":"2026-03-30T17:51:35.786017Z","steps":["trace[3521797] 'process raft request' (duration: 147.050989ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:36.01229Z","caller":"traceutil/trace.go:171","msg":"trace[1833978214] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"195.171924ms","start":"2026-03-30T17:51:35.817091Z","end":"2026-03-30T17:51:36.012263Z","steps":["trace[1833978214] 'process raft request' (duration: 195.097933ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:36.012577Z","caller":"traceutil/trace.go:171","msg":"trace[1930660737] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"196.104292ms","start":"2026-03-30T17:51:35.816439Z","end":"2026-03-30T17:51:36.012543Z","steps":["trace[1930660737] 'process raft request' (duration: 195.692234ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:36.012591Z","caller":"traceutil/trace.go:171","msg":"trace[766301346] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"339.627654ms","start":"2026-03-30T17:51:35.672899Z","end":"2026-03-30T17:51:36.012527Z","steps":["trace[766301346] 'process raft request' (duration: 325.079285ms)","trace[766301346] 'compare' (duration: 13.892137ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:36.013684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:35.672875Z","time spent":"339.856069ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:37.44718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267630347473584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:51:37.678891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.263993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:51:37.678996Z","caller":"traceutil/trace.go:171","msg":"trace[1664898652] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"751.997655ms","start":"2026-03-30T17:51:36.926979Z","end":"2026-03-30T17:51:37.678977Z","steps":["trace[1664898652] 'process raft request' (duration: 540.435836ms)","trace[1664898652] 'compare' (duration: 211.032029ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:37.679056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:36.926954Z","time spent":"752.075416ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:38.533126Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065294374s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-30T17:51:38.646149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.498834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:51:38.646309Z","caller":"traceutil/trace.go:171","msg":"trace[1736312375] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.673195694s","start":"2026-03-30T17:51:36.973081Z","end":"2026-03-30T17:51:38.646277Z","steps":["trace[1736312375] 'process raft request' (duration: 1.560468825s)","trace[1736312375] 'compare' (duration: 112.28611ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:38.64641Z","caller":"traceutil/trace.go:171","msg":"trace[459635663] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"918.991931ms","start":"2026-03-30T17:51:37.727403Z","end":"2026-03-30T17:51:38.646395Z","steps":["trace[459635663] 'process raft request' (duration: 918.90566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:38.646451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:36.973047Z","time spent":"1.673343257s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:38.646505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:37.72738Z","time spent":"919.080483ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:38.646486Z","caller":"traceutil/trace.go:171","msg":"trace[1537403067] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"1.699555458s","start":"2026-03-30T17:51:36.946905Z","end":"2026-03-30T17:51:38.646461Z","steps":["trace[1537403067] 'read index received' (duration: 520.580877ms)","trace[1537403067] 'applied index is now lower than readState.Index' (duration: 1.17892348s)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:38.646721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551419066s","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-03-30T17:51:38.646763Z","caller":"traceutil/trace.go:171","msg":"trace[175766140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"1.551513158s","start":"2026-03-30T17:51:37.095238Z","end":"2026-03-30T17:51:38.646751Z","steps":["trace[175766140] 'agreement among raft nodes before linearized reading' (duration: 1.551382386s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:38.646795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:37.095209Z","time spent":"1.55157853s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:51:38.646827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230809335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:51:38.646897Z","caller":"traceutil/trace.go:171","msg":"trace[744866191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"1.230910737s","start":"2026-03-30T17:51:37.415964Z","end":"2026-03-30T17:51:38.646875Z","steps":["trace[744866191] 'agreement among raft nodes before linearized reading' (duration: 1.230822346s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:38.646945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:37.415911Z","time spent":"1.231023469s","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:51:38.64691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699919584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"warn","ts":"2026-03-30T17:51:38.64703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.233367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:51:38.647199Z","caller":"traceutil/trace.go:171","msg":"trace[1665007250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"345.441971ms","start":"2026-03-30T17:51:38.301666Z","end":"2026-03-30T17:51:38.647108Z","steps":["trace[1665007250] 'agreement among raft nodes before linearized reading' (duration: 345.050063ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:38.64707Z","caller":"traceutil/trace.go:171","msg":"trace[1542519660] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:581; }","duration":"1.70019101s","start":"2026-03-30T17:51:36.946857Z","end":"2026-03-30T17:51:38.647048Z","steps":["trace[1542519660] 'agreement among raft nodes before linearized reading' (duration: 1.699731571s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:38.647257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:38.301649Z","time spent":"345.596314ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T17:51:38.647003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51681364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-30T17:51:38.647384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:36.94684Z","time spent":"1.700475435s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65459,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-03-30T17:51:38.647444Z","caller":"traceutil/trace.go:171","msg":"trace[132970387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.517312579s","start":"2026-03-30T17:51:37.130118Z","end":"2026-03-30T17:51:38.647431Z","steps":["trace[132970387] 'agreement among raft nodes before linearized reading' (duration: 1.516781169s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:38.647547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:37.130097Z","time spent":"1.517434871s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-30T17:51:38.8616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.235626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:51:38.861849Z","caller":"traceutil/trace.go:171","msg":"trace[64285305] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"134.258994ms","start":"2026-03-30T17:51:38.727561Z","end":"2026-03-30T17:51:38.86182Z","steps":["trace[64285305] 'process raft request' (duration: 19.671421ms)","trace[64285305] 'compare' (duration: 113.965711ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:38.862012Z","caller":"traceutil/trace.go:171","msg":"trace[786384910] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"121.580346ms","start":"2026-03-30T17:51:38.740425Z","end":"2026-03-30T17:51:38.862005Z","steps":["trace[786384910] 'process raft request' (duration: 121.33413ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:39.30522Z","caller":"traceutil/trace.go:171","msg":"trace[1081561177] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"129.846407ms","start":"2026-03-30T17:51:39.175347Z","end":"2026-03-30T17:51:39.305193Z","steps":["trace[1081561177] 'process raft request' (duration: 128.641554ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:39.766278Z","caller":"traceutil/trace.go:171","msg":"trace[167614737] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"208.304497ms","start":"2026-03-30T17:51:39.557948Z","end":"2026-03-30T17:51:39.766253Z","steps":["trace[167614737] 'process raft request' (duration: 208.170345ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:39.99768Z","caller":"traceutil/trace.go:171","msg":"trace[1536664516] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"240.464501ms","start":"2026-03-30T17:51:39.757193Z","end":"2026-03-30T17:51:39.997657Z","steps":["trace[1536664516] 'process raft request' (duration: 240.278598ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:40.014595Z","caller":"traceutil/trace.go:171","msg":"trace[1417170498] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"243.282163ms","start":"2026-03-30T17:51:39.771291Z","end":"2026-03-30T17:51:40.014573Z","steps":["trace[1417170498] 'process raft request' (duration: 243.059819ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:40.014634Z","caller":"traceutil/trace.go:171","msg":"trace[961978819] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"243.12988ms","start":"2026-03-30T17:51:39.771494Z","end":"2026-03-30T17:51:40.014624Z","steps":["trace[961978819] 'process raft request' (duration: 242.968387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.388627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025210371s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:51:41.38905Z","caller":"traceutil/trace.go:171","msg":"trace[1045982514] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.220707966s","start":"2026-03-30T17:51:40.168307Z","end":"2026-03-30T17:51:41.389015Z","steps":["trace[1045982514] 'process raft request' (duration: 194.994845ms)","trace[1045982514] 'compare' (duration: 1.024855725s)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:41.389207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.16828Z","time spent":"1.22086005s","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:51:41.389053Z","caller":"traceutil/trace.go:171","msg":"trace[2067049397] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:633; }","duration":"1.1822777s","start":"2026-03-30T17:51:40.206752Z","end":"2026-03-30T17:51:41.38903Z","steps":["trace[2067049397] 'read index received' (duration: 15.515378ms)","trace[2067049397] 'applied index is now lower than readState.Index' (duration: 1.166760972s)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:41.390016Z","caller":"traceutil/trace.go:171","msg":"trace[572186185] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.220818009s","start":"2026-03-30T17:51:40.168777Z","end":"2026-03-30T17:51:41.389595Z","steps":["trace[572186185] 'process raft request' (duration: 1.220151537s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.390309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.168743Z","time spent":"1.221361348s","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:51:41.390334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183562993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-30T17:51:41.391084Z","caller":"traceutil/trace.go:171","msg":"trace[1233192294] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:613; }","duration":"1.184355668s","start":"2026-03-30T17:51:40.206709Z","end":"2026-03-30T17:51:41.391065Z","steps":["trace[1233192294] 'agreement among raft nodes before linearized reading' (duration: 1.182402682s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.391212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.206694Z","time spent":"1.18449785s","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-03-30T17:51:41.549047Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.18588869s","expected-duration":"1s"} {"level":"info","ts":"2026-03-30T17:51:41.625657Z","caller":"traceutil/trace.go:171","msg":"trace[730585159] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.396842264s","start":"2026-03-30T17:51:40.228798Z","end":"2026-03-30T17:51:41.625641Z","steps":["trace[730585159] 'process raft request' (duration: 1.320475239s)","trace[730585159] 'compare' (duration: 76.276264ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:41.625792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.228781Z","time spent":"1.396926285s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:41.625789Z","caller":"traceutil/trace.go:171","msg":"trace[920794147] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.266579786s","start":"2026-03-30T17:51:40.359186Z","end":"2026-03-30T17:51:41.625766Z","steps":["trace[920794147] 'process raft request' (duration: 1.266434943s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.62591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.359163Z","time spent":"1.266687157s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:51:41.723393Z","caller":"traceutil/trace.go:171","msg":"trace[574171836] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.175741521s","start":"2026-03-30T17:51:40.547612Z","end":"2026-03-30T17:51:41.723353Z","steps":["trace[574171836] 'process raft request' (duration: 1.175298763s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.724457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.54758Z","time spent":"1.176567255s","remote":"127.0.0.1:45452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:51:41.724646Z","caller":"traceutil/trace.go:171","msg":"trace[431580988] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.166936795s","start":"2026-03-30T17:51:40.557627Z","end":"2026-03-30T17:51:41.724563Z","steps":["trace[431580988] 'process raft request' (duration: 1.16556161s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.725046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.557606Z","time spent":"1.167169851s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:51:41.816477Z","caller":"traceutil/trace.go:171","msg":"trace[953010471] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.055727312s","start":"2026-03-30T17:51:40.760713Z","end":"2026-03-30T17:51:41.81644Z","steps":["trace[953010471] 'process raft request' (duration: 998.4021ms)","trace[953010471] 'compare' (duration: 56.907914ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:41.816584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.760688Z","time spent":"1.055848475s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:51:41.816782Z","caller":"traceutil/trace.go:171","msg":"trace[1793519431] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:636; }","duration":"427.123055ms","start":"2026-03-30T17:51:41.389618Z","end":"2026-03-30T17:51:41.816741Z","steps":["trace[1793519431] 'read index received' (duration: 159.7135ms)","trace[1793519431] 'applied index is now lower than readState.Index' (duration: 267.408805ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:41.816815Z","caller":"traceutil/trace.go:171","msg":"trace[1691279075] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"184.58005ms","start":"2026-03-30T17:51:41.632197Z","end":"2026-03-30T17:51:41.816777Z","steps":["trace[1691279075] 'process raft request' (duration: 184.492828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.817022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.392777Z","time spent":"424.240401ms","remote":"127.0.0.1:45382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-30T17:51:41.817076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.548814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-30T17:51:41.817116Z","caller":"traceutil/trace.go:171","msg":"trace[1972053396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:623; }","duration":"814.622746ms","start":"2026-03-30T17:51:41.002483Z","end":"2026-03-30T17:51:41.817105Z","steps":["trace[1972053396] 'agreement among raft nodes before linearized reading' (duration: 814.434823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.817155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.002466Z","time spent":"814.682207ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-30T17:51:41.817239Z","caller":"traceutil/trace.go:171","msg":"trace[1697052760] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"185.314253ms","start":"2026-03-30T17:51:41.631866Z","end":"2026-03-30T17:51:41.81718Z","steps":["trace[1697052760] 'process raft request' (duration: 184.758363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.81734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108609618s","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-03-30T17:51:41.81737Z","caller":"traceutil/trace.go:171","msg":"trace[773301745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"1.10866832s","start":"2026-03-30T17:51:40.708693Z","end":"2026-03-30T17:51:41.817361Z","steps":["trace[773301745] 'agreement among raft nodes before linearized reading' (duration: 1.108617499s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.817455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.708676Z","time spent":"1.108768891s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-30T17:51:41.81754Z","caller":"traceutil/trace.go:171","msg":"trace[1563963691] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"626.251706ms","start":"2026-03-30T17:51:41.191265Z","end":"2026-03-30T17:51:41.817517Z","steps":["trace[1563963691] 'process raft request' (duration: 625.076394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.817618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402796334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:51:41.817683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.191232Z","time spent":"626.347498ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:51:41.817753Z","caller":"traceutil/trace.go:171","msg":"trace[2076826512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.402928465s","start":"2026-03-30T17:51:40.414753Z","end":"2026-03-30T17:51:41.817682Z","steps":["trace[2076826512] 'agreement among raft nodes before linearized reading' (duration: 1.402746812s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.81781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.414739Z","time spent":"1.403059369s","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:51:41.817869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14568226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:51:41.817929Z","caller":"traceutil/trace.go:171","msg":"trace[1560544054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.145771431s","start":"2026-03-30T17:51:40.672142Z","end":"2026-03-30T17:51:41.817914Z","steps":["trace[1560544054] 'agreement among raft nodes before linearized reading' (duration: 1.145642658s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.817971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148567664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-30T17:51:41.817983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.672128Z","time spent":"1.145843353s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-30T17:51:41.818001Z","caller":"traceutil/trace.go:171","msg":"trace[1652377865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"1.148638334s","start":"2026-03-30T17:51:40.669353Z","end":"2026-03-30T17:51:41.817991Z","steps":["trace[1652377865] 'agreement among raft nodes before linearized reading' (duration: 1.148575723s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.818029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.669332Z","time spent":"1.148692426s","remote":"127.0.0.1:45618","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-03-30T17:51:41.817642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.924815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-30T17:51:41.818108Z","caller":"traceutil/trace.go:171","msg":"trace[673759256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:623; }","duration":"261.430965ms","start":"2026-03-30T17:51:41.556667Z","end":"2026-03-30T17:51:41.818098Z","steps":["trace[673759256] 'agreement among raft nodes before linearized reading' (duration: 260.880605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.818194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145898094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:51:41.818239Z","caller":"traceutil/trace.go:171","msg":"trace[185771881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.145992425s","start":"2026-03-30T17:51:40.672236Z","end":"2026-03-30T17:51:41.818228Z","steps":["trace[185771881] 'agreement among raft nodes before linearized reading' (duration: 1.145871153s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:41.818248Z","caller":"traceutil/trace.go:171","msg":"trace[1056412601] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"854.019541ms","start":"2026-03-30T17:51:40.964189Z","end":"2026-03-30T17:51:41.818208Z","steps":["trace[1056412601] 'process raft request' (duration: 852.052314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.818429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.672202Z","time spent":"1.146147228s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-30T17:51:41.817298Z","caller":"traceutil/trace.go:171","msg":"trace[1843861979] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"432.436523ms","start":"2026-03-30T17:51:41.384582Z","end":"2026-03-30T17:51:41.817018Z","steps":["trace[1843861979] 'process raft request' (duration: 431.876543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:41.818764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.384555Z","time spent":"434.104484ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:51:41.818812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:40.964166Z","time spent":"854.247966ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:51:42.55149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.341405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:51:42.551803Z","caller":"traceutil/trace.go:171","msg":"trace[122911351] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"723.535658ms","start":"2026-03-30T17:51:41.82825Z","end":"2026-03-30T17:51:42.551786Z","steps":["trace[122911351] 'process raft request' (duration: 723.477897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.551879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.828244Z","time spent":"723.601199ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:42.5519Z","caller":"traceutil/trace.go:171","msg":"trace[1203946214] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"723.793132ms","start":"2026-03-30T17:51:41.828079Z","end":"2026-03-30T17:51:42.551872Z","steps":["trace[1203946214] 'process raft request' (duration: 144.00671ms)","trace[1203946214] 'compare' (duration: 579.205262ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:42.551862Z","caller":"traceutil/trace.go:171","msg":"trace[1296564694] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"723.644069ms","start":"2026-03-30T17:51:41.828178Z","end":"2026-03-30T17:51:42.551822Z","steps":["trace[1296564694] 'process raft request' (duration: 723.467836ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:42.552087Z","caller":"traceutil/trace.go:171","msg":"trace[1484045232] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"723.830823ms","start":"2026-03-30T17:51:41.828237Z","end":"2026-03-30T17:51:42.552068Z","steps":["trace[1484045232] 'process raft request' (duration: 723.458656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.552063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.82806Z","time spent":"723.929484ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:42.552192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.828228Z","time spent":"723.908704ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:42.552226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.828171Z","time spent":"723.965175ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:42.552296Z","caller":"traceutil/trace.go:171","msg":"trace[2025035492] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"723.984005ms","start":"2026-03-30T17:51:41.828285Z","end":"2026-03-30T17:51:42.552269Z","steps":["trace[2025035492] 'process raft request' (duration: 723.471686ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:42.552419Z","caller":"traceutil/trace.go:171","msg":"trace[120502401] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"638.486251ms","start":"2026-03-30T17:51:41.91392Z","end":"2026-03-30T17:51:42.552406Z","steps":["trace[120502401] 'process raft request' (duration: 638.42371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.552438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.828276Z","time spent":"724.092157ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:42.552465Z","caller":"traceutil/trace.go:171","msg":"trace[1326237560] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:649; }","duration":"722.955776ms","start":"2026-03-30T17:51:41.829498Z","end":"2026-03-30T17:51:42.552454Z","steps":["trace[1326237560] 'read index received' (duration: 72.337351ms)","trace[1326237560] 'applied index is now lower than readState.Index' (duration: 650.617095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:42.552503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.913897Z","time spent":"638.569802ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:42.55252Z","caller":"traceutil/trace.go:171","msg":"trace[1191389057] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"661.963755ms","start":"2026-03-30T17:51:41.890549Z","end":"2026-03-30T17:51:42.552513Z","steps":["trace[1191389057] 'process raft request' (duration: 661.576848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.552565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.058897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-30T17:51:42.552614Z","caller":"traceutil/trace.go:171","msg":"trace[1239307018] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:632; }","duration":"723.127008ms","start":"2026-03-30T17:51:41.829477Z","end":"2026-03-30T17:51:42.552604Z","steps":["trace[1239307018] 'agreement among raft nodes before linearized reading' (duration: 723.043757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.552652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.829471Z","time spent":"723.173809ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-30T17:51:42.552647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.89053Z","time spent":"662.072927ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:42.620552Z","caller":"traceutil/trace.go:171","msg":"trace[2036250156] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"367.126422ms","start":"2026-03-30T17:51:42.253401Z","end":"2026-03-30T17:51:42.620527Z","steps":["trace[2036250156] 'process raft request' (duration: 365.265089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.620688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:42.253369Z","time spent":"367.251704ms","remote":"127.0.0.1:45452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:42.620699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.573243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-30T17:51:42.62078Z","caller":"traceutil/trace.go:171","msg":"trace[178129224] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"264.721356ms","start":"2026-03-30T17:51:42.356042Z","end":"2026-03-30T17:51:42.620763Z","steps":["trace[178129224] 'agreement among raft nodes before linearized reading' (duration: 264.494721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.621027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.015925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-30T17:51:42.621151Z","caller":"traceutil/trace.go:171","msg":"trace[314245595] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"465.178928ms","start":"2026-03-30T17:51:42.155952Z","end":"2026-03-30T17:51:42.621131Z","steps":["trace[314245595] 'agreement among raft nodes before linearized reading' (duration: 464.900383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.621078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.158257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-30T17:51:42.621287Z","caller":"traceutil/trace.go:171","msg":"trace[1506504846] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"665.423852ms","start":"2026-03-30T17:51:41.955845Z","end":"2026-03-30T17:51:42.621269Z","steps":["trace[1506504846] 'agreement among raft nodes before linearized reading' (duration: 665.095475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:42.621341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:41.955817Z","time spent":"665.508874ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-03-30T17:51:42.621464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:42.15592Z","time spent":"465.396463ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-03-30T17:51:42.887392Z","caller":"traceutil/trace.go:171","msg":"trace[873922984] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"127.108162ms","start":"2026-03-30T17:51:42.760262Z","end":"2026-03-30T17:51:42.88737Z","steps":["trace[873922984] 'read index received' (duration: 33.933075ms)","trace[873922984] 'applied index is now lower than readState.Index' (duration: 93.174037ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:42.887623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.343776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-30T17:51:42.887663Z","caller":"traceutil/trace.go:171","msg":"trace[9836097] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"127.428248ms","start":"2026-03-30T17:51:42.760222Z","end":"2026-03-30T17:51:42.88765Z","steps":["trace[9836097] 'agreement among raft nodes before linearized reading' (duration: 127.226185ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:42.88782Z","caller":"traceutil/trace.go:171","msg":"trace[1028941937] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"180.107158ms","start":"2026-03-30T17:51:42.707703Z","end":"2026-03-30T17:51:42.88781Z","steps":["trace[1028941937] 'process raft request' (duration: 86.521414ms)","trace[1028941937] 'compare' (duration: 92.742198ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:50.601141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267630347473866,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:51:50.939483Z","caller":"traceutil/trace.go:171","msg":"trace[807990738] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"838.973572ms","start":"2026-03-30T17:51:50.100493Z","end":"2026-03-30T17:51:50.939467Z","steps":["trace[807990738] 'read index received' (duration: 838.838ms)","trace[807990738] 'applied index is now lower than readState.Index' (duration: 134.782µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:50.939594Z","caller":"traceutil/trace.go:171","msg":"trace[1937045017] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.187302361s","start":"2026-03-30T17:51:49.752232Z","end":"2026-03-30T17:51:50.939534Z","steps":["trace[1937045017] 'process raft request' (duration: 1.187133568s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.939739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.113424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mn6f7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-30T17:51:50.939787Z","caller":"traceutil/trace.go:171","msg":"trace[543718757] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mn6f7; range_end:; response_count:1; response_revision:681; }","duration":"839.344899ms","start":"2026-03-30T17:51:50.100433Z","end":"2026-03-30T17:51:50.939778Z","steps":["trace[543718757] 'agreement among raft nodes before linearized reading' (duration: 839.133515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.939828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:49.752186Z","time spent":"1.187511775s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:50.939848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.100414Z","time spent":"839.42475ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-mn6f7\" "} {"level":"warn","ts":"2026-03-30T17:51:50.939855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.228995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-30T17:51:50.93985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.438838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-30T17:51:50.939907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.313827ms","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-03-30T17:51:50.939896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.321025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-30T17:51:50.939927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.805643ms","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-03-30T17:51:50.939921Z","caller":"traceutil/trace.go:171","msg":"trace[1237921871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"310.318627ms","start":"2026-03-30T17:51:50.629584Z","end":"2026-03-30T17:51:50.939902Z","steps":["trace[1237921871] 'agreement among raft nodes before linearized reading' (duration: 310.132333ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:50.939991Z","caller":"traceutil/trace.go:171","msg":"trace[1310755726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"351.430649ms","start":"2026-03-30T17:51:50.588539Z","end":"2026-03-30T17:51:50.93997Z","steps":["trace[1310755726] 'agreement among raft nodes before linearized reading' (duration: 351.236785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.94003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.658415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:51:50.940027Z","caller":"traceutil/trace.go:171","msg":"trace[697897626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"319.448507ms","start":"2026-03-30T17:51:50.620535Z","end":"2026-03-30T17:51:50.939984Z","steps":["trace[697897626] 'agreement among raft nodes before linearized reading' (duration: 319.144582ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:50.940027Z","caller":"traceutil/trace.go:171","msg":"trace[1636214358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"340.950435ms","start":"2026-03-30T17:51:50.599058Z","end":"2026-03-30T17:51:50.940009Z","steps":["trace[1636214358] 'agreement among raft nodes before linearized reading' (duration: 340.736442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.940052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.629572Z","time spent":"310.449479ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-30T17:51:50.940065Z","caller":"traceutil/trace.go:171","msg":"trace[1522176361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"524.719156ms","start":"2026-03-30T17:51:50.415337Z","end":"2026-03-30T17:51:50.940056Z","steps":["trace[1522176361] 'agreement among raft nodes before linearized reading' (duration: 524.668155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.94008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.599039Z","time spent":"341.032957ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:51:50.940097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.4153Z","time spent":"524.790198ms","remote":"127.0.0.1:45360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:51:50.940106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.620526Z","time spent":"319.567739ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T17:51:50.940034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.588519Z","time spent":"351.505851ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-30T17:51:50.939978Z","caller":"traceutil/trace.go:171","msg":"trace[1336546827] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:681; }","duration":"732.623542ms","start":"2026-03-30T17:51:50.207332Z","end":"2026-03-30T17:51:50.939955Z","steps":["trace[1336546827] 'agreement among raft nodes before linearized reading' (duration: 732.304976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.940247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.20731Z","time spent":"732.930126ms","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-03-30T17:51:50.940331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.614621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-30T17:51:50.940461Z","caller":"traceutil/trace.go:171","msg":"trace[339640912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"310.903587ms","start":"2026-03-30T17:51:50.629535Z","end":"2026-03-30T17:51:50.940438Z","steps":["trace[339640912] 'agreement among raft nodes before linearized reading' (duration: 310.218895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:50.940546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:50.629526Z","time spent":"311.006109ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-30T17:51:51.126554Z","caller":"traceutil/trace.go:171","msg":"trace[271790914] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"181.575561ms","start":"2026-03-30T17:51:50.944963Z","end":"2026-03-30T17:51:51.126539Z","steps":["trace[271790914] 'process raft request' (duration: 181.479359ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:51.126561Z","caller":"traceutil/trace.go:171","msg":"trace[872231660] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"182.1597ms","start":"2026-03-30T17:51:50.944369Z","end":"2026-03-30T17:51:51.126529Z","steps":["trace[872231660] 'process raft request' (duration: 182.022638ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:51.126533Z","caller":"traceutil/trace.go:171","msg":"trace[829652526] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"182.287193ms","start":"2026-03-30T17:51:50.944214Z","end":"2026-03-30T17:51:51.126501Z","steps":["trace[829652526] 'process raft request' (duration: 182.039199ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:51.127055Z","caller":"traceutil/trace.go:171","msg":"trace[1269751686] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"182.061539ms","start":"2026-03-30T17:51:50.94498Z","end":"2026-03-30T17:51:51.127042Z","steps":["trace[1269751686] 'process raft request' (duration: 181.51642ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:51.127465Z","caller":"traceutil/trace.go:171","msg":"trace[1864839978] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"171.546594ms","start":"2026-03-30T17:51:50.955905Z","end":"2026-03-30T17:51:51.127452Z","steps":["trace[1864839978] 'process raft request' (duration: 171.462413ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:51.127972Z","caller":"traceutil/trace.go:171","msg":"trace[327868334] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"182.484526ms","start":"2026-03-30T17:51:50.945041Z","end":"2026-03-30T17:51:51.127525Z","steps":["trace[327868334] 'process raft request' (duration: 181.944967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:51.976128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.68233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:51:51.976258Z","caller":"traceutil/trace.go:171","msg":"trace[216745121] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"914.408728ms","start":"2026-03-30T17:51:51.06182Z","end":"2026-03-30T17:51:51.976229Z","steps":["trace[216745121] 'process raft request' (duration: 105.552715ms)","trace[216745121] 'compare' (duration: 808.606709ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:51:51.976216Z","caller":"traceutil/trace.go:171","msg":"trace[671017346] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"560.858066ms","start":"2026-03-30T17:51:51.415346Z","end":"2026-03-30T17:51:51.976204Z","steps":["trace[671017346] 'read index received' (duration: 44.041µs)","trace[671017346] 'applied index is now lower than readState.Index' (duration: 560.813265ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:51.976341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:51.061794Z","time spent":"914.50096ms","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:51.976472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.09785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:51:51.976506Z","caller":"traceutil/trace.go:171","msg":"trace[1616997702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"561.200342ms","start":"2026-03-30T17:51:51.415296Z","end":"2026-03-30T17:51:51.976497Z","steps":["trace[1616997702] 'agreement among raft nodes before linearized reading' (duration: 561.06971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:51.976527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:51.415274Z","time spent":"561.248522ms","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:51:53.704781Z","caller":"traceutil/trace.go:171","msg":"trace[2085629424] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"570.007098ms","start":"2026-03-30T17:51:53.134741Z","end":"2026-03-30T17:51:53.704749Z","steps":["trace[2085629424] 'process raft request' (duration: 569.783265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:53.704895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.134723Z","time spent":"570.10765ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:54.922925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721574ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:51:54.923059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218416766s","expected-duration":"1s"} {"level":"info","ts":"2026-03-30T17:51:54.923061Z","caller":"traceutil/trace.go:171","msg":"trace[1534028093] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:689; }","duration":"114.869526ms","start":"2026-03-30T17:51:54.80817Z","end":"2026-03-30T17:51:54.92304Z","steps":["trace[1534028093] 'range keys from in-memory index tree' (duration: 114.703933ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:54.924096Z","caller":"traceutil/trace.go:171","msg":"trace[797807194] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.78753816s","start":"2026-03-30T17:51:53.136539Z","end":"2026-03-30T17:51:54.924077Z","steps":["trace[797807194] 'process raft request' (duration: 1.787444118s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:54.924139Z","caller":"traceutil/trace.go:171","msg":"trace[553551901] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.788017438s","start":"2026-03-30T17:51:53.136101Z","end":"2026-03-30T17:51:54.924118Z","steps":["trace[553551901] 'process raft request' (duration: 1.787702923s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:54.924248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.136091Z","time spent":"1.788087099s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:54.924264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.136526Z","time spent":"1.787629021s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:54.924311Z","caller":"traceutil/trace.go:171","msg":"trace[1960973736] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.787310966s","start":"2026-03-30T17:51:53.136985Z","end":"2026-03-30T17:51:54.924296Z","steps":["trace[1960973736] 'process raft request' (duration: 1.787053692s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:54.924409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.136977Z","time spent":"1.787384927s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:51:54.924605Z","caller":"traceutil/trace.go:171","msg":"trace[1666013458] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.787084852s","start":"2026-03-30T17:51:53.137505Z","end":"2026-03-30T17:51:54.92459Z","steps":["trace[1666013458] 'process raft request' (duration: 1.786725136s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:54.924639Z","caller":"traceutil/trace.go:171","msg":"trace[1830950524] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:714; }","duration":"1.509368968s","start":"2026-03-30T17:51:53.415245Z","end":"2026-03-30T17:51:54.924614Z","steps":["trace[1830950524] 'read index received' (duration: 289.420115ms)","trace[1830950524] 'applied index is now lower than readState.Index' (duration: 1.219947423s)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:51:54.924695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.137499Z","time spent":"1.787150763s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:51:54.924809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50952073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:51:54.924881Z","caller":"traceutil/trace.go:171","msg":"trace[713930429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"1.509640433s","start":"2026-03-30T17:51:53.4152Z","end":"2026-03-30T17:51:54.92484Z","steps":["trace[713930429] 'agreement among raft nodes before linearized reading' (duration: 1.50951962s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:51:54.924894Z","caller":"traceutil/trace.go:171","msg":"trace[1989442462] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.683293717s","start":"2026-03-30T17:51:53.241521Z","end":"2026-03-30T17:51:54.924815Z","steps":["trace[1989442462] 'process raft request' (duration: 1.68294625s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:51:54.92492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.41518Z","time spent":"1.509730165s","remote":"127.0.0.1:45360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:51:54.925586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:51:53.241499Z","time spent":"1.683663043s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:52:12.130807Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.654269804s","expected-duration":"1s"} {"level":"info","ts":"2026-03-30T17:52:12.131202Z","caller":"traceutil/trace.go:171","msg":"trace[1433364784] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"1.715436057s","start":"2026-03-30T17:52:10.415743Z","end":"2026-03-30T17:52:12.131179Z","steps":["trace[1433364784] 'read index received' (duration: 58.247326ms)","trace[1433364784] 'applied index is now lower than readState.Index' (duration: 1.657187931s)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:52:12.132881Z","caller":"traceutil/trace.go:171","msg":"trace[1392054804] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.915834033s","start":"2026-03-30T17:52:10.215497Z","end":"2026-03-30T17:52:12.131331Z","steps":["trace[1392054804] 'process raft request' (duration: 1.915529719s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.133027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:10.21547Z","time spent":"1.917468978s","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:52:12.136171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720408464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:52:12.13629Z","caller":"traceutil/trace.go:171","msg":"trace[385134759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"1.720567646s","start":"2026-03-30T17:52:10.415701Z","end":"2026-03-30T17:52:12.136269Z","steps":["trace[385134759] 'agreement among raft nodes before linearized reading' (duration: 1.715844943s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.136343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:10.415678Z","time spent":"1.720636629s","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:52:12.137161Z","caller":"traceutil/trace.go:171","msg":"trace[1138686077] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.662123359s","start":"2026-03-30T17:52:10.475022Z","end":"2026-03-30T17:52:12.137145Z","steps":["trace[1138686077] 'process raft request' (duration: 1.661940246s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.137275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:10.475002Z","time spent":"1.66222276s","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:52:12.137354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082946663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:52:12.137399Z","caller":"traceutil/trace.go:171","msg":"trace[461293670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.083027884s","start":"2026-03-30T17:52:11.054355Z","end":"2026-03-30T17:52:12.137383Z","steps":["trace[461293670] 'agreement among raft nodes before linearized reading' (duration: 1.082864062s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.137425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:11.054334Z","time spent":"1.083085925s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-30T17:52:12.137654Z","caller":"traceutil/trace.go:171","msg":"trace[504700792] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"742.438292ms","start":"2026-03-30T17:52:11.395194Z","end":"2026-03-30T17:52:12.137632Z","steps":["trace[504700792] 'process raft request' (duration: 741.900074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.137723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:11.395173Z","time spent":"742.505014ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:52:12.13787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.68925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:52:12.137896Z","caller":"traceutil/trace.go:171","msg":"trace[1470154415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"329.754361ms","start":"2026-03-30T17:52:11.808135Z","end":"2026-03-30T17:52:12.137889Z","steps":["trace[1470154415] 'agreement among raft nodes before linearized reading' (duration: 329.68708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.137918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:11.808115Z","time spent":"329.798902ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T17:52:12.138017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074232604s","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-03-30T17:52:12.13806Z","caller":"traceutil/trace.go:171","msg":"trace[1461063888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.074293345s","start":"2026-03-30T17:52:11.063751Z","end":"2026-03-30T17:52:12.138044Z","steps":["trace[1461063888] 'agreement among raft nodes before linearized reading' (duration: 1.074099762s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.138096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:11.063741Z","time spent":"1.074347225s","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-30T17:52:12.138288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049754217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-30T17:52:12.1383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05059682s","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-03-30T17:52:12.138318Z","caller":"traceutil/trace.go:171","msg":"trace[57793725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"1.049813999s","start":"2026-03-30T17:52:11.088495Z","end":"2026-03-30T17:52:12.138309Z","steps":["trace[57793725] 'agreement among raft nodes before linearized reading' (duration: 1.049739628s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:52:12.138331Z","caller":"traceutil/trace.go:171","msg":"trace[387821373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"1.050662782s","start":"2026-03-30T17:52:11.087655Z","end":"2026-03-30T17:52:12.138318Z","steps":["trace[387821373] 'agreement among raft nodes before linearized reading' (duration: 1.050588171s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:12.138347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:11.088478Z","time spent":"1.049862489s","remote":"127.0.0.1:45618","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-03-30T17:52:12.138359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:11.087647Z","time spent":"1.050707452s","remote":"127.0.0.1:45618","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-03-30T17:52:34.840644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267630347474489,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:52:35.139654Z","caller":"traceutil/trace.go:171","msg":"trace[1725774070] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"823.151373ms","start":"2026-03-30T17:52:34.316469Z","end":"2026-03-30T17:52:35.139621Z","steps":["trace[1725774070] 'process raft request' (duration: 822.950951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:35.139871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.316458Z","time spent":"823.289415ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:52:35.140959Z","caller":"traceutil/trace.go:171","msg":"trace[948281353] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"801.067543ms","start":"2026-03-30T17:52:34.339865Z","end":"2026-03-30T17:52:35.140932Z","steps":["trace[948281353] 'read index received' (duration: 799.818365ms)","trace[948281353] 'applied index is now lower than readState.Index' (duration: 1.248098ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:52:35.141076Z","caller":"traceutil/trace.go:171","msg":"trace[1403994520] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"332.225591ms","start":"2026-03-30T17:52:34.808838Z","end":"2026-03-30T17:52:35.141063Z","steps":["trace[1403994520] 'process raft request' (duration: 331.966436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:35.14117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.036326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-30T17:52:35.141175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.808813Z","time spent":"332.287401ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:52:35.141199Z","caller":"traceutil/trace.go:171","msg":"trace[459264318] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1004; }","duration":"533.130568ms","start":"2026-03-30T17:52:34.60806Z","end":"2026-03-30T17:52:35.141191Z","steps":["trace[459264318] 'agreement among raft nodes before linearized reading' (duration: 533.044206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:35.141186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.074874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-30T17:52:35.141218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.608038Z","time spent":"533.175718ms","remote":"127.0.0.1:45702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-30T17:52:35.141239Z","caller":"traceutil/trace.go:171","msg":"trace[211890413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"781.174096ms","start":"2026-03-30T17:52:34.360051Z","end":"2026-03-30T17:52:35.141225Z","steps":["trace[211890413] 'agreement among raft nodes before linearized reading' (duration: 781.030384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:35.141268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.36003Z","time spent":"781.231567ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:52:35.141301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.420939ms","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-03-30T17:52:35.141317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.113997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-30T17:52:35.141346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.842382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-30T17:52:35.141375Z","caller":"traceutil/trace.go:171","msg":"trace[103274758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"780.932633ms","start":"2026-03-30T17:52:34.360434Z","end":"2026-03-30T17:52:35.141367Z","steps":["trace[103274758] 'agreement among raft nodes before linearized reading' (duration: 780.789671ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:52:35.141371Z","caller":"traceutil/trace.go:171","msg":"trace[1539524307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"801.51858ms","start":"2026-03-30T17:52:34.339834Z","end":"2026-03-30T17:52:35.141352Z","steps":["trace[1539524307] 'agreement among raft nodes before linearized reading' (duration: 801.333427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:35.14139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.360421Z","time spent":"780.965323ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-30T17:52:35.141378Z","caller":"traceutil/trace.go:171","msg":"trace[98624358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"777.212139ms","start":"2026-03-30T17:52:34.364149Z","end":"2026-03-30T17:52:35.141361Z","steps":["trace[98624358] 'agreement among raft nodes before linearized reading' (duration: 777.056917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:52:35.141415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.339817Z","time spent":"801.587062ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-30T17:52:35.141425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:52:34.364127Z","time spent":"777.28738ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-30T17:54:05.964732Z","caller":"traceutil/trace.go:171","msg":"trace[1513159275] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"369.53563ms","start":"2026-03-30T17:54:05.59517Z","end":"2026-03-30T17:54:05.964706Z","steps":["trace[1513159275] 'process raft request' (duration: 369.404408ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:05.964716Z","caller":"traceutil/trace.go:171","msg":"trace[331541005] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"528.476404ms","start":"2026-03-30T17:54:05.436198Z","end":"2026-03-30T17:54:05.964674Z","steps":["trace[331541005] 'read index received' (duration: 488.45759ms)","trace[331541005] 'applied index is now lower than readState.Index' (duration: 40.014783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:54:05.964974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.595145Z","time spent":"369.670832ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:54:05.965285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.290376ms","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-03-30T17:54:05.965353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.240633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:54:05.965396Z","caller":"traceutil/trace.go:171","msg":"trace[2073226124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"364.444129ms","start":"2026-03-30T17:54:05.600928Z","end":"2026-03-30T17:54:05.965372Z","steps":["trace[2073226124] 'agreement among raft nodes before linearized reading' (duration: 364.183866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:54:05.965434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.676137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-30T17:54:05.965533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.345413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-30T17:54:05.965523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.600916Z","time spent":"364.59324ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-30T17:54:05.965583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.835168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-30T17:54:05.965442Z","caller":"traceutil/trace.go:171","msg":"trace[2089539382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"358.355655ms","start":"2026-03-30T17:54:05.607063Z","end":"2026-03-30T17:54:05.965419Z","steps":["trace[2089539382] 'agreement among raft nodes before linearized reading' (duration: 358.132143ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:05.965634Z","caller":"traceutil/trace.go:171","msg":"trace[1034822803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"357.928389ms","start":"2026-03-30T17:54:05.607693Z","end":"2026-03-30T17:54:05.965622Z","steps":["trace[1034822803] 'agreement among raft nodes before linearized reading' (duration: 357.708936ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:54:05.965617Z","caller":"traceutil/trace.go:171","msg":"trace[1252815830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"357.880009ms","start":"2026-03-30T17:54:05.607675Z","end":"2026-03-30T17:54:05.965555Z","steps":["trace[1252815830] 'agreement among raft nodes before linearized reading' (duration: 357.451624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:54:05.965695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.60705Z","time spent":"358.632628ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T17:54:05.965738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.607644Z","time spent":"358.080292ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-30T17:54:05.965564Z","caller":"traceutil/trace.go:171","msg":"trace[873070699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"362.413024ms","start":"2026-03-30T17:54:05.603143Z","end":"2026-03-30T17:54:05.965556Z","steps":["trace[873070699] 'agreement among raft nodes before linearized reading' (duration: 362.334683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:54:05.965853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.603123Z","time spent":"362.722117ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:54:05.96632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.607688Z","time spent":"357.998799ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-30T17:54:05.966422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.195885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:54:05.966555Z","caller":"traceutil/trace.go:171","msg":"trace[1939969605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"530.392316ms","start":"2026-03-30T17:54:05.43614Z","end":"2026-03-30T17:54:05.966533Z","steps":["trace[1939969605] 'agreement among raft nodes before linearized reading' (duration: 528.652875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:54:05.966604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:54:05.436127Z","time spent":"530.467118ms","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}