{"level":"warn","ts":"2026-04-13T15:04:02.945328Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"warn","ts":"2026-04-13T15:04:02.945479Z","caller":"etcdmain/config.go:270","msg":"--snapshot-count is deprecated in 3.6 and will be decommissioned in 3.7."} {"level":"info","ts":"2026-04-13T15:04:02.945509Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--feature-gates=InitialCorruptCheck=true","--initial-advertise-peer-urls=https://199.19.213.209:2380","--initial-cluster=controller=https://199.19.213.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.209:2380","--name=controller","--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","--watch-progress-notify-interval=5s"]} {"level":"info","ts":"2026-04-13T15:04:02.945671Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/var/lib/etcd","dir-type":"empty"} {"level":"warn","ts":"2026-04-13T15:04:02.945697Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T15:04:02.945714Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-04-13T15:04:02.945801Z","caller":"embed/etcd.go:544","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-13T15:04:02.947322Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"]} {"level":"info","ts":"2026-04-13T15:04:02.947620Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.6.4","git-sha":"5400cdc","go-version":"go1.23.11","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"controller","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.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.19.213.209: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,"feature-gates":"InitialCorruptCheck=true","initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","discovery-token":"","discovery-endpoints":"","discovery-dial-timeout":"2s","discovery-request-timeout":"5s","discovery-keepalive-time":"2s","discovery-keepalive-timeout":"6s","discovery-insecure-transport":true,"discovery-insecure-skip-tls-verify":false,"discovery-cert":"","discovery-key":"","discovery-cacert":"","discovery-user":"","downgrade-check-interval":"5s","max-learners":1,"v2-deprecation":"write-only"} {"level":"info","ts":"2026-04-13T15:04:02.948898Z","logger":"bbolt","caller":"backend/backend.go:203","msg":"Opening db file (/var/lib/etcd/member/snap/db) with mode -rw------- and with options: {Timeout: 0s, NoGrowSync: false, NoFreelistSync: true, PreLoadFreelist: false, FreelistType: hashmap, ReadOnly: false, MmapFlags: 8000, InitialMmapSize: 10737418240, PageSize: 0, NoSync: false, OpenFile: 0x0, Mlock: false, Logger: 0xc000682138}"} {"level":"info","ts":"2026-04-13T15:04:02.954251Z","logger":"bbolt","caller":"bbolt@v1.4.2/db.go:321","msg":"Opening bbolt db (/var/lib/etcd/member/snap/db) successfully"} {"level":"info","ts":"2026-04-13T15:04:02.954312Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.588026ms"} {"level":"info","ts":"2026-04-13T15:04:02.954342Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-04-13T15:04:02.954376Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-04-13T15:04:02.954426Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-04-13T15:04:02.964496Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-04-13T15:04:02.964674Z","caller":"etcdserver/bootstrap.go:499","msg":"starting local member","local-member-id":"9409e054be1d0d72","cluster-id":"ff5f9c0688884e7d"} {"level":"info","ts":"2026-04-13T15:04:02.964731Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-04-13T15:04:02.964817Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"9409e054be1d0d72 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T15:04:02.964876Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"9409e054be1d0d72 became follower at term 0"} {"level":"info","ts":"2026-04-13T15:04:02.964897Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft 9409e054be1d0d72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T15:04:02.964921Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"9409e054be1d0d72 became follower at term 1"} {"level":"info","ts":"2026-04-13T15:04:02.964963Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"warn","ts":"2026-04-13T15:04:02.973421Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T15:04:02.981904Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T15:04:02.984573Z","caller":"storage/quota.go:93","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-13T15:04:02.985090Z","caller":"etcdserver/server.go:598","msg":"starting etcd server","local-member-id":"9409e054be1d0d72","local-server-version":"3.6.4","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T15:04:02.985291Z","caller":"etcdserver/server.go:483","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9409e054be1d0d72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T15:04:02.985408Z","caller":"embed/etcd.go:766","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-13T15:04:02.985404Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T15:04:02.985569Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T15:04:02.985662Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T15:04:02.985516Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-04-13T15:04:02.985769Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-04-13T15:04:02.985937Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"9409e054be1d0d72","initial-advertise-peer-urls":["https://199.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T15:04:02.986028Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T15:04:02.986229Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"info","ts":"2026-04-13T15:04:02.986339Z","caller":"membership/cluster.go:424","msg":"added member","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","added-peer-id":"9409e054be1d0d72","added-peer-peer-urls":["https://199.19.213.209:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-04-13T15:04:03.365998Z","logger":"raft","caller":"v3@v3.6.0/raft.go:988","msg":"9409e054be1d0d72 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T15:04:03.366076Z","logger":"raft","caller":"v3@v3.6.0/raft.go:930","msg":"9409e054be1d0d72 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T15:04:03.366157Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"9409e054be1d0d72 received MsgPreVoteResp from 9409e054be1d0d72 at term 1"} {"level":"info","ts":"2026-04-13T15:04:03.366187Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"9409e054be1d0d72 has received 1 MsgPreVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-04-13T15:04:03.366220Z","logger":"raft","caller":"v3@v3.6.0/raft.go:912","msg":"9409e054be1d0d72 became candidate at term 2"} {"level":"info","ts":"2026-04-13T15:04:03.370246Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"9409e054be1d0d72 received MsgVoteResp from 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-04-13T15:04:03.370333Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"9409e054be1d0d72 has received 1 MsgVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-04-13T15:04:03.370392Z","logger":"raft","caller":"v3@v3.6.0/raft.go:970","msg":"9409e054be1d0d72 became leader at term 2"} {"level":"info","ts":"2026-04-13T15:04:03.370421Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 9409e054be1d0d72 elected leader 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-04-13T15:04:03.371625Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"9409e054be1d0d72","local-member-attributes":"{Name:controller ClientURLs:[https://199.19.213.209:2379]}","cluster-id":"ff5f9c0688884e7d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T15:04:03.371664Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T15:04:03.371740Z","caller":"etcdserver/server.go:2404","msg":"setting up initial cluster version using v3 API","cluster-version":"3.6"} {"level":"info","ts":"2026-04-13T15:04:03.371678Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T15:04:03.372230Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T15:04:03.372319Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T15:04:03.372911Z","caller":"membership/cluster.go:682","msg":"set initial cluster version","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","cluster-version":"3.6"} {"level":"info","ts":"2026-04-13T15:04:03.373028Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"warn","ts":"2026-04-13T15:04:03.373556Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-04-13T15:04:03.374656Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-04-13T15:04:03.377476Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-04-13T15:04:03.377571Z","caller":"etcdserver/server.go:2424","msg":"cluster version is updated","cluster-version":"3.6"} {"level":"info","ts":"2026-04-13T15:04:03.377904Z","caller":"version/monitor.go:116","msg":"cluster version differs from storage version.","cluster-version":"3.6.0","storage-version":"3.5.0"} {"level":"info","ts":"2026-04-13T15:04:03.378193Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-04-13T15:04:03.380480Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T15:04:03.382591Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.209:2379"} {"level":"warn","ts":"2026-04-13T15:04:03.689239Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56732","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.689935Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56756","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.720961Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56762","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.729705Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56770","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.741096Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56818","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.764179Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56832","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.774612Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56848","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.782492Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56878","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.806727Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56902","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.814609Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56912","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.824773Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56938","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.832191Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56964","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.843528Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56976","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.853778Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:56996","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.865561Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57004","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.877220Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57024","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.884909Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57048","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.894160Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57070","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.903140Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57092","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.912139Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57100","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.924437Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57120","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:03.977490Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57142","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.001870Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57180","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.016317Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57188","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.025223Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57204","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.035415Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57218","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.047171Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57244","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.056603Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57274","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.065188Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57294","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.074589Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57312","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.084158Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57318","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.093172Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57340","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.102644Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57358","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.111913Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57374","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.120582Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57402","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.133853Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57418","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.142079Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57442","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.154224Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57470","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.166846Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57478","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.183089Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57502","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.192320Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57512","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.203313Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57526","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.214523Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57540","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.235154Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57572","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.251594Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57586","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.260471Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57612","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.269579Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57614","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.279703Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57636","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.289376Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57652","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.301491Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57672","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.305370Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57700","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.317650Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57702","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.326842Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57714","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.336619Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57744","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.348738Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57758","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.353575Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57782","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.378634Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57814","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.392474Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57816","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.412222Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57844","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:04:04.472323Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:57848","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-13T15:04:14.186883Z","caller":"traceutil/trace.go:172","msg":"trace[16237767] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"101.381247ms","start":"2026-04-13T15:04:14.085483Z","end":"2026-04-13T15:04:14.186864Z","steps":["trace[16237767] 'process raft request' (duration: 101.328495ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:14.187036Z","caller":"traceutil/trace.go:172","msg":"trace[861953976] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"101.532182ms","start":"2026-04-13T15:04:14.085483Z","end":"2026-04-13T15:04:14.187015Z","steps":["trace[861953976] 'process raft request' (duration: 101.356576ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:14.187008Z","caller":"traceutil/trace.go:172","msg":"trace[1304193686] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"102.175323ms","start":"2026-04-13T15:04:14.084798Z","end":"2026-04-13T15:04:14.186973Z","steps":["trace[1304193686] 'process raft request' (duration: 101.884254ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:14.187041Z","caller":"traceutil/trace.go:172","msg":"trace[1837129523] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"102.026129ms","start":"2026-04-13T15:04:14.084986Z","end":"2026-04-13T15:04:14.187012Z","steps":["trace[1837129523] 'process raft request' (duration: 101.794521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:16.735354Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:17.236144Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:17.654007Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.473726269s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:04:17.654525Z","caller":"traceutil/trace.go:172","msg":"trace[1246644498] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:396; }","duration":"1.419391095s","start":"2026-04-13T15:04:16.235098Z","end":"2026-04-13T15:04:17.654489Z","steps":["trace[1246644498] 'read index received' (duration: 1.419313783s)","trace[1246644498] 'applied index is now lower than readState.Index' (duration: 75.322µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:17.654738Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.419618242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:17.654821Z","caller":"traceutil/trace.go:172","msg":"trace[670591220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"1.419726326s","start":"2026-04-13T15:04:16.235085Z","end":"2026-04-13T15:04:17.654811Z","steps":["trace[670591220] 'agreement among raft nodes before linearized reading' (duration: 1.419564121s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:17.654862Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:16.235069Z","time spent":"1.419784998s","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:04:17.654857Z","caller":"traceutil/trace.go:172","msg":"trace[1534315921] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.474727933s","start":"2026-04-13T15:04:16.180078Z","end":"2026-04-13T15:04:17.654806Z","steps":["trace[1534315921] 'process raft request' (duration: 1.474569398s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:17.655804Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:16.180049Z","time spent":"1.474904888s","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4933,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:18.155192Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:18.656356Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:19.157497Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:19.657485Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000822755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T15:04:19.657577Z","caller":"traceutil/trace.go:172","msg":"trace[986640007] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000930129s","start":"2026-04-13T15:04:17.656625Z","end":"2026-04-13T15:04:19.657555Z","steps":["trace[986640007] 'agreement among raft nodes before linearized reading' (duration: 2.000818195s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:19.657666Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:17.656605Z","time spent":"2.001047223s","remote":"127.0.0.1:56740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 15:04:19 WARNING: [core] [Server #5]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T15:04:19.657846Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:20.158277Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:20.407631Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"2.753075729s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:04:20.407902Z","caller":"traceutil/trace.go:172","msg":"trace[522204947] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:397; }","duration":"2.753255955s","start":"2026-04-13T15:04:17.654607Z","end":"2026-04-13T15:04:20.407863Z","steps":["trace[522204947] 'read index received' (duration: 2.753244805s)","trace[522204947] 'applied index is now lower than readState.Index' (duration: 9.41µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:20.408121Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.308752283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T15:04:20.408152Z","caller":"traceutil/trace.go:172","msg":"trace[18185143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"3.308802485s","start":"2026-04-13T15:04:17.099343Z","end":"2026-04-13T15:04:20.408146Z","steps":["trace[18185143] 'agreement among raft nodes before linearized reading' (duration: 3.30865239s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.408178Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:17.099327Z","time spent":"3.308845157s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:20.408411Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.066451012s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:20.408441Z","caller":"traceutil/trace.go:172","msg":"trace[1954411481] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:380; }","duration":"4.066481093s","start":"2026-04-13T15:04:16.341953Z","end":"2026-04-13T15:04:20.408434Z","steps":["trace[1954411481] 'agreement among raft nodes before linearized reading' (duration: 4.066421781s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.408520Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.601178472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T15:04:20.408596Z","caller":"traceutil/trace.go:172","msg":"trace[1042916898] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:380; }","duration":"3.601271855s","start":"2026-04-13T15:04:16.807307Z","end":"2026-04-13T15:04:20.408579Z","steps":["trace[1042916898] 'agreement among raft nodes before linearized reading' (duration: 3.601032557s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.408645Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:16.807276Z","time spent":"3.601359607s","remote":"127.0.0.1:56778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.209\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:20.408667Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.310218841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T15:04:20.408725Z","caller":"traceutil/trace.go:172","msg":"trace[453170324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"3.310246402s","start":"2026-04-13T15:04:17.098439Z","end":"2026-04-13T15:04:20.408685Z","steps":["trace[453170324] 'agreement among raft nodes before linearized reading' (duration: 3.3101788s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.408753Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:17.098397Z","time spent":"3.310349975s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-04-13T15:04:20.408935Z","caller":"traceutil/trace.go:172","msg":"trace[1171579589] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"3.117909309s","start":"2026-04-13T15:04:17.291009Z","end":"2026-04-13T15:04:20.408919Z","steps":["trace[1171579589] 'process raft request' (duration: 3.117858018s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.409047Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:17.290979Z","time spent":"3.118008702s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:20.409080Z","caller":"traceutil/trace.go:172","msg":"trace[1026965231] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"4.216163653s","start":"2026-04-13T15:04:16.192908Z","end":"2026-04-13T15:04:20.409072Z","steps":["trace[1026965231] 'process raft request' (duration: 4.215562714s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.409141Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:16.192872Z","time spent":"4.216228455s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:20.416527Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"750.450551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:20.416578Z","caller":"traceutil/trace.go:172","msg":"trace[1324033603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"750.506692ms","start":"2026-04-13T15:04:19.666061Z","end":"2026-04-13T15:04:20.416567Z","steps":["trace[1324033603] 'agreement among raft nodes before linearized reading' (duration: 750.42567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.416606Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:19.666044Z","time spent":"750.555994ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:04:20.416788Z","caller":"traceutil/trace.go:172","msg":"trace[1702483361] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.720650873s","start":"2026-04-13T15:04:18.696125Z","end":"2026-04-13T15:04:20.416776Z","steps":["trace[1702483361] 'process raft request' (duration: 1.720438956s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.416637Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.752135629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-controller\" limit:1 ","response":"range_response_count:1 size:4948"} {"level":"info","ts":"2026-04-13T15:04:20.416760Z","caller":"traceutil/trace.go:172","msg":"trace[1019528294] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"755.760532ms","start":"2026-04-13T15:04:19.660988Z","end":"2026-04-13T15:04:20.416748Z","steps":["trace[1019528294] 'process raft request' (duration: 755.664809ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:20.416859Z","caller":"traceutil/trace.go:172","msg":"trace[659481810] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-controller; range_end:; response_count:1; response_revision:382; }","duration":"2.752355466s","start":"2026-04-13T15:04:17.664479Z","end":"2026-04-13T15:04:20.416834Z","steps":["trace[659481810] 'agreement among raft nodes before linearized reading' (duration: 2.752029466s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:20.416884Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:19.660958Z","time spent":"755.889536ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":769,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:20.416893Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:17.664460Z","time spent":"2.752423837s","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":4972,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-controller\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:20.416897Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:18.696092Z","time spent":"1.720730685s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:24.593276Z","caller":"traceutil/trace.go:172","msg":"trace[449043134] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:414; }","duration":"108.865408ms","start":"2026-04-13T15:04:24.484325Z","end":"2026-04-13T15:04:24.593190Z","steps":["trace[449043134] 'read index received' (duration: 108.854037ms)","trace[449043134] 'applied index is now lower than readState.Index' (duration: 9.761µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:24.594166Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"124.97518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T15:04:24.594255Z","caller":"traceutil/trace.go:172","msg":"trace[1253253939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"125.081043ms","start":"2026-04-13T15:04:24.469158Z","end":"2026-04-13T15:04:24.594239Z","steps":["trace[1253253939] 'agreement among raft nodes before linearized reading' (duration: 124.216457ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:24.594481Z","caller":"traceutil/trace.go:172","msg":"trace[1730120477] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"127.825808ms","start":"2026-04-13T15:04:24.466635Z","end":"2026-04-13T15:04:24.594461Z","steps":["trace[1730120477] 'process raft request' (duration: 126.728925ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:24.601170Z","caller":"traceutil/trace.go:172","msg":"trace[1742206857] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"112.039904ms","start":"2026-04-13T15:04:24.489119Z","end":"2026-04-13T15:04:24.601159Z","steps":["trace[1742206857] 'process raft request' (duration: 111.980042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:26.735624Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967311,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:27.053189Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.624998166s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:04:27.053504Z","caller":"traceutil/trace.go:172","msg":"trace[2129867173] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:417; }","duration":"818.305816ms","start":"2026-04-13T15:04:26.235165Z","end":"2026-04-13T15:04:27.053471Z","steps":["trace[2129867173] 'read index received' (duration: 818.288205ms)","trace[2129867173] 'applied index is now lower than readState.Index' (duration: 11.821µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:27.053665Z","caller":"traceutil/trace.go:172","msg":"trace[1948873232] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.625589394s","start":"2026-04-13T15:04:25.428057Z","end":"2026-04-13T15:04:27.053646Z","steps":["trace[1948873232] 'process raft request' (duration: 1.62545495s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:27.053743Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"818.548854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:27.053778Z","caller":"traceutil/trace.go:172","msg":"trace[1615480219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"818.614256ms","start":"2026-04-13T15:04:26.235152Z","end":"2026-04-13T15:04:27.053766Z","steps":["trace[1615480219] 'agreement among raft nodes before linearized reading' (duration: 818.496892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:27.053809Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:26.235122Z","time spent":"818.680587ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T15:04:27.053855Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:25.428019Z","time spent":"1.625705097s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:27.553949Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:28.054161Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:28.316789Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.263327266s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:04:28.317023Z","caller":"traceutil/trace.go:172","msg":"trace[1804085254] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:418; }","duration":"1.263382908s","start":"2026-04-13T15:04:27.053619Z","end":"2026-04-13T15:04:28.317002Z","steps":["trace[1804085254] 'read index received' (duration: 1.263355567s)","trace[1804085254] 'applied index is now lower than readState.Index' (duration: 26.061µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.589353Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.248725319s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:28.589463Z","caller":"traceutil/trace.go:172","msg":"trace[84114643] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:399; }","duration":"2.248850842s","start":"2026-04-13T15:04:26.340598Z","end":"2026-04-13T15:04:28.589449Z","steps":["trace[84114643] 'agreement among raft nodes before linearized reading' (duration: 1.976490281s)","trace[84114643] 'range keys from in-memory index tree' (duration: 272.208377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.589483Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.772151451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T15:04:28.589554Z","caller":"traceutil/trace.go:172","msg":"trace[209803161] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:399; }","duration":"1.772221914s","start":"2026-04-13T15:04:26.817313Z","end":"2026-04-13T15:04:28.589535Z","steps":["trace[209803161] 'agreement among raft nodes before linearized reading' (duration: 1.499737838s)","trace[209803161] 'range keys from in-memory index tree' (duration: 272.30715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.589609Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:26.817290Z","time spent":"1.772309107s","remote":"127.0.0.1:56778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.209\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:28.589623Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.984536898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T15:04:28.589724Z","caller":"traceutil/trace.go:172","msg":"trace[832193087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"1.984651251s","start":"2026-04-13T15:04:26.605049Z","end":"2026-04-13T15:04:28.589701Z","steps":["trace[832193087] 'agreement among raft nodes before linearized reading' (duration: 1.71202731s)","trace[832193087] 'range keys from in-memory index tree' (duration: 272.388793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.589766Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.904251784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T15:04:28.589771Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.55188005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" limit:1 ","response":"range_response_count:1 size:4338"} {"level":"info","ts":"2026-04-13T15:04:28.589799Z","caller":"traceutil/trace.go:172","msg":"trace[1568025271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"1.904312975s","start":"2026-04-13T15:04:26.685476Z","end":"2026-04-13T15:04:28.589789Z","steps":["trace[1568025271] 'agreement among raft nodes before linearized reading' (duration: 1.631591052s)","trace[1568025271] 'range keys from in-memory index tree' (duration: 272.584739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.589801Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:26.605024Z","time spent":"1.984761264s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:28.589827Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:26.685443Z","time spent":"1.904373347s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-13T15:04:28.589826Z","caller":"traceutil/trace.go:172","msg":"trace[71961428] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:399; }","duration":"1.551933002s","start":"2026-04-13T15:04:27.037874Z","end":"2026-04-13T15:04:28.589807Z","steps":["trace[71961428] 'agreement among raft nodes before linearized reading' (duration: 1.279192638s)","trace[71961428] 'range keys from in-memory index tree' (duration: 272.59254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.589873Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:27.037858Z","time spent":"1.552003854s","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4362,"request content":"key:\"/registry/deployments/kube-system/coredns\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:28.590004Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"272.761645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:04:28.590392Z","caller":"traceutil/trace.go:172","msg":"trace[241836496] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"2.100189954s","start":"2026-04-13T15:04:26.490179Z","end":"2026-04-13T15:04:28.590369Z","steps":["trace[241836496] 'process raft request' (duration: 1.826865163s)","trace[241836496] 'compare' (duration: 272.520248ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:28.590422Z","caller":"traceutil/trace.go:172","msg":"trace[1480168657] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"273.31422ms","start":"2026-04-13T15:04:28.317094Z","end":"2026-04-13T15:04:28.590409Z","steps":["trace[1480168657] 'read index received' (duration: 28.941µs)","trace[1480168657] 'applied index is now lower than readState.Index' (duration: 273.284099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.590485Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.535599975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:04:28.590518Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:26.490154Z","time spent":"2.100295517s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:28.590553Z","caller":"traceutil/trace.go:172","msg":"trace[1907801320] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"1.990500116s","start":"2026-04-13T15:04:26.600033Z","end":"2026-04-13T15:04:28.590533Z","steps":["trace[1907801320] 'process raft request' (duration: 1.990037663s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:28.590677Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:26.600004Z","time spent":"1.990589729s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:28.590510Z","caller":"traceutil/trace.go:172","msg":"trace[1064197985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"1.535627426s","start":"2026-04-13T15:04:27.054874Z","end":"2026-04-13T15:04:28.590501Z","steps":["trace[1064197985] 'agreement among raft nodes before linearized reading' (duration: 1.535579755s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:28.590767Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:27.054860Z","time spent":"1.535894944s","remote":"127.0.0.1:56740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:04:28.810497Z","caller":"traceutil/trace.go:172","msg":"trace[343998358] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:420; }","duration":"217.523125ms","start":"2026-04-13T15:04:28.592949Z","end":"2026-04-13T15:04:28.810473Z","steps":["trace[343998358] 'read index received' (duration: 217.511415ms)","trace[343998358] 'applied index is now lower than readState.Index' (duration: 10.07µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:28.813235Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"220.198574ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:28.813275Z","caller":"traceutil/trace.go:172","msg":"trace[1730129058] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:401; }","duration":"220.324517ms","start":"2026-04-13T15:04:28.592941Z","end":"2026-04-13T15:04:28.813266Z","steps":["trace[1730129058] 'agreement among raft nodes before linearized reading' (duration: 217.656689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:28.816590Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"216.599099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:28.816648Z","caller":"traceutil/trace.go:172","msg":"trace[1116107974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"216.662901ms","start":"2026-04-13T15:04:28.599975Z","end":"2026-04-13T15:04:28.816637Z","steps":["trace[1116107974] 'agreement among raft nodes before linearized reading' (duration: 216.568358ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:28.816883Z","caller":"traceutil/trace.go:172","msg":"trace[547701384] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"220.983527ms","start":"2026-04-13T15:04:28.595884Z","end":"2026-04-13T15:04:28.816867Z","steps":["trace[547701384] 'process raft request' (duration: 220.693239ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:28.816929Z","caller":"traceutil/trace.go:172","msg":"trace[1669274796] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"220.307047ms","start":"2026-04-13T15:04:28.596589Z","end":"2026-04-13T15:04:28.816896Z","steps":["trace[1669274796] 'process raft request' (duration: 220.177873ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:28.816965Z","caller":"traceutil/trace.go:172","msg":"trace[341103373] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"209.54661ms","start":"2026-04-13T15:04:28.607401Z","end":"2026-04-13T15:04:28.816948Z","steps":["trace[341103373] 'process raft request' (duration: 209.444327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:43.575035Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"232.993805ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:43.575148Z","caller":"traceutil/trace.go:172","msg":"trace[109570496] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:482; }","duration":"233.121659ms","start":"2026-04-13T15:04:43.342002Z","end":"2026-04-13T15:04:43.575124Z","steps":["trace[109570496] 'range keys from in-memory index tree' (duration: 232.934514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:43.575251Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"284.4719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" limit:1 ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-04-13T15:04:43.575361Z","caller":"traceutil/trace.go:172","msg":"trace[1833860108] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:482; }","duration":"284.650055ms","start":"2026-04-13T15:04:43.290683Z","end":"2026-04-13T15:04:43.575333Z","steps":["trace[1833860108] 'agreement among raft nodes before linearized reading' (duration: 33.912802ms)","trace[1833860108] 'range keys from in-memory index tree' (duration: 250.501316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:43.575431Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.593509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:04:43.575890Z","caller":"traceutil/trace.go:172","msg":"trace[1508403896] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"322.92488ms","start":"2026-04-13T15:04:43.252949Z","end":"2026-04-13T15:04:43.575874Z","steps":["trace[1508403896] 'process raft request' (duration: 322.603651ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:43.575686Z","caller":"traceutil/trace.go:172","msg":"trace[1911958722] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"323.551855ms","start":"2026-04-13T15:04:43.252101Z","end":"2026-04-13T15:04:43.575653Z","steps":["trace[1911958722] 'process raft request' (duration: 72.652138ms)","trace[1911958722] 'compare' (duration: 250.345873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:43.576003Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.252941Z","time spent":"322.993971ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14972,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:43.576027Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.252077Z","time spent":"323.881384ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:43.576283Z","caller":"traceutil/trace.go:172","msg":"trace[1101997612] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"315.341274ms","start":"2026-04-13T15:04:43.260920Z","end":"2026-04-13T15:04:43.576261Z","steps":["trace[1101997612] 'process raft request' (duration: 314.717659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:43.576393Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.260896Z","time spent":"315.443986ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14232,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:43.576968Z","caller":"traceutil/trace.go:172","msg":"trace[838503236] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"291.015899ms","start":"2026-04-13T15:04:43.285936Z","end":"2026-04-13T15:04:43.576952Z","steps":["trace[838503236] 'process raft request' (duration: 289.775877ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:43.577173Z","caller":"traceutil/trace.go:172","msg":"trace[130176691] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"290.868875ms","start":"2026-04-13T15:04:43.286290Z","end":"2026-04-13T15:04:43.577159Z","steps":["trace[130176691] 'process raft request' (duration: 290.478124ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:43.577715Z","caller":"traceutil/trace.go:172","msg":"trace[151522370] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"290.631529ms","start":"2026-04-13T15:04:43.287069Z","end":"2026-04-13T15:04:43.577700Z","steps":["trace[151522370] 'process raft request' (duration: 289.804307ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:43.580417Z","caller":"traceutil/trace.go:172","msg":"trace[1946435650] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"289.793448ms","start":"2026-04-13T15:04:43.290591Z","end":"2026-04-13T15:04:43.580385Z","steps":["trace[1946435650] 'process raft request' (duration: 286.918284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:43.845773Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.353977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:43.845850Z","caller":"traceutil/trace.go:172","msg":"trace[49303497] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:489; }","duration":"176.44557ms","start":"2026-04-13T15:04:43.669386Z","end":"2026-04-13T15:04:43.845831Z","steps":["trace[49303497] 'agreement among raft nodes before linearized reading' (duration: 32.849535ms)","trace[49303497] 'range keys from in-memory index tree' (duration: 143.469622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:43.845886Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"143.546964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:04:43.846011Z","caller":"traceutil/trace.go:172","msg":"trace[1987870264] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"261.89607ms","start":"2026-04-13T15:04:43.584091Z","end":"2026-04-13T15:04:43.845987Z","steps":["trace[1987870264] 'process raft request' (duration: 118.192581ms)","trace[1987870264] 'compare' (duration: 143.39547ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:43.846169Z","caller":"traceutil/trace.go:172","msg":"trace[1202130443] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"256.47586ms","start":"2026-04-13T15:04:43.589682Z","end":"2026-04-13T15:04:43.846157Z","steps":["trace[1202130443] 'process raft request' (duration: 256.428269ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:43.846456Z","caller":"traceutil/trace.go:172","msg":"trace[242658578] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"260.712978ms","start":"2026-04-13T15:04:43.585729Z","end":"2026-04-13T15:04:43.846442Z","steps":["trace[242658578] 'process raft request' (duration: 260.271697ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:43.846495Z","caller":"traceutil/trace.go:172","msg":"trace[1285908013] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"257.023474ms","start":"2026-04-13T15:04:43.589457Z","end":"2026-04-13T15:04:43.846481Z","steps":["trace[1285908013] 'process raft request' (duration: 256.609674ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:44.041739Z","caller":"traceutil/trace.go:172","msg":"trace[850885962] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:516; }","duration":"177.815026ms","start":"2026-04-13T15:04:43.863897Z","end":"2026-04-13T15:04:44.041712Z","steps":["trace[850885962] 'read index received' (duration: 177.807756ms)","trace[850885962] 'applied index is now lower than readState.Index' (duration: 6.21µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:44.451285Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"109.830543ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:44.451377Z","caller":"traceutil/trace.go:172","msg":"trace[2010150322] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:493; }","duration":"109.998417ms","start":"2026-04-13T15:04:44.341361Z","end":"2026-04-13T15:04:44.451359Z","steps":["trace[2010150322] 'range keys from in-memory index tree' (duration: 109.766971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:44.451514Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"587.596641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" limit:1 ","response":"range_response_count:1 size:14309"} {"level":"warn","ts":"2026-04-13T15:04:44.451579Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"409.727284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:04:44.451600Z","caller":"traceutil/trace.go:172","msg":"trace[2066689322] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:493; }","duration":"587.680073ms","start":"2026-04-13T15:04:43.863894Z","end":"2026-04-13T15:04:44.451574Z","steps":["trace[2066689322] 'agreement among raft nodes before linearized reading' (duration: 177.906459ms)","trace[2066689322] 'range keys from in-memory index tree' (duration: 409.623961ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:44.451644Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.863886Z","time spent":"587.750105ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":14333,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" limit:1 "} {"level":"info","ts":"2026-04-13T15:04:44.451665Z","caller":"traceutil/trace.go:172","msg":"trace[1072047768] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"600.729679ms","start":"2026-04-13T15:04:43.850916Z","end":"2026-04-13T15:04:44.451646Z","steps":["trace[1072047768] 'process raft request' (duration: 190.871842ms)","trace[1072047768] 'compare' (duration: 409.548829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:44.451732Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.850892Z","time spent":"600.807162ms","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:44.453337Z","caller":"traceutil/trace.go:172","msg":"trace[1641464133] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:517; }","duration":"411.494068ms","start":"2026-04-13T15:04:44.041821Z","end":"2026-04-13T15:04:44.453315Z","steps":["trace[1641464133] 'read index received' (duration: 411.483968ms)","trace[1641464133] 'applied index is now lower than readState.Index' (duration: 8.36µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:44.453571Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"411.870477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" limit:1 ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-13T15:04:44.453619Z","caller":"traceutil/trace.go:172","msg":"trace[1550633449] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:496; }","duration":"411.922899ms","start":"2026-04-13T15:04:44.041684Z","end":"2026-04-13T15:04:44.453607Z","steps":["trace[1550633449] 'agreement among raft nodes before linearized reading' (duration: 411.758014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:44.453652Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:44.041655Z","time spent":"411.98783ms","remote":"127.0.0.1:57112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" limit:1 "} {"level":"info","ts":"2026-04-13T15:04:44.453942Z","caller":"traceutil/trace.go:172","msg":"trace[1460145200] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"596.281504ms","start":"2026-04-13T15:04:43.857620Z","end":"2026-04-13T15:04:44.453901Z","steps":["trace[1460145200] 'process raft request' (duration: 595.723519ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:44.454042Z","caller":"traceutil/trace.go:172","msg":"trace[1359337328] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"590.533096ms","start":"2026-04-13T15:04:43.863486Z","end":"2026-04-13T15:04:44.454019Z","steps":["trace[1359337328] 'process raft request' (duration: 589.94076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:44.454187Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.863477Z","time spent":"590.643359ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18335,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:44.454253Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.857604Z","time spent":"596.406348ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4634,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:44.458558Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"497.243844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:44.458608Z","caller":"traceutil/trace.go:172","msg":"trace[1642964113] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:496; }","duration":"497.296095ms","start":"2026-04-13T15:04:43.961302Z","end":"2026-04-13T15:04:44.458598Z","steps":["trace[1642964113] 'agreement among raft nodes before linearized reading' (duration: 492.577775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:44.458634Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.961283Z","time spent":"497.343607ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":29,"request content":"key:\"/registry/secrets\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:44.458736Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"222.022344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:44.458783Z","caller":"traceutil/trace.go:172","msg":"trace[564581502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"222.074365ms","start":"2026-04-13T15:04:44.236697Z","end":"2026-04-13T15:04:44.458772Z","steps":["trace[564581502] 'agreement among raft nodes before linearized reading' (duration: 222.004094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:44.458842Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"518.350078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" limit:1 ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-13T15:04:44.458886Z","caller":"traceutil/trace.go:172","msg":"trace[180956805] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:496; }","duration":"518.383119ms","start":"2026-04-13T15:04:43.940482Z","end":"2026-04-13T15:04:44.458866Z","steps":["trace[180956805] 'agreement among raft nodes before linearized reading' (duration: 513.412923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:44.458911Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:43.940443Z","time spent":"518.462001ms","remote":"127.0.0.1:57112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:46.744746Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967522,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T15:04:47.063026Z","caller":"traceutil/trace.go:172","msg":"trace[1616237013] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:527; }","duration":"819.296996ms","start":"2026-04-13T15:04:46.243699Z","end":"2026-04-13T15:04:47.062996Z","steps":["trace[1616237013] 'read index received' (duration: 819.288236ms)","trace[1616237013] 'applied index is now lower than readState.Index' (duration: 7.12µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:47.063182Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"819.46498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:47.063287Z","caller":"traceutil/trace.go:172","msg":"trace[414114261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"819.574023ms","start":"2026-04-13T15:04:46.243694Z","end":"2026-04-13T15:04:47.063268Z","steps":["trace[414114261] 'agreement among raft nodes before linearized reading' (duration: 819.429229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.063339Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.243675Z","time spent":"819.648294ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:04:47.063537Z","caller":"traceutil/trace.go:172","msg":"trace[2140856091] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"832.857425ms","start":"2026-04-13T15:04:46.230662Z","end":"2026-04-13T15:04:47.063520Z","steps":["trace[2140856091] 'process raft request' (duration: 832.688871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.063828Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.230636Z","time spent":"833.099201ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:47.072681Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.703924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T15:04:47.072869Z","caller":"traceutil/trace.go:172","msg":"trace[2056485735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"158.899699ms","start":"2026-04-13T15:04:46.913958Z","end":"2026-04-13T15:04:47.072858Z","steps":["trace[2056485735] 'agreement among raft nodes before linearized reading' (duration: 158.613242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.073075Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"159.199298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-13T15:04:47.073348Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"732.067076ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:47.073372Z","caller":"traceutil/trace.go:172","msg":"trace[1513168817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"159.495785ms","start":"2026-04-13T15:04:46.913867Z","end":"2026-04-13T15:04:47.073363Z","steps":["trace[1513168817] 'agreement among raft nodes before linearized reading' (duration: 159.154336ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.073400Z","caller":"traceutil/trace.go:172","msg":"trace[527227494] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:504; }","duration":"732.120757ms","start":"2026-04-13T15:04:46.341270Z","end":"2026-04-13T15:04:47.073390Z","steps":["trace[527227494] 'agreement among raft nodes before linearized reading' (duration: 732.049255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.073979Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"255.328518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T15:04:47.074034Z","caller":"traceutil/trace.go:172","msg":"trace[759737216] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:504; }","duration":"255.38867ms","start":"2026-04-13T15:04:46.818633Z","end":"2026-04-13T15:04:47.074022Z","steps":["trace[759737216] 'agreement among raft nodes before linearized reading' (duration: 254.904018ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074109Z","caller":"traceutil/trace.go:172","msg":"trace[1071315029] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"321.877217ms","start":"2026-04-13T15:04:46.752221Z","end":"2026-04-13T15:04:47.074099Z","steps":["trace[1071315029] 'process raft request' (duration: 321.844487ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074134Z","caller":"traceutil/trace.go:172","msg":"trace[1639850102] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"795.042645ms","start":"2026-04-13T15:04:46.279081Z","end":"2026-04-13T15:04:47.074124Z","steps":["trace[1639850102] 'process raft request' (duration: 794.652716ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074160Z","caller":"traceutil/trace.go:172","msg":"trace[76282550] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"807.377024ms","start":"2026-04-13T15:04:46.266768Z","end":"2026-04-13T15:04:47.074145Z","steps":["trace[76282550] 'process raft request' (duration: 806.863481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.074181Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.279076Z","time spent":"795.080386ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:47.074185Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.752188Z","time spent":"321.947859ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:47.074245Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.266750Z","time spent":"807.440926ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:47.074340Z","caller":"traceutil/trace.go:172","msg":"trace[461745040] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"791.762093ms","start":"2026-04-13T15:04:46.282567Z","end":"2026-04-13T15:04:47.074329Z","steps":["trace[461745040] 'process raft request' (duration: 791.23644ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074365Z","caller":"traceutil/trace.go:172","msg":"trace[1582541770] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"830.015372ms","start":"2026-04-13T15:04:46.244338Z","end":"2026-04-13T15:04:47.074354Z","steps":["trace[1582541770] 'process raft request' (duration: 829.233303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.074397Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.282556Z","time spent":"791.813414ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:47.074406Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.244328Z","time spent":"830.060083ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:47.074478Z","caller":"traceutil/trace.go:172","msg":"trace[436087153] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"803.426236ms","start":"2026-04-13T15:04:46.271044Z","end":"2026-04-13T15:04:47.074471Z","steps":["trace[436087153] 'process raft request' (duration: 802.634606ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074490Z","caller":"traceutil/trace.go:172","msg":"trace[1878631213] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"806.108753ms","start":"2026-04-13T15:04:46.268369Z","end":"2026-04-13T15:04:47.074478Z","steps":["trace[1878631213] 'process raft request' (duration: 805.287363ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074503Z","caller":"traceutil/trace.go:172","msg":"trace[257226293] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"784.532421ms","start":"2026-04-13T15:04:46.289964Z","end":"2026-04-13T15:04:47.074496Z","steps":["trace[257226293] 'process raft request' (duration: 783.899836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.074527Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.271035Z","time spent":"803.470686ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:47.074545Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.289948Z","time spent":"784.578192ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:47.074554Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.268361Z","time spent":"806.162134ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:47.074478Z","caller":"traceutil/trace.go:172","msg":"trace[231182619] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"790.903661ms","start":"2026-04-13T15:04:46.283565Z","end":"2026-04-13T15:04:47.074469Z","steps":["trace[231182619] 'process raft request' (duration: 790.267215ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.074610Z","caller":"traceutil/trace.go:172","msg":"trace[632747918] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"770.257323ms","start":"2026-04-13T15:04:46.304343Z","end":"2026-04-13T15:04:47.074600Z","steps":["trace[632747918] 'process raft request' (duration: 769.556826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.074624Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.283554Z","time spent":"791.051524ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:47.074717Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.304318Z","time spent":"770.318715ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:47.074719Z","caller":"traceutil/trace.go:172","msg":"trace[980808154] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"795.66179ms","start":"2026-04-13T15:04:46.278977Z","end":"2026-04-13T15:04:47.074638Z","steps":["trace[980808154] 'process raft request' (duration: 794.728397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.074777Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.278961Z","time spent":"795.792534ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:47.074795Z","caller":"traceutil/trace.go:172","msg":"trace[1703699607] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"347.071259ms","start":"2026-04-13T15:04:46.727715Z","end":"2026-04-13T15:04:47.074787Z","steps":["trace[1703699607] 'process raft request' (duration: 346.29531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.074842Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.727653Z","time spent":"347.164232ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:47.074844Z","caller":"traceutil/trace.go:172","msg":"trace[799243464] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"669.956657ms","start":"2026-04-13T15:04:46.404875Z","end":"2026-04-13T15:04:47.074832Z","steps":["trace[799243464] 'process raft request' (duration: 669.098576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:47.075192Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:46.404841Z","time spent":"670.025549ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:47.736763Z","caller":"traceutil/trace.go:172","msg":"trace[1710185405] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"121.84852ms","start":"2026-04-13T15:04:47.614895Z","end":"2026-04-13T15:04:47.736743Z","steps":["trace[1710185405] 'process raft request' (duration: 121.722147ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:47.736748Z","caller":"traceutil/trace.go:172","msg":"trace[866850089] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"130.622278ms","start":"2026-04-13T15:04:47.606079Z","end":"2026-04-13T15:04:47.736702Z","steps":["trace[866850089] 'process raft request' (duration: 111.925014ms)","trace[866850089] 'compare' (duration: 18.357456ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:48.235180Z","caller":"traceutil/trace.go:172","msg":"trace[1552948103] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"157.690069ms","start":"2026-04-13T15:04:48.077467Z","end":"2026-04-13T15:04:48.235157Z","steps":["trace[1552948103] 'process raft request' (duration: 157.179146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:48.647639Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"303.184929ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:48.647705Z","caller":"traceutil/trace.go:172","msg":"trace[1894500246] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:578; }","duration":"303.280201ms","start":"2026-04-13T15:04:48.344411Z","end":"2026-04-13T15:04:48.647691Z","steps":["trace[1894500246] 'range keys from in-memory index tree' (duration: 303.140498ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:48.647838Z","caller":"traceutil/trace.go:172","msg":"trace[425795103] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:606; }","duration":"410.140147ms","start":"2026-04-13T15:04:48.237691Z","end":"2026-04-13T15:04:48.647831Z","steps":["trace[425795103] 'read index received' (duration: 410.125776ms)","trace[425795103] 'applied index is now lower than readState.Index' (duration: 13.881µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:48.647942Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"410.246489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:48.647974Z","caller":"traceutil/trace.go:172","msg":"trace[947618112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"410.27833ms","start":"2026-04-13T15:04:48.237687Z","end":"2026-04-13T15:04:48.647965Z","steps":["trace[947618112] 'agreement among raft nodes before linearized reading' (duration: 410.221069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:48.648016Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:48.237669Z","time spent":"410.341211ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:04:48.648224Z","caller":"traceutil/trace.go:172","msg":"trace[1117789119] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"526.388483ms","start":"2026-04-13T15:04:48.121800Z","end":"2026-04-13T15:04:48.648188Z","steps":["trace[1117789119] 'process raft request' (duration: 526.177788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:48.648293Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:48.121758Z","time spent":"526.496346ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T15:04:48.661484Z","caller":"traceutil/trace.go:172","msg":"trace[1096502692] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"385.809356ms","start":"2026-04-13T15:04:48.275655Z","end":"2026-04-13T15:04:48.661464Z","steps":["trace[1096502692] 'process raft request' (duration: 385.566149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:48.661611Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:48.275633Z","time spent":"385.908038ms","remote":"127.0.0.1:56812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:48.663358Z","caller":"traceutil/trace.go:172","msg":"trace[17651162] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"198.937997ms","start":"2026-04-13T15:04:48.464393Z","end":"2026-04-13T15:04:48.663331Z","steps":["trace[17651162] 'process raft request' (duration: 197.573433ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:51.974245Z","caller":"traceutil/trace.go:172","msg":"trace[455207153] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"185.660577ms","start":"2026-04-13T15:04:51.788521Z","end":"2026-04-13T15:04:51.974182Z","steps":["trace[455207153] 'process raft request' (duration: 180.050832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:52.343662Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"108.431449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:52.343759Z","caller":"traceutil/trace.go:172","msg":"trace[1426218384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"108.550391ms","start":"2026-04-13T15:04:52.235194Z","end":"2026-04-13T15:04:52.343744Z","steps":["trace[1426218384] 'range keys from in-memory index tree' (duration: 108.326936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:53.398952Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"261.563043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T15:04:53.399025Z","caller":"traceutil/trace.go:172","msg":"trace[379265839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"261.646715ms","start":"2026-04-13T15:04:53.137364Z","end":"2026-04-13T15:04:53.399011Z","steps":["trace[379265839] 'agreement among raft nodes before linearized reading' (duration: 59.123579ms)","trace[379265839] 'range keys from in-memory index tree' (duration: 202.331721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:53.399618Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"202.904635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:04:53.400150Z","caller":"traceutil/trace.go:172","msg":"trace[1305014634] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"203.673492ms","start":"2026-04-13T15:04:53.196457Z","end":"2026-04-13T15:04:53.400130Z","steps":["trace[1305014634] 'read index received' (duration: 202.639688ms)","trace[1305014634] 'applied index is now lower than readState.Index' (duration: 1.031854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:53.400276Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"262.867814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T15:04:53.400318Z","caller":"traceutil/trace.go:172","msg":"trace[1838024963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"262.911565ms","start":"2026-04-13T15:04:53.137395Z","end":"2026-04-13T15:04:53.400307Z","steps":["trace[1838024963] 'agreement among raft nodes before linearized reading' (duration: 262.790453ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:53.400071Z","caller":"traceutil/trace.go:172","msg":"trace[81869989] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"298.979299ms","start":"2026-04-13T15:04:53.100890Z","end":"2026-04-13T15:04:53.399869Z","steps":["trace[81869989] 'process raft request' (duration: 95.730126ms)","trace[81869989] 'compare' (duration: 202.767252ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:53.400728Z","caller":"traceutil/trace.go:172","msg":"trace[1628981100] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"229.195907ms","start":"2026-04-13T15:04:53.171522Z","end":"2026-04-13T15:04:53.400717Z","steps":["trace[1628981100] 'process raft request' (duration: 228.428779ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:53.636894Z","caller":"traceutil/trace.go:172","msg":"trace[1855957012] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"233.308585ms","start":"2026-04-13T15:04:53.403562Z","end":"2026-04-13T15:04:53.636870Z","steps":["trace[1855957012] 'process raft request' (duration: 224.752822ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:53.637286Z","caller":"traceutil/trace.go:172","msg":"trace[467384580] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"171.339416ms","start":"2026-04-13T15:04:53.465929Z","end":"2026-04-13T15:04:53.637269Z","steps":["trace[467384580] 'process raft request' (duration: 170.431904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:54.734059Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:55.257508Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:55.758468Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:56.233416Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000112496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T15:04:56.233473Z","caller":"traceutil/trace.go:172","msg":"trace[1819312917] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000194658s","start":"2026-04-13T15:04:54.233265Z","end":"2026-04-13T15:04:56.233460Z","steps":["trace[1819312917] 'agreement among raft nodes before linearized reading' (duration: 2.000109596s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:56.233510Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:54.233244Z","time spent":"2.00025689s","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 15:04:56 WARNING: [core] [Server #5]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T15:04:56.259143Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:56.760124Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:04:57.061117Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"2.999842523s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:04:57.061359Z","caller":"traceutil/trace.go:172","msg":"trace[1447460955] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:638; }","duration":"2.828056138s","start":"2026-04-13T15:04:54.233278Z","end":"2026-04-13T15:04:57.061334Z","steps":["trace[1447460955] 'read index received' (duration: 2.828047438s)","trace[1447460955] 'applied index is now lower than readState.Index' (duration: 6.99µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:57.062130Z","caller":"traceutil/trace.go:172","msg":"trace[955017092] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"3.005228419s","start":"2026-04-13T15:04:54.056885Z","end":"2026-04-13T15:04:57.062113Z","steps":["trace[955017092] 'process raft request' (duration: 3.004772768s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:57.062306Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:54.056850Z","time spent":"3.005323741s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:57.523403Z","caller":"traceutil/trace.go:172","msg":"trace[325546317] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:639; }","duration":"461.960937ms","start":"2026-04-13T15:04:57.061405Z","end":"2026-04-13T15:04:57.523366Z","steps":["trace[325546317] 'read index received' (duration: 461.947147ms)","trace[325546317] 'applied index is now lower than readState.Index' (duration: 11.71µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:57.542576Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.897867717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T15:04:57.542685Z","caller":"traceutil/trace.go:172","msg":"trace[1236177208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"1.897977819s","start":"2026-04-13T15:04:55.644681Z","end":"2026-04-13T15:04:57.542658Z","steps":["trace[1236177208] 'agreement among raft nodes before linearized reading' (duration: 1.878815659s)","trace[1236177208] 'range keys from in-memory index tree' (duration: 18.905894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:57.542726Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:55.644650Z","time spent":"1.89806617s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:57.542710Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.30868685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:04:57.542787Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.111685668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T15:04:57.542844Z","caller":"traceutil/trace.go:172","msg":"trace[1287961076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"2.111740369s","start":"2026-04-13T15:04:55.431089Z","end":"2026-04-13T15:04:57.542830Z","steps":["trace[1287961076] 'agreement among raft nodes before linearized reading' (duration: 2.092413965s)","trace[1287961076] 'range keys from in-memory index tree' (duration: 19.219571ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:57.542857Z","caller":"traceutil/trace.go:172","msg":"trace[1578913358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"1.308829023s","start":"2026-04-13T15:04:56.233991Z","end":"2026-04-13T15:04:57.542820Z","steps":["trace[1578913358] 'agreement among raft nodes before linearized reading' (duration: 1.289464498s)","trace[1578913358] 'range keys from in-memory index tree' (duration: 19.16094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:57.542885Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:55.431065Z","time spent":"2.111810161s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:57.542903Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:56.233977Z","time spent":"1.308916094s","remote":"127.0.0.1:56740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T15:04:57.542969Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"723.445529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T15:04:57.542994Z","caller":"traceutil/trace.go:172","msg":"trace[740079652] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:610; }","duration":"723.46919ms","start":"2026-04-13T15:04:56.819518Z","end":"2026-04-13T15:04:57.542987Z","steps":["trace[740079652] 'agreement among raft nodes before linearized reading' (duration: 703.955172ms)","trace[740079652] 'range keys from in-memory index tree' (duration: 19.437646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:57.543012Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:56.819496Z","time spent":"723.51134ms","remote":"127.0.0.1:56778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.209\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:57.543011Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.201306341s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:57.543049Z","caller":"traceutil/trace.go:172","msg":"trace[2062640511] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:610; }","duration":"3.201341392s","start":"2026-04-13T15:04:54.341694Z","end":"2026-04-13T15:04:57.543036Z","steps":["trace[2062640511] 'agreement among raft nodes before linearized reading' (duration: 3.181811684s)","trace[2062640511] 'range keys from in-memory index tree' (duration: 19.482537ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:04:57.543138Z","caller":"traceutil/trace.go:172","msg":"trace[471649256] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"148.684342ms","start":"2026-04-13T15:04:57.394439Z","end":"2026-04-13T15:04:57.543123Z","steps":["trace[471649256] 'process raft request' (duration: 148.653741ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:57.543248Z","caller":"traceutil/trace.go:172","msg":"trace[52258165] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"2.136836874s","start":"2026-04-13T15:04:55.406404Z","end":"2026-04-13T15:04:57.543241Z","steps":["trace[52258165] 'process raft request' (duration: 2.136626799s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:57.543317Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:55.406377Z","time spent":"2.136897206s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:57.543324Z","caller":"traceutil/trace.go:172","msg":"trace[496338808] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"425.940571ms","start":"2026-04-13T15:04:57.117367Z","end":"2026-04-13T15:04:57.543308Z","steps":["trace[496338808] 'process raft request' (duration: 425.700345ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:57.543375Z","caller":"traceutil/trace.go:172","msg":"trace[1781159027] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"2.427664741s","start":"2026-04-13T15:04:55.115703Z","end":"2026-04-13T15:04:57.543368Z","steps":["trace[1781159027] 'process raft request' (duration: 2.407762505s)","trace[1781159027] 'compare' (duration: 19.467826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:57.543410Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:55.115676Z","time spent":"2.427718623s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:04:57.543408Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:57.117332Z","time spent":"426.024403ms","remote":"127.0.0.1:57448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:04:58.842243Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967722,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T15:04:59.005247Z","caller":"traceutil/trace.go:172","msg":"trace[1627113203] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:651; }","duration":"663.667197ms","start":"2026-04-13T15:04:58.341510Z","end":"2026-04-13T15:04:59.005177Z","steps":["trace[1627113203] 'read index received' (duration: 663.658766ms)","trace[1627113203] 'applied index is now lower than readState.Index' (duration: 7.051µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:04:59.005422Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"663.895432ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:04:59.005458Z","caller":"traceutil/trace.go:172","msg":"trace[670322994] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"663.948833ms","start":"2026-04-13T15:04:58.341495Z","end":"2026-04-13T15:04:59.005444Z","steps":["trace[670322994] 'agreement among raft nodes before linearized reading' (duration: 663.850081ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:59.007525Z","caller":"traceutil/trace.go:172","msg":"trace[1330877140] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"995.705486ms","start":"2026-04-13T15:04:58.011776Z","end":"2026-04-13T15:04:59.007481Z","steps":["trace[1330877140] 'process raft request' (duration: 994.502468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:59.007665Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"431.265847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5fmjl\" limit:1 ","response":"range_response_count:1 size:18350"} {"level":"info","ts":"2026-04-13T15:04:59.007772Z","caller":"traceutil/trace.go:172","msg":"trace[281269644] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5fmjl; range_end:; response_count:1; response_revision:622; }","duration":"431.40672ms","start":"2026-04-13T15:04:58.576342Z","end":"2026-04-13T15:04:59.007749Z","steps":["trace[281269644] 'agreement among raft nodes before linearized reading' (duration: 431.036641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:04:59.007843Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:58.576325Z","time spent":"431.506202ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":18374,"request content":"key:\"/registry/pods/kube-system/cilium-5fmjl\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:04:59.007958Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:58.011739Z","time spent":"995.898039ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:04:59.284826Z","caller":"traceutil/trace.go:172","msg":"trace[877064879] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"245.797992ms","start":"2026-04-13T15:04:59.038975Z","end":"2026-04-13T15:04:59.284773Z","steps":["trace[877064879] 'process raft request' (duration: 245.526996ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:04:59.292719Z","caller":"traceutil/trace.go:172","msg":"trace[63215831] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"209.624245ms","start":"2026-04-13T15:04:59.083064Z","end":"2026-04-13T15:04:59.292689Z","steps":["trace[63215831] 'process raft request' (duration: 209.383069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:00.078482Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:00.578654Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:01.079675Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:01.580046Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:02.081157Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577967730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:02.303317Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"2.753479625s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:05:02.303554Z","caller":"traceutil/trace.go:172","msg":"trace[1622853814] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:654; }","duration":"2.725634836s","start":"2026-04-13T15:04:59.577891Z","end":"2026-04-13T15:05:02.303526Z","steps":["trace[1622853814] 'read index received' (duration: 2.725626456s)","trace[1622853814] 'applied index is now lower than readState.Index' (duration: 7.02µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:02.676332Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.334586003s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:02.676437Z","caller":"traceutil/trace.go:172","msg":"trace[1733580772] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"3.334700136s","start":"2026-04-13T15:04:59.341719Z","end":"2026-04-13T15:05:02.676420Z","steps":["trace[1733580772] 'range keys from in-memory index tree' (duration: 3.334522682s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.676524Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.862500028s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:02.676547Z","caller":"traceutil/trace.go:172","msg":"trace[1904055463] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"1.862526438s","start":"2026-04-13T15:05:00.814013Z","end":"2026-04-13T15:05:02.676540Z","steps":["trace[1904055463] 'range keys from in-memory index tree' (duration: 1.862462277s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.677301Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.0994098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T15:05:02.677344Z","caller":"traceutil/trace.go:172","msg":"trace[1874510334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"3.099453081s","start":"2026-04-13T15:04:59.577878Z","end":"2026-04-13T15:05:02.677331Z","steps":["trace[1874510334] 'agreement among raft nodes before linearized reading' (duration: 2.725738408s)","trace[1874510334] 'range keys from in-memory index tree' (duration: 373.443847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:02.677379Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:59.577838Z","time spent":"3.099529743s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:02.677492Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"373.496369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:05:02.677606Z","caller":"traceutil/trace.go:172","msg":"trace[10569132] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"3.127883244s","start":"2026-04-13T15:04:59.549705Z","end":"2026-04-13T15:05:02.677588Z","steps":["trace[10569132] 'process raft request' (duration: 2.754186391s)","trace[10569132] 'compare' (duration: 373.137611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:02.677683Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:59.549673Z","time spent":"3.127968765s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:02.794852Z","caller":"traceutil/trace.go:172","msg":"trace[831548522] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:655; }","duration":"491.215559ms","start":"2026-04-13T15:05:02.303590Z","end":"2026-04-13T15:05:02.794805Z","steps":["trace[831548522] 'read index received' (duration: 491.202779ms)","trace[831548522] 'applied index is now lower than readState.Index' (duration: 10.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:02.798351Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.561764646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:02.798551Z","caller":"traceutil/trace.go:172","msg":"trace[1010859708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.561976261s","start":"2026-04-13T15:05:01.236555Z","end":"2026-04-13T15:05:02.798531Z","steps":["trace[1010859708] 'agreement among raft nodes before linearized reading' (duration: 1.558267669s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.798414Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.217078167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T15:05:02.798616Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:01.236529Z","time spent":"1.562072313s","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:05:02.799385Z","caller":"traceutil/trace.go:172","msg":"trace[683547402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"3.218044508s","start":"2026-04-13T15:04:59.581303Z","end":"2026-04-13T15:05:02.799347Z","steps":["trace[683547402] 'agreement among raft nodes before linearized reading' (duration: 3.213681262s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.799504Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:59.581271Z","time spent":"3.218189671s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-13T15:05:02.800118Z","caller":"traceutil/trace.go:172","msg":"trace[1236556707] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"3.244818594s","start":"2026-04-13T15:04:59.555265Z","end":"2026-04-13T15:05:02.800084Z","steps":["trace[1236556707] 'process raft request' (duration: 3.239622409s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.800306Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:59.555237Z","time spent":"3.244916996s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:02.803594Z","caller":"traceutil/trace.go:172","msg":"trace[697757432] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.564917096s","start":"2026-04-13T15:05:01.238644Z","end":"2026-04-13T15:05:02.803561Z","steps":["trace[697757432] 'process raft request' (duration: 1.564816604s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.803718Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:01.238610Z","time spent":"1.565043328s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":528,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:02.804306Z","caller":"traceutil/trace.go:172","msg":"trace[383403201] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"3.097611006s","start":"2026-04-13T15:04:59.706669Z","end":"2026-04-13T15:05:02.804280Z","steps":["trace[383403201] 'process raft request' (duration: 3.09236038s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:02.804462Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:04:59.706629Z","time spent":"3.097776109s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:05:02.806595Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"130.106214ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:02.806676Z","caller":"traceutil/trace.go:172","msg":"trace[1633343418] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:628; }","duration":"130.190625ms","start":"2026-04-13T15:05:02.676471Z","end":"2026-04-13T15:05:02.806662Z","steps":["trace[1633343418] 'agreement among raft nodes before linearized reading' (duration: 130.070163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:03.016672Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"210.118677ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-13T15:05:03.016823Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"209.081415ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:03.016898Z","caller":"traceutil/trace.go:172","msg":"trace[325211871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:628; }","duration":"209.188787ms","start":"2026-04-13T15:05:02.807692Z","end":"2026-04-13T15:05:03.016881Z","steps":["trace[325211871] 'range keys from in-memory index tree' (duration: 209.047694ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:03.016991Z","caller":"traceutil/trace.go:172","msg":"trace[57197128] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"335.125348ms","start":"2026-04-13T15:05:02.681855Z","end":"2026-04-13T15:05:03.016981Z","steps":["trace[57197128] 'process raft request' (duration: 334.918624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:03.017079Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:02.681831Z","time spent":"335.18948ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:03.017019Z","caller":"traceutil/trace.go:172","msg":"trace[1086678105] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"210.503825ms","start":"2026-04-13T15:05:02.806497Z","end":"2026-04-13T15:05:03.017001Z","steps":["trace[1086678105] 'read index received' (duration: 277.726µs)","trace[1086678105] 'applied index is now lower than readState.Index' (duration: 210.225039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:03.036798Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"232.633351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:03.037043Z","caller":"traceutil/trace.go:172","msg":"trace[1799067441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"232.805774ms","start":"2026-04-13T15:05:02.804135Z","end":"2026-04-13T15:05:03.036941Z","steps":["trace[1799067441] 'agreement among raft nodes before linearized reading' (duration: 212.97008ms)","trace[1799067441] 'range keys from in-memory index tree' (duration: 19.63412ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:03.037737Z","caller":"traceutil/trace.go:172","msg":"trace[125570982] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"231.154338ms","start":"2026-04-13T15:05:02.806547Z","end":"2026-04-13T15:05:03.037702Z","steps":["trace[125570982] 'process raft request' (duration: 210.428524ms)","trace[125570982] 'compare' (duration: 20.413107ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:03.037993Z","caller":"traceutil/trace.go:172","msg":"trace[175083827] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"176.318926ms","start":"2026-04-13T15:05:02.861636Z","end":"2026-04-13T15:05:03.037955Z","steps":["trace[175083827] 'process raft request' (duration: 175.989989ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:03.160741Z","caller":"traceutil/trace.go:172","msg":"trace[1056705990] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"105.088452ms","start":"2026-04-13T15:05:03.055631Z","end":"2026-04-13T15:05:03.160719Z","steps":["trace[1056705990] 'process raft request' (duration: 103.064009ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:03.701160Z","caller":"traceutil/trace.go:172","msg":"trace[401710772] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:667; }","duration":"104.52948ms","start":"2026-04-13T15:05:03.596604Z","end":"2026-04-13T15:05:03.701133Z","steps":["trace[401710772] 'read index received' (duration: 104.51864ms)","trace[401710772] 'applied index is now lower than readState.Index' (duration: 9.07µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:03.701365Z","caller":"traceutil/trace.go:172","msg":"trace[1454689941] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"104.890019ms","start":"2026-04-13T15:05:03.596456Z","end":"2026-04-13T15:05:03.701346Z","steps":["trace[1454689941] 'process raft request' (duration: 104.703165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:03.701643Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.958391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5fmjl\" limit:1 ","response":"range_response_count:1 size:18522"} {"level":"info","ts":"2026-04-13T15:05:03.702338Z","caller":"traceutil/trace.go:172","msg":"trace[1044892376] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5fmjl; range_end:; response_count:1; response_revision:637; }","duration":"105.718408ms","start":"2026-04-13T15:05:03.596598Z","end":"2026-04-13T15:05:03.702316Z","steps":["trace[1044892376] 'agreement among raft nodes before linearized reading' (duration: 104.680715ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:04.135950Z","caller":"traceutil/trace.go:172","msg":"trace[1082369569] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"417.697564ms","start":"2026-04-13T15:05:03.718222Z","end":"2026-04-13T15:05:04.135920Z","steps":["trace[1082369569] 'process raft request' (duration: 417.434729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:04.136171Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:03.718171Z","time spent":"417.895608ms","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:05:04.432108Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"196.793091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:04.432264Z","caller":"traceutil/trace.go:172","msg":"trace[659947350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"196.913204ms","start":"2026-04-13T15:05:04.235277Z","end":"2026-04-13T15:05:04.432191Z","steps":["trace[659947350] 'range keys from in-memory index tree' (duration: 196.726541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:04.432448Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"197.305902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:04.432628Z","caller":"traceutil/trace.go:172","msg":"trace[997849445] range","detail":"{range_begin:/registry/roles; range_end:; response_count:0; response_revision:638; }","duration":"197.550108ms","start":"2026-04-13T15:05:04.235047Z","end":"2026-04-13T15:05:04.432597Z","steps":["trace[997849445] 'range keys from in-memory index tree' (duration: 197.18575ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:05.173510Z","caller":"traceutil/trace.go:172","msg":"trace[1300745677] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"126.843801ms","start":"2026-04-13T15:05:05.046651Z","end":"2026-04-13T15:05:05.173495Z","steps":["trace[1300745677] 'process raft request' (duration: 124.864998ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:07.293942Z","caller":"traceutil/trace.go:172","msg":"trace[345628819] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"111.489694ms","start":"2026-04-13T15:05:07.182425Z","end":"2026-04-13T15:05:07.293915Z","steps":["trace[345628819] 'process raft request' (duration: 111.239448ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:08.982036Z","caller":"traceutil/trace.go:172","msg":"trace[1795607628] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"102.65369ms","start":"2026-04-13T15:05:08.879365Z","end":"2026-04-13T15:05:08.982019Z","steps":["trace[1795607628] 'process raft request' (duration: 101.70127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:13.040190Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:59998","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.145304Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60018","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.207333Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60034","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.298763Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40300","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.423369Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40320","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.435111Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40336","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.452878Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40346","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.475964Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40368","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.498893Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40404","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.515595Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40410","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.557318Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40432","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.582827Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40454","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.610471Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40458","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.649910Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40494","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.696362Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40506","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.753378Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40520","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.798153Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40544","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.817901Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40578","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.869373Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40590","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.895444Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40610","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.917579Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40632","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.930370Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40642","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.943647Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40658","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.957821Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40670","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.972244Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40688","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:13.988931Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40706","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:14.000674Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40726","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:14.013408Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40732","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:14.032848Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40740","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:14.047922Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40768","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:15.943729Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40794","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:16.055838Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40814","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:16.079973Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40834","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-13T15:05:16.095069Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:40850","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-13T15:05:23.459490Z","caller":"traceutil/trace.go:172","msg":"trace[809668716] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"100.797908ms","start":"2026-04-13T15:05:23.358662Z","end":"2026-04-13T15:05:23.459460Z","steps":["trace[809668716] 'process raft request' (duration: 100.594445ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:23.782740Z","caller":"traceutil/trace.go:172","msg":"trace[67957059] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:846; }","duration":"323.551052ms","start":"2026-04-13T15:05:23.459165Z","end":"2026-04-13T15:05:23.782716Z","steps":["trace[67957059] 'read index received' (duration: 323.517872ms)","trace[67957059] 'applied index is now lower than readState.Index' (duration: 11.56µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:23.782865Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"407.899407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:23.782912Z","caller":"traceutil/trace.go:172","msg":"trace[1450901964] range","detail":"{range_begin:/registry/horizontalpodautoscalers; range_end:; response_count:0; response_revision:809; }","duration":"407.959848ms","start":"2026-04-13T15:05:23.374939Z","end":"2026-04-13T15:05:23.782899Z","steps":["trace[1450901964] 'agreement among raft nodes before linearized reading' (duration: 407.854536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:23.782944Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:23.374918Z","time spent":"408.017969ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers\" limit:1 "} {"level":"info","ts":"2026-04-13T15:05:23.783020Z","caller":"traceutil/trace.go:172","msg":"trace[212657440] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"375.419815ms","start":"2026-04-13T15:05:23.407588Z","end":"2026-04-13T15:05:23.783008Z","steps":["trace[212657440] 'process raft request' (duration: 375.310043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:23.783107Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:23.407559Z","time spent":"375.493576ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:24.053571Z","caller":"traceutil/trace.go:172","msg":"trace[1777557992] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:847; }","duration":"270.715505ms","start":"2026-04-13T15:05:23.782823Z","end":"2026-04-13T15:05:24.053538Z","steps":["trace[1777557992] 'read index received' (duration: 270.706155ms)","trace[1777557992] 'applied index is now lower than readState.Index' (duration: 7.92µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:24.275945Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"679.168796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:24.276036Z","caller":"traceutil/trace.go:172","msg":"trace[1222484701] range","detail":"{range_begin:/registry/resourcequotas; range_end:; response_count:0; response_revision:810; }","duration":"679.267048ms","start":"2026-04-13T15:05:23.596748Z","end":"2026-04-13T15:05:24.276015Z","steps":["trace[1222484701] 'agreement among raft nodes before linearized reading' (duration: 456.907088ms)","trace[1222484701] 'range keys from in-memory index tree' (duration: 222.229248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:24.276076Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:23.596724Z","time spent":"679.34193ms","remote":"127.0.0.1:56886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:24.276194Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"597.736748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:24.276274Z","caller":"traceutil/trace.go:172","msg":"trace[1394908892] range","detail":"{range_begin:/registry/volumeattachments; range_end:; response_count:0; response_revision:810; }","duration":"597.8163ms","start":"2026-04-13T15:05:23.678442Z","end":"2026-04-13T15:05:24.276258Z","steps":["trace[1394908892] 'agreement among raft nodes before linearized reading' (duration: 375.241616ms)","trace[1394908892] 'range keys from in-memory index tree' (duration: 222.351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:24.276310Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:23.678418Z","time spent":"597.882671ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:24.276327Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"222.540144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:05:24.277023Z","caller":"traceutil/trace.go:172","msg":"trace[1942501499] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"179.150638ms","start":"2026-04-13T15:05:24.097854Z","end":"2026-04-13T15:05:24.277004Z","steps":["trace[1942501499] 'read index received' (duration: 50.321µs)","trace[1942501499] 'applied index is now lower than readState.Index' (duration: 179.099087ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:24.277051Z","caller":"traceutil/trace.go:172","msg":"trace[1533800438] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"812.849244ms","start":"2026-04-13T15:05:23.464177Z","end":"2026-04-13T15:05:24.277026Z","steps":["trace[1533800438] 'process raft request' (duration: 589.536856ms)","trace[1533800438] 'compare' (duration: 222.36763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:24.277135Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"179.2885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:05:24.277151Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:23.464153Z","time spent":"812.935465ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:24.277165Z","caller":"traceutil/trace.go:172","msg":"trace[1887495925] range","detail":"{range_begin:/registry/flowschemas; range_end:; response_count:0; response_revision:811; }","duration":"179.323491ms","start":"2026-04-13T15:05:24.097834Z","end":"2026-04-13T15:05:24.277158Z","steps":["trace[1887495925] 'agreement among raft nodes before linearized reading' (duration: 179.260549ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:25.154308Z","caller":"traceutil/trace.go:172","msg":"trace[1533510578] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"120.957756ms","start":"2026-04-13T15:05:25.033297Z","end":"2026-04-13T15:05:25.154255Z","steps":["trace[1533510578] 'process raft request' (duration: 111.251213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:29.672692Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577968281,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:30.172965Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577968281,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:30.673938Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010074577968281,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T15:05:30.996273Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"2.260607511s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T15:05:30.996501Z","caller":"traceutil/trace.go:172","msg":"trace[5208337] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:869; }","duration":"1.824553714s","start":"2026-04-13T15:05:29.171922Z","end":"2026-04-13T15:05:30.996476Z","steps":["trace[5208337] 'read index received' (duration: 1.824544064s)","trace[5208337] 'applied index is now lower than readState.Index' (duration: 8.13µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:30.996811Z","caller":"traceutil/trace.go:172","msg":"trace[227918337] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"2.261300154s","start":"2026-04-13T15:05:28.735498Z","end":"2026-04-13T15:05:30.996798Z","steps":["trace[227918337] 'process raft request' (duration: 2.261145351s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.996821Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.82487003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T15:05:30.996905Z","caller":"traceutil/trace.go:172","msg":"trace[1613142534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"1.824962962s","start":"2026-04-13T15:05:29.171918Z","end":"2026-04-13T15:05:30.996881Z","steps":["trace[1613142534] 'agreement among raft nodes before linearized reading' (duration: 1.824688567s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.996911Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:28.735475Z","time spent":"2.261367136s","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4553,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:05:30.996947Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.171901Z","time spent":"1.825037373s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:30.998573Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"762.914262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:05:30.998574Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.770704681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.998610Z","caller":"traceutil/trace.go:172","msg":"trace[1102998289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"762.951023ms","start":"2026-04-13T15:05:30.235649Z","end":"2026-04-13T15:05:30.998600Z","steps":["trace[1102998289] 'agreement among raft nodes before linearized reading' (duration: 762.893782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.998630Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:30.235633Z","time spent":"762.992033ms","remote":"127.0.0.1:56740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T15:05:30.998631Z","caller":"traceutil/trace.go:172","msg":"trace[1343441380] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:832; }","duration":"1.770762922s","start":"2026-04-13T15:05:29.227852Z","end":"2026-04-13T15:05:30.998615Z","steps":["trace[1343441380] 'agreement among raft nodes before linearized reading' (duration: 1.77067275s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.998681Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.227835Z","time spent":"1.770825783s","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/pods\" limit:1 "} {"level":"info","ts":"2026-04-13T15:05:30.998734Z","caller":"traceutil/trace.go:172","msg":"trace[1404928932] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"1.832940399s","start":"2026-04-13T15:05:29.165773Z","end":"2026-04-13T15:05:30.998714Z","steps":["trace[1404928932] 'process raft request' (duration: 1.832892978s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.998782Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"865.121428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.998808Z","caller":"traceutil/trace.go:172","msg":"trace[1123490394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"865.145288ms","start":"2026-04-13T15:05:30.133654Z","end":"2026-04-13T15:05:30.998799Z","steps":["trace[1123490394] 'agreement among raft nodes before linearized reading' (duration: 865.104948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.998831Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:30.133633Z","time spent":"865.192829ms","remote":"127.0.0.1:56742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T15:05:30.998851Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"171.286254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.998872Z","caller":"traceutil/trace.go:172","msg":"trace[888278002] range","detail":"{range_begin:/registry/networkpolicies; range_end:; response_count:0; response_revision:834; }","duration":"171.314644ms","start":"2026-04-13T15:05:30.827552Z","end":"2026-04-13T15:05:30.998866Z","steps":["trace[888278002] 'agreement among raft nodes before linearized reading' (duration: 171.278284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.998986Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.51561339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-13T15:05:30.999017Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.656974551s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.999041Z","caller":"traceutil/trace.go:172","msg":"trace[1463713726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"1.515673111s","start":"2026-04-13T15:05:29.483355Z","end":"2026-04-13T15:05:30.999028Z","steps":["trace[1463713726] 'agreement among raft nodes before linearized reading' (duration: 1.515497828s)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:30.999073Z","caller":"traceutil/trace.go:172","msg":"trace[930275188] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:834; }","duration":"1.657025432s","start":"2026-04-13T15:05:29.342031Z","end":"2026-04-13T15:05:30.999057Z","steps":["trace[930275188] 'agreement among raft nodes before linearized reading' (duration: 1.656961781s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.999089Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.483320Z","time spent":"1.515755443s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"info","ts":"2026-04-13T15:05:30.999095Z","caller":"traceutil/trace.go:172","msg":"trace[1308349151] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"2.064216325s","start":"2026-04-13T15:05:28.934868Z","end":"2026-04-13T15:05:30.999085Z","steps":["trace[1308349151] 'process raft request' (duration: 2.063687876s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.999151Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:28.934836Z","time spent":"2.064286456s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T15:05:30.999222Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.258896582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.999279Z","caller":"traceutil/trace.go:172","msg":"trace[1172064745] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:834; }","duration":"1.258978784s","start":"2026-04-13T15:05:29.740286Z","end":"2026-04-13T15:05:30.999265Z","steps":["trace[1172064745] 'agreement among raft nodes before linearized reading' (duration: 1.258846531s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.999312Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.740262Z","time spent":"1.259042274s","remote":"127.0.0.1:57062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":29,"request content":"key:\"/registry/minions\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:30.999342Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.691179972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.999390Z","caller":"traceutil/trace.go:172","msg":"trace[406633109] range","detail":"{range_begin:/registry/csidrivers; range_end:; response_count:0; response_revision:834; }","duration":"1.691230673s","start":"2026-04-13T15:05:29.308148Z","end":"2026-04-13T15:05:30.999378Z","steps":["trace[406633109] 'agreement among raft nodes before linearized reading' (duration: 1.691151481s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.999417Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.308124Z","time spent":"1.691285273s","remote":"127.0.0.1:57552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":24,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:30.999548Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.082228881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/servicecidrs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:30.999580Z","caller":"traceutil/trace.go:172","msg":"trace[1084500403] range","detail":"{range_begin:/registry/servicecidrs; range_end:; response_count:0; response_revision:834; }","duration":"1.082261431s","start":"2026-04-13T15:05:29.917309Z","end":"2026-04-13T15:05:30.999570Z","steps":["trace[1084500403] 'agreement among raft nodes before linearized reading' (duration: 1.08220757s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.999098Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"565.04158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:05:30.999605Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.917285Z","time spent":"1.082314272s","remote":"127.0.0.1:57328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":29,"request content":"key:\"/registry/servicecidrs\" limit:1 "} {"level":"info","ts":"2026-04-13T15:05:30.999635Z","caller":"traceutil/trace.go:172","msg":"trace[1724036380] range","detail":"{range_begin:/registry/poddisruptionbudgets; range_end:; response_count:0; response_revision:834; }","duration":"565.58537ms","start":"2026-04-13T15:05:30.434036Z","end":"2026-04-13T15:05:30.999621Z","steps":["trace[1724036380] 'agreement among raft nodes before linearized reading' (duration: 565.03215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:30.999678Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:30.434015Z","time spent":"565.650101ms","remote":"127.0.0.1:57346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets\" limit:1 "} {"level":"warn","ts":"2026-04-13T15:05:30.999053Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"253.921611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:05:30.998860Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:29.165751Z","time spent":"1.833035581s","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T15:05:30.999838Z","caller":"traceutil/trace.go:172","msg":"trace[2113710722] range","detail":"{range_begin:/registry/validatingwebhookconfigurations; range_end:; response_count:0; response_revision:834; }","duration":"254.706835ms","start":"2026-04-13T15:05:30.745122Z","end":"2026-04-13T15:05:30.999829Z","steps":["trace[2113710722] 'agreement among raft nodes before linearized reading' (duration: 253.90208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:31.000066Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"662.776014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T15:05:31.000145Z","caller":"traceutil/trace.go:172","msg":"trace[549868391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:834; }","duration":"662.816565ms","start":"2026-04-13T15:05:30.337280Z","end":"2026-04-13T15:05:31.000096Z","steps":["trace[549868391] 'agreement among raft nodes before linearized reading' (duration: 661.829447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:31.000167Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:30.337255Z","time spent":"662.905817ms","remote":"127.0.0.1:57216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-13T15:05:34.609370Z","caller":"traceutil/trace.go:172","msg":"trace[2089206413] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"182.922881ms","start":"2026-04-13T15:05:34.426426Z","end":"2026-04-13T15:05:34.609349Z","steps":["trace[2089206413] 'process raft request' (duration: 182.25458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:34.910292Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"166.672541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:34.910400Z","caller":"traceutil/trace.go:172","msg":"trace[1205222744] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:876; }","duration":"166.792503ms","start":"2026-04-13T15:05:34.743582Z","end":"2026-04-13T15:05:34.910375Z","steps":["trace[1205222744] 'range keys from in-memory index tree' (duration: 166.542649ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:34.910814Z","caller":"traceutil/trace.go:172","msg":"trace[670682778] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"163.016534ms","start":"2026-04-13T15:05:34.747764Z","end":"2026-04-13T15:05:34.910780Z","steps":["trace[670682778] 'process raft request' (duration: 114.941382ms)","trace[670682778] 'compare' (duration: 47.453892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:35.251738Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"229.693687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T15:05:35.252086Z","caller":"traceutil/trace.go:172","msg":"trace[1432985268] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"332.514581ms","start":"2026-04-13T15:05:34.919534Z","end":"2026-04-13T15:05:35.252049Z","steps":["trace[1432985268] 'process raft request' (duration: 102.434677ms)","trace[1432985268] 'compare' (duration: 229.377362ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:35.252272Z","caller":"traceutil/trace.go:172","msg":"trace[476853619] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"182.615948ms","start":"2026-04-13T15:05:35.069632Z","end":"2026-04-13T15:05:35.252248Z","steps":["trace[476853619] 'read index received' (duration: 82.482251ms)","trace[476853619] 'applied index is now lower than readState.Index' (duration: 100.131687ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:35.252543Z","caller":"traceutil/trace.go:172","msg":"trace[1883493715] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"237.932975ms","start":"2026-04-13T15:05:35.014550Z","end":"2026-04-13T15:05:35.252483Z","steps":["trace[1883493715] 'process raft request' (duration: 237.526668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:35.252399Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-13T15:05:34.919508Z","time spent":"332.644633ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2782,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T15:05:35.252814Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"117.541936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:35.252719Z","caller":"traceutil/trace.go:172","msg":"trace[603494686] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"237.999106ms","start":"2026-04-13T15:05:35.014701Z","end":"2026-04-13T15:05:35.252700Z","steps":["trace[603494686] 'process raft request' (duration: 237.67637ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:35.252944Z","caller":"traceutil/trace.go:172","msg":"trace[1481847270] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:880; }","duration":"117.593007ms","start":"2026-04-13T15:05:35.135260Z","end":"2026-04-13T15:05:35.252853Z","steps":["trace[1481847270] 'agreement among raft nodes before linearized reading' (duration: 117.516336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:35.252726Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"183.082866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T15:05:35.253053Z","caller":"traceutil/trace.go:172","msg":"trace[1316520707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"183.410891ms","start":"2026-04-13T15:05:35.069617Z","end":"2026-04-13T15:05:35.253028Z","steps":["trace[1316520707] 'agreement among raft nodes before linearized reading' (duration: 182.863192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:35.253107Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"178.312141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T15:05:35.253300Z","caller":"traceutil/trace.go:172","msg":"trace[1623301020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"178.506094ms","start":"2026-04-13T15:05:35.074778Z","end":"2026-04-13T15:05:35.253284Z","steps":["trace[1623301020] 'agreement among raft nodes before linearized reading' (duration: 177.942324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:35.253634Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"178.182218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T15:05:35.253802Z","caller":"traceutil/trace.go:172","msg":"trace[1744754383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"178.520115ms","start":"2026-04-13T15:05:35.075265Z","end":"2026-04-13T15:05:35.253785Z","steps":["trace[1744754383] 'agreement among raft nodes before linearized reading' (duration: 177.927643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:35.484677Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"143.700424ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:35.484756Z","caller":"traceutil/trace.go:172","msg":"trace[1864509779] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:881; }","duration":"143.789325ms","start":"2026-04-13T15:05:35.340947Z","end":"2026-04-13T15:05:35.484737Z","steps":["trace[1864509779] 'agreement among raft nodes before linearized reading' (duration: 98.492617ms)","trace[1864509779] 'range keys from in-memory index tree' (duration: 45.189576ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:35.484824Z","caller":"traceutil/trace.go:172","msg":"trace[706473374] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"225.014454ms","start":"2026-04-13T15:05:35.259798Z","end":"2026-04-13T15:05:35.484813Z","steps":["trace[706473374] 'process raft request' (duration: 224.979344ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:35.485189Z","caller":"traceutil/trace.go:172","msg":"trace[1692420812] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"225.964352ms","start":"2026-04-13T15:05:35.259167Z","end":"2026-04-13T15:05:35.485132Z","steps":["trace[1692420812] 'process raft request' (duration: 180.319228ms)","trace[1692420812] 'compare' (duration: 45.196296ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:51.559339Z","caller":"traceutil/trace.go:172","msg":"trace[486236878] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"174.39817ms","start":"2026-04-13T15:05:51.384916Z","end":"2026-04-13T15:05:51.559315Z","steps":["trace[486236878] 'process raft request' (duration: 81.024413ms)","trace[486236878] 'compare' (duration: 93.052251ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:52.470493Z","caller":"traceutil/trace.go:172","msg":"trace[828701679] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:995; }","duration":"128.941912ms","start":"2026-04-13T15:05:52.341530Z","end":"2026-04-13T15:05:52.470472Z","steps":["trace[828701679] 'read index received' (duration: 128.935432ms)","trace[828701679] 'applied index is now lower than readState.Index' (duration: 5.48µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:52.470610Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"129.100675ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:05:52.470934Z","caller":"traceutil/trace.go:172","msg":"trace[2042013069] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"156.175139ms","start":"2026-04-13T15:05:52.314751Z","end":"2026-04-13T15:05:52.470926Z","steps":["trace[2042013069] 'process raft request' (duration: 156.064356ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:52.470632Z","caller":"traceutil/trace.go:172","msg":"trace[2062349609] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:952; }","duration":"129.141506ms","start":"2026-04-13T15:05:52.341484Z","end":"2026-04-13T15:05:52.470626Z","steps":["trace[2062349609] 'agreement among raft nodes before linearized reading' (duration: 129.064215ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:55.767536Z","caller":"traceutil/trace.go:172","msg":"trace[433307724] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1006; }","duration":"108.645917ms","start":"2026-04-13T15:05:55.658859Z","end":"2026-04-13T15:05:55.767505Z","steps":["trace[433307724] 'read index received' (duration: 108.633666ms)","trace[433307724] 'applied index is now lower than readState.Index' (duration: 10.251µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T15:05:55.773278Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"114.394829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T15:05:55.773355Z","caller":"traceutil/trace.go:172","msg":"trace[1303000938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"114.488381ms","start":"2026-04-13T15:05:55.658850Z","end":"2026-04-13T15:05:55.773339Z","steps":["trace[1303000938] 'agreement among raft nodes before linearized reading' (duration: 108.765428ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:55.773360Z","caller":"traceutil/trace.go:172","msg":"trace[474025041] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"116.895599ms","start":"2026-04-13T15:05:55.656443Z","end":"2026-04-13T15:05:55.773339Z","steps":["trace[474025041] 'process raft request' (duration: 111.088045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:05:58.136937Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.888303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" limit:1 ","response":"range_response_count:1 size:1149"} {"level":"info","ts":"2026-04-13T15:05:58.137052Z","caller":"traceutil/trace.go:172","msg":"trace[2053631691] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"271.947108ms","start":"2026-04-13T15:05:57.865065Z","end":"2026-04-13T15:05:58.137012Z","steps":["trace[2053631691] 'process raft request' (duration: 245.662899ms)","trace[2053631691] 'compare' (duration: 25.807251ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:58.137079Z","caller":"traceutil/trace.go:172","msg":"trace[221172284] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:971; }","duration":"105.054225ms","start":"2026-04-13T15:05:58.031993Z","end":"2026-04-13T15:05:58.137048Z","steps":["trace[221172284] 'agreement among raft nodes before linearized reading' (duration: 78.694005ms)","trace[221172284] 'range keys from in-memory index tree' (duration: 26.004264ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:05:59.533531Z","caller":"traceutil/trace.go:172","msg":"trace[185190651] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"157.92582ms","start":"2026-04-13T15:05:59.375587Z","end":"2026-04-13T15:05:59.533513Z","steps":["trace[185190651] 'process raft request' (duration: 157.791748ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:05:59.604243Z","caller":"traceutil/trace.go:172","msg":"trace[1921751156] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"226.289455ms","start":"2026-04-13T15:05:59.377894Z","end":"2026-04-13T15:05:59.604183Z","steps":["trace[1921751156] 'process raft request' (duration: 226.139943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:06:06.365683Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"127.815204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:06:06.365754Z","caller":"traceutil/trace.go:172","msg":"trace[465208467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"127.903436ms","start":"2026-04-13T15:06:06.237838Z","end":"2026-04-13T15:06:06.365741Z","steps":["trace[465208467] 'range keys from in-memory index tree' (duration: 127.706482ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:06:06.667242Z","caller":"traceutil/trace.go:172","msg":"trace[565841504] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"116.652411ms","start":"2026-04-13T15:06:06.550527Z","end":"2026-04-13T15:06:06.667180Z","steps":["trace[565841504] 'process raft request' (duration: 116.504539ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:06:38.398803Z","caller":"traceutil/trace.go:172","msg":"trace[609386367] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1158; }","duration":"164.160636ms","start":"2026-04-13T15:06:38.234621Z","end":"2026-04-13T15:06:38.398781Z","steps":["trace[609386367] 'read index received' (duration: 164.154586ms)","trace[609386367] 'applied index is now lower than readState.Index' (duration: 4.88µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:06:38.398910Z","caller":"traceutil/trace.go:172","msg":"trace[2080346319] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"219.444156ms","start":"2026-04-13T15:06:38.179457Z","end":"2026-04-13T15:06:38.398901Z","steps":["trace[2080346319] 'process raft request' (duration: 219.351285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:06:38.399010Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"164.372159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:06:38.399048Z","caller":"traceutil/trace.go:172","msg":"trace[1411884776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"164.420619ms","start":"2026-04-13T15:06:38.234616Z","end":"2026-04-13T15:06:38.399036Z","steps":["trace[1411884776] 'agreement among raft nodes before linearized reading' (duration: 164.335798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:07:22.202831Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"225.529861ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T15:07:22.203657Z","caller":"traceutil/trace.go:172","msg":"trace[174474239] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1310; }","duration":"153.548971ms","start":"2026-04-13T15:07:22.050062Z","end":"2026-04-13T15:07:22.203611Z","steps":["trace[174474239] 'read index received' (duration: 153.521661ms)","trace[174474239] 'applied index is now lower than readState.Index' (duration: 25.06µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T15:07:22.203817Z","caller":"traceutil/trace.go:172","msg":"trace[481794576] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"229.406562ms","start":"2026-04-13T15:07:21.974389Z","end":"2026-04-13T15:07:22.203796Z","steps":["trace[481794576] 'process raft request' (duration: 229.260679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:07:22.203940Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"153.856196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/kube-root-ca.crt\" limit:1 ","response":"range_response_count:1 size:1764"} {"level":"info","ts":"2026-04-13T15:07:22.204003Z","caller":"traceutil/trace.go:172","msg":"trace[482716393] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1250; }","duration":"153.929657ms","start":"2026-04-13T15:07:22.050057Z","end":"2026-04-13T15:07:22.203987Z","steps":["trace[482716393] 'agreement among raft nodes before linearized reading' (duration: 153.717884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:07:22.204058Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"147.049469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T15:07:22.204037Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"139.884757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T15:07:22.204106Z","caller":"traceutil/trace.go:172","msg":"trace[2075651556] range","detail":"{range_begin:/registry/cronjobs; range_end:; response_count:0; response_revision:1250; }","duration":"147.096729ms","start":"2026-04-13T15:07:22.056997Z","end":"2026-04-13T15:07:22.204094Z","steps":["trace[2075651556] 'agreement among raft nodes before linearized reading' (duration: 147.035069ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T15:07:22.204118Z","caller":"traceutil/trace.go:172","msg":"trace[1038486955] range","detail":"{range_begin:/registry/cronjobs; range_end:; response_count:0; response_revision:1250; }","duration":"139.979008ms","start":"2026-04-13T15:07:22.064124Z","end":"2026-04-13T15:07:22.204103Z","steps":["trace[1038486955] 'agreement among raft nodes before linearized reading' (duration: 139.826597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T15:07:22.204291Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"149.984527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" limit:1 ","response":"range_response_count:1 size:1550"} {"level":"info","ts":"2026-04-13T15:07:22.204390Z","caller":"traceutil/trace.go:172","msg":"trace[1846107983] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:1; response_revision:1250; }","duration":"150.079898ms","start":"2026-04-13T15:07:22.054293Z","end":"2026-04-13T15:07:22.204373Z","steps":["trace[1846107983] 'agreement among raft nodes before linearized reading' (duration: 149.507261ms)"],"step_count":1}