{"level":"warn","ts":"2026-04-22T16:22:01.548101Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:22:01.548178Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18: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.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18: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-04-22T16:22:01.54824Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:22:01.548251Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-04-22T16:22:01.548271Z","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-04-22T16:22:01.548854Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-04-22T16:22:01.548922Z","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.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18: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-04-22T16:22:01.552558Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.442772ms"} {"level":"info","ts":"2026-04-22T16:22:01.558236Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-04-22T16:22:01.558285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:22:01.558303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-04-22T16:22:01.558313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:22:01.558323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-04-22T16:22:01.558349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-04-22T16:22:01.567061Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:22:01.5694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:22:01.571943Z","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-04-22T16:22:01.573913Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:22:01.577094Z","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-04-22T16:22:01.5772Z","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-04-22T16:22:01.577078Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:22:01.577263Z","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-04-22T16:22:01.582777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-04-22T16:22:01.583731Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-04-22T16:22:01.582929Z","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-04-22T16:22:01.583931Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:22:01.583956Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:22:01.582947Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-04-22T16:22:01.583974Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-04-22T16:22:01.758756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:22:01.758812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:22:01.758849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-04-22T16:22:01.75887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:22:01.758889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-04-22T16:22:01.758996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-04-22T16:22:01.759022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-04-22T16:22:01.760342Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:22:01.76055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:22:01.76063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:22:01.76076Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:22:01.760769Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:22:01.760846Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:22:01.762421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:22:01.76492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"info","ts":"2026-04-22T16:22:01.766197Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:22:01.766259Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:22:01.766324Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:22:03.55851Z","caller":"traceutil/trace.go:171","msg":"trace[474186481] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"178.040746ms","start":"2026-04-22T16:22:03.380445Z","end":"2026-04-22T16:22:03.558486Z","steps":["trace[474186481] 'process raft request' (duration: 177.713648ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.586947Z","caller":"traceutil/trace.go:171","msg":"trace[811626192] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"118.873729ms","start":"2026-04-22T16:22:03.46805Z","end":"2026-04-22T16:22:03.586923Z","steps":["trace[811626192] 'process raft request' (duration: 118.762716ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.587001Z","caller":"traceutil/trace.go:171","msg":"trace[864714430] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"205.893798ms","start":"2026-04-22T16:22:03.381092Z","end":"2026-04-22T16:22:03.586986Z","steps":["trace[864714430] 'read index received' (duration: 177.020008ms)","trace[864714430] 'applied index is now lower than readState.Index' (duration: 28.87313ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:03.587028Z","caller":"traceutil/trace.go:171","msg":"trace[1796764147] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"206.451724ms","start":"2026-04-22T16:22:03.380559Z","end":"2026-04-22T16:22:03.587011Z","steps":["trace[1796764147] 'process raft request' (duration: 205.110398ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.587329Z","caller":"traceutil/trace.go:171","msg":"trace[1795603567] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"125.764813ms","start":"2026-04-22T16:22:03.461549Z","end":"2026-04-22T16:22:03.587314Z","steps":["trace[1795603567] 'process raft request' (duration: 125.234389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:03.587455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.156973ms","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":"warn","ts":"2026-04-22T16:22:03.587362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.743549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:22:03.587461Z","caller":"traceutil/trace.go:171","msg":"trace[876437150] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"114.646427ms","start":"2026-04-22T16:22:03.47279Z","end":"2026-04-22T16:22:03.587436Z","steps":["trace[876437150] 'process raft request' (duration: 114.050941ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.587505Z","caller":"traceutil/trace.go:171","msg":"trace[1059132396] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"126.304827ms","start":"2026-04-22T16:22:03.461188Z","end":"2026-04-22T16:22:03.587492Z","steps":["trace[1059132396] 'agreement among raft nodes before linearized reading' (duration: 126.211975ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.588502Z","caller":"traceutil/trace.go:171","msg":"trace[1008187662] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"115.310874ms","start":"2026-04-22T16:22:03.473163Z","end":"2026-04-22T16:22:03.588474Z","steps":["trace[1008187662] 'process raft request' (duration: 113.705911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:03.588604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.385531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-22T16:22:03.588719Z","caller":"traceutil/trace.go:171","msg":"trace[1205981950] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"207.594344ms","start":"2026-04-22T16:22:03.381077Z","end":"2026-04-22T16:22:03.588671Z","steps":["trace[1205981950] 'agreement among raft nodes before linearized reading' (duration: 206.168015ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.588814Z","caller":"traceutil/trace.go:171","msg":"trace[367489870] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"114.74986ms","start":"2026-04-22T16:22:03.473562Z","end":"2026-04-22T16:22:03.588312Z","steps":["trace[367489870] 'process raft request' (duration: 113.385073ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.589223Z","caller":"traceutil/trace.go:171","msg":"trace[1093319374] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"113.751392ms","start":"2026-04-22T16:22:03.47346Z","end":"2026-04-22T16:22:03.587211Z","steps":["trace[1093319374] 'process raft request' (duration: 113.465375ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.587515Z","caller":"traceutil/trace.go:171","msg":"trace[1142314659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"205.130528ms","start":"2026-04-22T16:22:03.382376Z","end":"2026-04-22T16:22:03.587506Z","steps":["trace[1142314659] 'agreement among raft nodes before linearized reading' (duration: 204.878711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:03.589997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.707012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:22:03.590105Z","caller":"traceutil/trace.go:171","msg":"trace[782470173] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"116.507166ms","start":"2026-04-22T16:22:03.473581Z","end":"2026-04-22T16:22:03.590088Z","steps":["trace[782470173] 'process raft request' (duration: 113.383112ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.590123Z","caller":"traceutil/trace.go:171","msg":"trace[1995498859] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"128.880186ms","start":"2026-04-22T16:22:03.461193Z","end":"2026-04-22T16:22:03.590073Z","steps":["trace[1995498859] 'agreement among raft nodes before linearized reading' (duration: 128.018562ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.589495Z","caller":"traceutil/trace.go:171","msg":"trace[885148092] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"115.764996ms","start":"2026-04-22T16:22:03.473367Z","end":"2026-04-22T16:22:03.589132Z","steps":["trace[885148092] 'process raft request' (duration: 113.527136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:03.591943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.984033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:22:03.592003Z","caller":"traceutil/trace.go:171","msg":"trace[348591164] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:0; response_revision:11; }","duration":"131.031744ms","start":"2026-04-22T16:22:03.460946Z","end":"2026-04-22T16:22:03.591977Z","steps":["trace[348591164] 'agreement among raft nodes before linearized reading' (duration: 130.790087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:03.592449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.754752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:22:03.592847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.250165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:22:03.592907Z","caller":"traceutil/trace.go:171","msg":"trace[1558958480] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"162.326617ms","start":"2026-04-22T16:22:03.430557Z","end":"2026-04-22T16:22:03.592884Z","steps":["trace[1558958480] 'agreement among raft nodes before linearized reading' (duration: 162.246274ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:03.592922Z","caller":"traceutil/trace.go:171","msg":"trace[1684346084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"209.798552ms","start":"2026-04-22T16:22:03.382676Z","end":"2026-04-22T16:22:03.592475Z","steps":["trace[1684346084] 'agreement among raft nodes before linearized reading' (duration: 209.744791ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:08.863472Z","caller":"traceutil/trace.go:171","msg":"trace[572079203] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"158.94405ms","start":"2026-04-22T16:22:08.704504Z","end":"2026-04-22T16:22:08.863448Z","steps":["trace[572079203] 'process raft request' (duration: 158.821696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:09.480365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.219808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:22:09.48049Z","caller":"traceutil/trace.go:171","msg":"trace[254375209] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"608.299289ms","start":"2026-04-22T16:22:08.872169Z","end":"2026-04-22T16:22:09.480468Z","steps":["trace[254375209] 'process raft request' (duration: 259.505655ms)","trace[254375209] 'compare' (duration: 348.065784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:22:09.480546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:08.872156Z","time spent":"608.368591ms","remote":"127.0.0.1:46188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:09.713615Z","caller":"traceutil/trace.go:171","msg":"trace[826124193] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"221.961148ms","start":"2026-04-22T16:22:09.491625Z","end":"2026-04-22T16:22:09.713587Z","steps":["trace[826124193] 'process raft request' (duration: 176.932823ms)","trace[826124193] 'compare' (duration: 44.760337ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:09.713615Z","caller":"traceutil/trace.go:171","msg":"trace[476581731] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"200.587191ms","start":"2026-04-22T16:22:09.512983Z","end":"2026-04-22T16:22:09.71357Z","steps":["trace[476581731] 'read index received' (duration: 155.560857ms)","trace[476581731] 'applied index is now lower than readState.Index' (duration: 45.025014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:22:09.713763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.760055ms","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-04-22T16:22:09.713827Z","caller":"traceutil/trace.go:171","msg":"trace[1794446426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:334; }","duration":"200.854988ms","start":"2026-04-22T16:22:09.512956Z","end":"2026-04-22T16:22:09.713811Z","steps":["trace[1794446426] 'agreement among raft nodes before linearized reading' (duration: 200.711724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.389471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701872173472322,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:22:30.605614Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.087744379s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:22:30.606166Z","caller":"traceutil/trace.go:171","msg":"trace[452611327] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.088350242s","start":"2026-04-22T16:22:29.517782Z","end":"2026-04-22T16:22:30.606132Z","steps":["trace[452611327] 'process raft request' (duration: 1.088147147s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.606378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.517757Z","time spent":"1.088501246s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.687211Z","caller":"traceutil/trace.go:171","msg":"trace[625526137] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.158850413s","start":"2026-04-22T16:22:29.528334Z","end":"2026-04-22T16:22:30.687185Z","steps":["trace[625526137] 'process raft request' (duration: 1.158760991s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:30.687342Z","caller":"traceutil/trace.go:171","msg":"trace[1392778015] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.153265115s","start":"2026-04-22T16:22:29.534034Z","end":"2026-04-22T16:22:30.687299Z","steps":["trace[1392778015] 'process raft request' (duration: 1.153128002s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.68737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.528317Z","time spent":"1.158972425s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.687405Z","caller":"traceutil/trace.go:171","msg":"trace[2142764573] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.153676486s","start":"2026-04-22T16:22:29.533651Z","end":"2026-04-22T16:22:30.687328Z","steps":["trace[2142764573] 'process raft request' (duration: 1.153477661s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.68749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.534027Z","time spent":"1.153394139s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.687591Z","caller":"traceutil/trace.go:171","msg":"trace[1113089915] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.143086818s","start":"2026-04-22T16:22:29.544482Z","end":"2026-04-22T16:22:30.687569Z","steps":["trace[1113089915] 'process raft request' (duration: 1.142793711s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.687627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.533642Z","time spent":"1.153856951s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:22:30.687679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.544465Z","time spent":"1.1431755s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.687697Z","caller":"traceutil/trace.go:171","msg":"trace[1133244461] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.151020506s","start":"2026-04-22T16:22:29.536636Z","end":"2026-04-22T16:22:30.687657Z","steps":["trace[1133244461] 'process raft request' (duration: 1.150609706s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:30.687852Z","caller":"traceutil/trace.go:171","msg":"trace[137574436] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.143131658s","start":"2026-04-22T16:22:29.544702Z","end":"2026-04-22T16:22:30.687833Z","steps":["trace[137574436] 'process raft request' (duration: 1.142612016s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.687832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.536627Z","time spent":"1.151132988s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.68786Z","caller":"traceutil/trace.go:171","msg":"trace[808707613] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.13977744s","start":"2026-04-22T16:22:29.548065Z","end":"2026-04-22T16:22:30.687843Z","steps":["trace[808707613] 'process raft request' (duration: 1.139284629s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.687947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.544695Z","time spent":"1.14320493s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.688001Z","caller":"traceutil/trace.go:171","msg":"trace[721195632] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.112405302s","start":"2026-04-22T16:22:29.575583Z","end":"2026-04-22T16:22:30.687989Z","steps":["trace[721195632] 'process raft request' (duration: 1.112156446s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:30.688062Z","caller":"traceutil/trace.go:171","msg":"trace[1167129505] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"993.898103ms","start":"2026-04-22T16:22:29.694149Z","end":"2026-04-22T16:22:30.688047Z","steps":["trace[1167129505] 'process raft request' (duration: 993.78511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.688051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.548031Z","time spent":"1.139920274s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:22:30.688216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.694117Z","time spent":"994.002786ms","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:22:30.688073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.57556Z","time spent":"1.112473254s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.688434Z","caller":"traceutil/trace.go:171","msg":"trace[2077822867] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.164265422s","start":"2026-04-22T16:22:29.523703Z","end":"2026-04-22T16:22:30.687969Z","steps":["trace[2077822867] 'process raft request' (duration: 1.163281959s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:30.688277Z","caller":"traceutil/trace.go:171","msg":"trace[1362400117] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.136451774s","start":"2026-04-22T16:22:29.551449Z","end":"2026-04-22T16:22:30.6879Z","steps":["trace[1362400117] 'process raft request' (duration: 1.135958952s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.688805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.551441Z","time spent":"1.137284973s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:22:30.689302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.523682Z","time spent":"1.164815535s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:22:30.711994Z","caller":"traceutil/trace.go:171","msg":"trace[1693016889] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:526; }","duration":"822.919078ms","start":"2026-04-22T16:22:29.88905Z","end":"2026-04-22T16:22:30.711969Z","steps":["trace[1693016889] 'read index received' (duration: 717.488484ms)","trace[1693016889] 'applied index is now lower than readState.Index' (duration: 105.429644ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:30.712292Z","caller":"traceutil/trace.go:171","msg":"trace[1375991092] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"100.012568ms","start":"2026-04-22T16:22:30.612255Z","end":"2026-04-22T16:22:30.712267Z","steps":["trace[1375991092] 'process raft request' (duration: 99.273051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.712349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.294107ms","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-04-22T16:22:30.712379Z","caller":"traceutil/trace.go:171","msg":"trace[791102670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"823.399899ms","start":"2026-04-22T16:22:29.888973Z","end":"2026-04-22T16:22:30.712372Z","steps":["trace[791102670] 'agreement among raft nodes before linearized reading' (duration: 823.312617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.712402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.888947Z","time spent":"823.450021ms","remote":"127.0.0.1:46250","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-04-22T16:22:30.730304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.145236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:22:30.73037Z","caller":"traceutil/trace.go:171","msg":"trace[1817325566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"838.271048ms","start":"2026-04-22T16:22:29.892084Z","end":"2026-04-22T16:22:30.730355Z","steps":["trace[1817325566] 'agreement among raft nodes before linearized reading' (duration: 838.149045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.730376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.426748ms","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-04-22T16:22:30.730394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.892043Z","time spent":"838.34598ms","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:22:30.730422Z","caller":"traceutil/trace.go:171","msg":"trace[309776096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"606.572142ms","start":"2026-04-22T16:22:30.123836Z","end":"2026-04-22T16:22:30.730408Z","steps":["trace[309776096] 'agreement among raft nodes before linearized reading' (duration: 606.416538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.730468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:30.123809Z","time spent":"606.650044ms","remote":"127.0.0.1:46250","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-04-22T16:22:30.730541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.526765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:30.730564Z","caller":"traceutil/trace.go:171","msg":"trace[1213830685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"702.606956ms","start":"2026-04-22T16:22:30.027951Z","end":"2026-04-22T16:22:30.730557Z","steps":["trace[1213830685] 'agreement among raft nodes before linearized reading' (duration: 702.571625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.730582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:30.02792Z","time spent":"702.658518ms","remote":"127.0.0.1:46002","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-04-22T16:22:30.73065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.050793ms","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-04-22T16:22:30.730699Z","caller":"traceutil/trace.go:171","msg":"trace[1298646995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"818.163596ms","start":"2026-04-22T16:22:29.912522Z","end":"2026-04-22T16:22:30.730685Z","steps":["trace[1298646995] 'agreement among raft nodes before linearized reading' (duration: 818.034713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:30.73072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:29.912501Z","time spent":"818.213997ms","remote":"127.0.0.1:46250","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-04-22T16:22:31.49166Z","caller":"traceutil/trace.go:171","msg":"trace[1679623662] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"158.536936ms","start":"2026-04-22T16:22:31.333082Z","end":"2026-04-22T16:22:31.491619Z","steps":["trace[1679623662] 'process raft request' (duration: 157.846581ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:31.734749Z","caller":"traceutil/trace.go:171","msg":"trace[1683951657] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"156.09839ms","start":"2026-04-22T16:22:31.578603Z","end":"2026-04-22T16:22:31.734702Z","steps":["trace[1683951657] 'process raft request' (duration: 74.877296ms)","trace[1683951657] 'compare' (duration: 80.830365ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:31.734901Z","caller":"traceutil/trace.go:171","msg":"trace[2038771154] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"139.185745ms","start":"2026-04-22T16:22:31.595682Z","end":"2026-04-22T16:22:31.734868Z","steps":["trace[2038771154] 'process raft request' (duration: 138.98576ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:31.734894Z","caller":"traceutil/trace.go:171","msg":"trace[643321883] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"126.591911ms","start":"2026-04-22T16:22:31.608264Z","end":"2026-04-22T16:22:31.734856Z","steps":["trace[643321883] 'process raft request' (duration: 126.535639ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:31.964193Z","caller":"traceutil/trace.go:171","msg":"trace[1083446020] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"205.362499ms","start":"2026-04-22T16:22:31.758805Z","end":"2026-04-22T16:22:31.964168Z","steps":["trace[1083446020] 'process raft request' (duration: 116.488186ms)","trace[1083446020] 'compare' (duration: 88.75357ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:31.969499Z","caller":"traceutil/trace.go:171","msg":"trace[675297921] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"207.314184ms","start":"2026-04-22T16:22:31.762155Z","end":"2026-04-22T16:22:31.969469Z","steps":["trace[675297921] 'process raft request' (duration: 206.997816ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:31.987501Z","caller":"traceutil/trace.go:171","msg":"trace[818478925] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"109.303208ms","start":"2026-04-22T16:22:31.878167Z","end":"2026-04-22T16:22:31.98747Z","steps":["trace[818478925] 'process raft request' (duration: 109.156185ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:32.122772Z","caller":"traceutil/trace.go:171","msg":"trace[1075382891] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"101.139031ms","start":"2026-04-22T16:22:32.021603Z","end":"2026-04-22T16:22:32.122742Z","steps":["trace[1075382891] 'process raft request' (duration: 100.895156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:32.157031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.411992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:32.157194Z","caller":"traceutil/trace.go:171","msg":"trace[1431958580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"123.621146ms","start":"2026-04-22T16:22:32.03352Z","end":"2026-04-22T16:22:32.157141Z","steps":["trace[1431958580] 'agreement among raft nodes before linearized reading' (duration: 123.29099ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:32.351858Z","caller":"traceutil/trace.go:171","msg":"trace[1750225482] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"133.823325ms","start":"2026-04-22T16:22:32.218005Z","end":"2026-04-22T16:22:32.351828Z","steps":["trace[1750225482] 'read index received' (duration: 72.939473ms)","trace[1750225482] 'applied index is now lower than readState.Index' (duration: 60.882932ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:32.352168Z","caller":"traceutil/trace.go:171","msg":"trace[1087224777] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"141.814161ms","start":"2026-04-22T16:22:32.210225Z","end":"2026-04-22T16:22:32.35204Z","steps":["trace[1087224777] 'process raft request' (duration: 80.821457ms)","trace[1087224777] 'compare' (duration: 60.653076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:22:32.352256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.226804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-22T16:22:32.352345Z","caller":"traceutil/trace.go:171","msg":"trace[968931721] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"134.373808ms","start":"2026-04-22T16:22:32.217948Z","end":"2026-04-22T16:22:32.352321Z","steps":["trace[968931721] 'agreement among raft nodes before linearized reading' (duration: 134.063591ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:32.55414Z","caller":"traceutil/trace.go:171","msg":"trace[497103526] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"171.016353ms","start":"2026-04-22T16:22:32.383072Z","end":"2026-04-22T16:22:32.554088Z","steps":["trace[497103526] 'read index received' (duration: 169.119499ms)","trace[497103526] 'applied index is now lower than readState.Index' (duration: 1.895684ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:32.554262Z","caller":"traceutil/trace.go:171","msg":"trace[316317933] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"195.138837ms","start":"2026-04-22T16:22:32.359066Z","end":"2026-04-22T16:22:32.554205Z","steps":["trace[316317933] 'process raft request' (duration: 193.413837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:32.554485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.354721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-22T16:22:32.554564Z","caller":"traceutil/trace.go:171","msg":"trace[847008729] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:585; }","duration":"171.515605ms","start":"2026-04-22T16:22:32.383035Z","end":"2026-04-22T16:22:32.55455Z","steps":["trace[847008729] 'agreement among raft nodes before linearized reading' (duration: 171.200087ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:34.429281Z","caller":"traceutil/trace.go:171","msg":"trace[662506306] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"205.740278ms","start":"2026-04-22T16:22:34.223501Z","end":"2026-04-22T16:22:34.429241Z","steps":["trace[662506306] 'process raft request' (duration: 205.584965ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:37.899336Z","caller":"traceutil/trace.go:171","msg":"trace[583570117] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"143.336562ms","start":"2026-04-22T16:22:37.755963Z","end":"2026-04-22T16:22:37.899299Z","steps":["trace[583570117] 'read index received' (duration: 142.556453ms)","trace[583570117] 'applied index is now lower than readState.Index' (duration: 779.249µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:37.899435Z","caller":"traceutil/trace.go:171","msg":"trace[1573681414] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"469.599306ms","start":"2026-04-22T16:22:37.429762Z","end":"2026-04-22T16:22:37.899362Z","steps":["trace[1573681414] 'process raft request' (duration: 468.984962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:37.899622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.661689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7vz57\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T16:22:37.899665Z","caller":"traceutil/trace.go:171","msg":"trace[1355307979] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7vz57; range_end:; response_count:1; response_revision:621; }","duration":"143.836483ms","start":"2026-04-22T16:22:37.755817Z","end":"2026-04-22T16:22:37.899653Z","steps":["trace[1355307979] 'agreement among raft nodes before linearized reading' (duration: 143.617038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:37.900376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:37.42973Z","time spent":"469.863263ms","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:22:38.37276Z","caller":"traceutil/trace.go:171","msg":"trace[825661054] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"446.28204ms","start":"2026-04-22T16:22:37.925818Z","end":"2026-04-22T16:22:38.3721Z","steps":["trace[825661054] 'process raft request' (duration: 445.971883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:38.373102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:37.925795Z","time spent":"447.127731ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:22:38.432088Z","caller":"traceutil/trace.go:171","msg":"trace[1765673551] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"403.909394ms","start":"2026-04-22T16:22:38.028138Z","end":"2026-04-22T16:22:38.432048Z","steps":["trace[1765673551] 'read index received' (duration: 343.545842ms)","trace[1765673551] 'applied index is now lower than readState.Index' (duration: 60.361812ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:38.432306Z","caller":"traceutil/trace.go:171","msg":"trace[196423428] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"468.193408ms","start":"2026-04-22T16:22:37.964065Z","end":"2026-04-22T16:22:38.432258Z","steps":["trace[196423428] 'process raft request' (duration: 467.822629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:38.432351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.197411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:38.432475Z","caller":"traceutil/trace.go:171","msg":"trace[1383816601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"404.420827ms","start":"2026-04-22T16:22:38.028038Z","end":"2026-04-22T16:22:38.432458Z","steps":["trace[1383816601] 'agreement among raft nodes before linearized reading' (duration: 404.181891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:38.432551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:38.027978Z","time spent":"404.56142ms","remote":"127.0.0.1:46004","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-04-22T16:22:38.432483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:37.964037Z","time spent":"468.365232ms","remote":"127.0.0.1:46336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:22:39.338875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701872173472509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:22:39.840043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701872173472509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:22:40.304364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488797711s","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-04-22T16:22:40.304545Z","caller":"traceutil/trace.go:171","msg":"trace[231450905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"1.488976055s","start":"2026-04-22T16:22:38.81548Z","end":"2026-04-22T16:22:40.304456Z","steps":["trace[231450905] 'range keys from in-memory index tree' (duration: 1.488675098s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.305302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:38.815447Z","time spent":"1.489835616s","remote":"127.0.0.1:46250","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-04-22T16:22:40.340262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993701872173472509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:22:40.489862Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.665806382s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:22:40.491192Z","caller":"traceutil/trace.go:171","msg":"trace[1990201220] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.673422072s","start":"2026-04-22T16:22:38.817734Z","end":"2026-04-22T16:22:40.491156Z","steps":["trace[1990201220] 'process raft request' (duration: 1.672641573s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.491408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:38.8177Z","time spent":"1.673599675s","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:22:40.525103Z","caller":"traceutil/trace.go:171","msg":"trace[544012059] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"1.686642646s","start":"2026-04-22T16:22:38.838401Z","end":"2026-04-22T16:22:40.525043Z","steps":["trace[544012059] 'read index received' (duration: 1.651721321s)","trace[544012059] 'applied index is now lower than readState.Index' (duration: 34.919565ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:40.525125Z","caller":"traceutil/trace.go:171","msg":"trace[324998440] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"214.949847ms","start":"2026-04-22T16:22:40.310148Z","end":"2026-04-22T16:22:40.525098Z","steps":["trace[324998440] 'process raft request' (duration: 214.722332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.538876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696400146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:22:40.538972Z","caller":"traceutil/trace.go:171","msg":"trace[100331236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.696521889s","start":"2026-04-22T16:22:38.842437Z","end":"2026-04-22T16:22:40.538959Z","steps":["trace[100331236] 'agreement among raft nodes before linearized reading' (duration: 1.682837325s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.539016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:38.842427Z","time spent":"1.696580741s","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:22:40.53902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699613741s","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-04-22T16:22:40.539172Z","caller":"traceutil/trace.go:171","msg":"trace[127034040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"1.699802966s","start":"2026-04-22T16:22:38.839357Z","end":"2026-04-22T16:22:40.53916Z","steps":["trace[127034040] 'agreement among raft nodes before linearized reading' (duration: 1.685878597s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.539213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511979891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:40.539318Z","caller":"traceutil/trace.go:171","msg":"trace[1943929273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.512133244s","start":"2026-04-22T16:22:39.027161Z","end":"2026-04-22T16:22:40.539294Z","steps":["trace[1943929273] 'agreement among raft nodes before linearized reading' (duration: 1.498108053s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.5392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:38.839344Z","time spent":"1.699847757s","remote":"127.0.0.1:46250","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-04-22T16:22:40.539367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:39.027139Z","time spent":"1.512217817s","remote":"127.0.0.1:46002","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-04-22T16:22:40.538891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700456312s","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-04-22T16:22:40.539994Z","caller":"traceutil/trace.go:171","msg":"trace[1002311631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.701606608s","start":"2026-04-22T16:22:38.83837Z","end":"2026-04-22T16:22:40.539977Z","steps":["trace[1002311631] 'agreement among raft nodes before linearized reading' (duration: 1.686841479s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:40.540155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:38.838356Z","time spent":"1.701783552s","remote":"127.0.0.1:46250","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-04-22T16:22:42.021275Z","caller":"traceutil/trace.go:171","msg":"trace[1996403001] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"127.23955ms","start":"2026-04-22T16:22:41.894011Z","end":"2026-04-22T16:22:42.021251Z","steps":["trace[1996403001] 'read index received' (duration: 127.014685ms)","trace[1996403001] 'applied index is now lower than readState.Index' (duration: 223.855µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:22:42.021552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.532497ms","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-04-22T16:22:42.021599Z","caller":"traceutil/trace.go:171","msg":"trace[1921677647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:647; }","duration":"127.618379ms","start":"2026-04-22T16:22:41.893969Z","end":"2026-04-22T16:22:42.021588Z","steps":["trace[1921677647] 'agreement among raft nodes before linearized reading' (duration: 127.432435ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.021526Z","caller":"traceutil/trace.go:171","msg":"trace[1794626991] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"181.549514ms","start":"2026-04-22T16:22:41.839802Z","end":"2026-04-22T16:22:42.021351Z","steps":["trace[1794626991] 'process raft request' (duration: 181.3667ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.021834Z","caller":"traceutil/trace.go:171","msg":"trace[47996904] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"186.807699ms","start":"2026-04-22T16:22:41.834961Z","end":"2026-04-22T16:22:42.021768Z","steps":["trace[47996904] 'process raft request' (duration: 186.075612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.021984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.590894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:42.02203Z","caller":"traceutil/trace.go:171","msg":"trace[480967001] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"112.655935ms","start":"2026-04-22T16:22:41.909363Z","end":"2026-04-22T16:22:42.022019Z","steps":["trace[480967001] 'agreement among raft nodes before linearized reading' (duration: 112.572423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.022073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.432058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:22:42.021894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.516887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:42.022138Z","caller":"traceutil/trace.go:171","msg":"trace[769278827] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"119.841305ms","start":"2026-04-22T16:22:41.902285Z","end":"2026-04-22T16:22:42.022126Z","steps":["trace[769278827] 'agreement among raft nodes before linearized reading' (duration: 119.457716ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.022167Z","caller":"traceutil/trace.go:171","msg":"trace[408440442] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"117.54565ms","start":"2026-04-22T16:22:41.904572Z","end":"2026-04-22T16:22:42.022117Z","steps":["trace[408440442] 'agreement among raft nodes before linearized reading' (duration: 117.441978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.022266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.705104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:22:42.022287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.909158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:22:42.022366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.757316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:42.022381Z","caller":"traceutil/trace.go:171","msg":"trace[1745156187] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"115.044742ms","start":"2026-04-22T16:22:41.907314Z","end":"2026-04-22T16:22:42.022358Z","steps":["trace[1745156187] 'agreement among raft nodes before linearized reading' (duration: 114.899449ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.022399Z","caller":"traceutil/trace.go:171","msg":"trace[1195280041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"117.799047ms","start":"2026-04-22T16:22:41.904592Z","end":"2026-04-22T16:22:42.022391Z","steps":["trace[1195280041] 'agreement among raft nodes before linearized reading' (duration: 117.753806ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.022321Z","caller":"traceutil/trace.go:171","msg":"trace[329470820] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"117.787667ms","start":"2026-04-22T16:22:41.904516Z","end":"2026-04-22T16:22:42.022304Z","steps":["trace[329470820] 'agreement among raft nodes before linearized reading' (duration: 117.678784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.022451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.94686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:22:42.02252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.883262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:42.022521Z","caller":"traceutil/trace.go:171","msg":"trace[1686292239] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"118.025482ms","start":"2026-04-22T16:22:41.904479Z","end":"2026-04-22T16:22:42.022504Z","steps":["trace[1686292239] 'agreement among raft nodes before linearized reading' (duration: 117.93645ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.022548Z","caller":"traceutil/trace.go:171","msg":"trace[1146044340] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"115.965193ms","start":"2026-04-22T16:22:41.906573Z","end":"2026-04-22T16:22:42.022539Z","steps":["trace[1146044340] 'agreement among raft nodes before linearized reading' (duration: 115.926232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.022737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.205843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:42.022881Z","caller":"traceutil/trace.go:171","msg":"trace[1589703849] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"118.215216ms","start":"2026-04-22T16:22:41.904617Z","end":"2026-04-22T16:22:42.022832Z","steps":["trace[1589703849] 'agreement among raft nodes before linearized reading' (duration: 117.141211ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.170758Z","caller":"traceutil/trace.go:171","msg":"trace[1296783959] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"141.244685ms","start":"2026-04-22T16:22:42.029476Z","end":"2026-04-22T16:22:42.17072Z","steps":["trace[1296783959] 'read index received' (duration: 139.943914ms)","trace[1296783959] 'applied index is now lower than readState.Index' (duration: 1.298271ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:42.170842Z","caller":"traceutil/trace.go:171","msg":"trace[1169658309] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"142.994756ms","start":"2026-04-22T16:22:42.027811Z","end":"2026-04-22T16:22:42.170806Z","steps":["trace[1169658309] 'process raft request' (duration: 141.565612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.170925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.44843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:42.170975Z","caller":"traceutil/trace.go:171","msg":"trace[5816065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"141.568253ms","start":"2026-04-22T16:22:42.029393Z","end":"2026-04-22T16:22:42.170961Z","steps":["trace[5816065] 'agreement among raft nodes before linearized reading' (duration: 141.489211ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.334401Z","caller":"traceutil/trace.go:171","msg":"trace[402554859] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"300.781052ms","start":"2026-04-22T16:22:42.03358Z","end":"2026-04-22T16:22:42.334361Z","steps":["trace[402554859] 'process raft request' (duration: 239.937511ms)","trace[402554859] 'compare' (duration: 60.594425ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:42.334457Z","caller":"traceutil/trace.go:171","msg":"trace[1262029425] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"163.581194ms","start":"2026-04-22T16:22:42.170855Z","end":"2026-04-22T16:22:42.334436Z","steps":["trace[1262029425] 'read index received' (duration: 102.649331ms)","trace[1262029425] 'applied index is now lower than readState.Index' (duration: 60.929533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:22:42.334573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.559431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:22:42.33457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:42.033563Z","time spent":"300.909196ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:22:42.334613Z","caller":"traceutil/trace.go:171","msg":"trace[1797142598] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:650; }","duration":"233.634823ms","start":"2026-04-22T16:22:42.100967Z","end":"2026-04-22T16:22:42.334602Z","steps":["trace[1797142598] 'agreement among raft nodes before linearized reading' (duration: 233.534081ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.733683Z","caller":"traceutil/trace.go:171","msg":"trace[593303053] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"204.552153ms","start":"2026-04-22T16:22:42.5291Z","end":"2026-04-22T16:22:42.733652Z","steps":["trace[593303053] 'read index received' (duration: 204.487402ms)","trace[593303053] 'applied index is now lower than readState.Index' (duration: 63.581µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:42.733839Z","caller":"traceutil/trace.go:171","msg":"trace[489424418] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"226.716809ms","start":"2026-04-22T16:22:42.507087Z","end":"2026-04-22T16:22:42.733804Z","steps":["trace[489424418] 'process raft request' (duration: 226.398211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.733987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.858141ms","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-04-22T16:22:42.734028Z","caller":"traceutil/trace.go:171","msg":"trace[1390147306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"204.967894ms","start":"2026-04-22T16:22:42.529047Z","end":"2026-04-22T16:22:42.734015Z","steps":["trace[1390147306] 'agreement among raft nodes before linearized reading' (duration: 204.760259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.740913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.854124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:22:42.740956Z","caller":"traceutil/trace.go:171","msg":"trace[1383167021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"191.929136ms","start":"2026-04-22T16:22:42.549016Z","end":"2026-04-22T16:22:42.740945Z","steps":["trace[1383167021] 'agreement among raft nodes before linearized reading' (duration: 191.754762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:42.741046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.60835ms","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-04-22T16:22:42.741132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.923781ms","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-04-22T16:22:42.741134Z","caller":"traceutil/trace.go:171","msg":"trace[1021635164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"189.767344ms","start":"2026-04-22T16:22:42.551345Z","end":"2026-04-22T16:22:42.741113Z","steps":["trace[1021635164] 'agreement among raft nodes before linearized reading' (duration: 189.448596ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:42.741154Z","caller":"traceutil/trace.go:171","msg":"trace[209067341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"191.009574ms","start":"2026-04-22T16:22:42.550138Z","end":"2026-04-22T16:22:42.741148Z","steps":["trace[209067341] 'agreement among raft nodes before linearized reading' (duration: 190.951212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:45.123704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.412583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-22T16:22:45.123782Z","caller":"traceutil/trace.go:171","msg":"trace[862593789] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:711; }","duration":"123.500145ms","start":"2026-04-22T16:22:45.000266Z","end":"2026-04-22T16:22:45.123767Z","steps":["trace[862593789] 'range keys from in-memory index tree' (duration: 123.272119ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:49.715941Z","caller":"traceutil/trace.go:171","msg":"trace[511254572] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"132.30149ms","start":"2026-04-22T16:22:49.583588Z","end":"2026-04-22T16:22:49.715889Z","steps":["trace[511254572] 'process raft request' (duration: 82.269614ms)","trace[511254572] 'compare' (duration: 49.450182ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:22:50.952419Z","caller":"traceutil/trace.go:171","msg":"trace[452189091] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"106.190279ms","start":"2026-04-22T16:22:50.846202Z","end":"2026-04-22T16:22:50.952393Z","steps":["trace[452189091] 'process raft request' (duration: 106.021715ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:50.952563Z","caller":"traceutil/trace.go:171","msg":"trace[851490482] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"106.283522ms","start":"2026-04-22T16:22:50.846247Z","end":"2026-04-22T16:22:50.952531Z","steps":["trace[851490482] 'process raft request' (duration: 106.104357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:53.051005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.495332ms","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-04-22T16:22:53.051097Z","caller":"traceutil/trace.go:171","msg":"trace[173051564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"101.682507ms","start":"2026-04-22T16:22:52.949394Z","end":"2026-04-22T16:22:53.051077Z","steps":["trace[173051564] 'range keys from in-memory index tree' (duration: 101.243326ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:56.301742Z","caller":"traceutil/trace.go:171","msg":"trace[562272682] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"274.506225ms","start":"2026-04-22T16:22:56.027213Z","end":"2026-04-22T16:22:56.301719Z","steps":["trace[562272682] 'read index received' (duration: 274.306681ms)","trace[562272682] 'applied index is now lower than readState.Index' (duration: 198.604µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:22:56.301894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.67417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:22:56.301959Z","caller":"traceutil/trace.go:171","msg":"trace[308555212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"274.810793ms","start":"2026-04-22T16:22:56.027135Z","end":"2026-04-22T16:22:56.301946Z","steps":["trace[308555212] 'agreement among raft nodes before linearized reading' (duration: 274.70884ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:22:56.30179Z","caller":"traceutil/trace.go:171","msg":"trace[27715370] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"403.143188ms","start":"2026-04-22T16:22:55.89862Z","end":"2026-04-22T16:22:56.301763Z","steps":["trace[27715370] 'process raft request' (duration: 402.958954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:22:56.302169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:22:55.898592Z","time spent":"403.485627ms","remote":"127.0.0.1:46218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:23:10.124823Z","caller":"traceutil/trace.go:171","msg":"trace[1286085086] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"163.967525ms","start":"2026-04-22T16:23:09.960821Z","end":"2026-04-22T16:23:10.124788Z","steps":["trace[1286085086] 'process raft request' (duration: 163.775009ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:23:10.511064Z","caller":"traceutil/trace.go:171","msg":"trace[151572697] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"380.102252ms","start":"2026-04-22T16:23:10.130932Z","end":"2026-04-22T16:23:10.511034Z","steps":["trace[151572697] 'process raft request' (duration: 379.897987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:23:10.511255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:23:10.13084Z","time spent":"380.311507ms","remote":"127.0.0.1:46442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:23:10.516735Z","caller":"traceutil/trace.go:171","msg":"trace[1545362430] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"222.873571ms","start":"2026-04-22T16:23:10.293836Z","end":"2026-04-22T16:23:10.516709Z","steps":["trace[1545362430] 'read index received' (duration: 216.970797ms)","trace[1545362430] 'applied index is now lower than readState.Index' (duration: 5.901254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:23:10.516963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.460194ms","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":"warn","ts":"2026-04-22T16:23:10.516949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.075056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:23:10.517067Z","caller":"traceutil/trace.go:171","msg":"trace[686463728] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:959; }","duration":"223.23534ms","start":"2026-04-22T16:23:10.293806Z","end":"2026-04-22T16:23:10.517041Z","steps":["trace[686463728] 'agreement among raft nodes before linearized reading' (duration: 223.026155ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:23:10.517001Z","caller":"traceutil/trace.go:171","msg":"trace[1291209899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"138.554457ms","start":"2026-04-22T16:23:10.37844Z","end":"2026-04-22T16:23:10.516994Z","steps":["trace[1291209899] 'agreement among raft nodes before linearized reading' (duration: 138.411353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:23:10.517141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.28236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rnv82\" ","response":"range_response_count:1 size:5673"} {"level":"info","ts":"2026-04-22T16:23:10.517192Z","caller":"traceutil/trace.go:171","msg":"trace[16602115] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rnv82; range_end:; response_count:1; response_revision:959; }","duration":"131.355111ms","start":"2026-04-22T16:23:10.385823Z","end":"2026-04-22T16:23:10.517178Z","steps":["trace[16602115] 'agreement among raft nodes before linearized reading' (duration: 131.162647ms)"],"step_count":1}