{"level":"warn","ts":"2026-04-23T04:43:28.032577Z","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-23T04:43:28.032867Z","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-23T04:43:28.032885Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.204.45.4:2380","--initial-cluster=controller=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-23T04:43:28.033091Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/var/lib/etcd","dir-type":"empty"} {"level":"warn","ts":"2026-04-23T04:43:28.033118Z","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-23T04:43:28.033212Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-23T04:43:28.033248Z","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-23T04:43:28.036515Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-23T04:43:28.036757Z","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.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.204.45.4: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-23T04:43:28.037900Z","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: 0xc000200100}"} {"level":"info","ts":"2026-04-23T04:43:28.042013Z","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-23T04:43:28.042096Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.385157ms"} {"level":"info","ts":"2026-04-23T04:43:28.042164Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-04-23T04:43:28.042191Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-04-23T04:43:28.042251Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-04-23T04:43:28.047877Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-04-23T04:43:28.048069Z","caller":"etcdserver/bootstrap.go:499","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-23T04:43:28.048127Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-04-23T04:43:28.048304Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T04:43:28.048382Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-23T04:43:28.048403Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T04:43:28.048424Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-23T04:43:28.048489Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-23T04:43:28.053227Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T04:43:28.056290Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T04:43:28.059844Z","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-23T04:43:28.061538Z","caller":"etcdserver/server.go:598","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.6.4","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T04:43:28.061645Z","caller":"etcdserver/server.go:483","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T04:43:28.061829Z","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-23T04:43:28.061962Z","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-23T04:43:28.062092Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-23T04:43:28.062282Z","caller":"membership/cluster.go:424","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-04-23T04:43:28.062487Z","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-23T04:43:28.062903Z","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-23T04:43:28.063283Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-23T04:43:28.063390Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-23T04:43:28.063392Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T04:43:28.063439Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T04:43:28.249006Z","logger":"raft","caller":"v3@v3.6.0/raft.go:988","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T04:43:28.249088Z","logger":"raft","caller":"v3@v3.6.0/raft.go:930","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T04:43:28.249182Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-23T04:43:28.249205Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"66f7b57d56ad567d has received 1 MsgPreVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-04-23T04:43:28.249236Z","logger":"raft","caller":"v3@v3.6.0/raft.go:912","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-23T04:43:28.249989Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-23T04:43:28.250110Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"66f7b57d56ad567d has received 1 MsgVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-04-23T04:43:28.250195Z","logger":"raft","caller":"v3@v3.6.0/raft.go:970","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-23T04:43:28.250225Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-23T04:43:28.251300Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:controller ClientURLs:[https://199.204.45.4:2379]}","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T04:43:28.251434Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T04:43:28.251586Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T04:43:28.251782Z","caller":"etcdserver/server.go:2404","msg":"setting up initial cluster version using v3 API","cluster-version":"3.6"} {"level":"info","ts":"2026-04-23T04:43:28.251971Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T04:43:28.252004Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T04:43:28.253038Z","caller":"membership/cluster.go:682","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.6"} {"level":"info","ts":"2026-04-23T04:43:28.253292Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-04-23T04:43:28.253311Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-04-23T04:43:28.253484Z","caller":"etcdserver/server.go:2424","msg":"cluster version is updated","cluster-version":"3.6"} {"level":"info","ts":"2026-04-23T04:43:28.253651Z","caller":"version/monitor.go:116","msg":"cluster version differs from storage version.","cluster-version":"3.6.0","storage-version":"3.5.0"} {"level":"warn","ts":"2026-04-23T04:43:28.253730Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-04-23T04:43:28.253824Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-04-23T04:43:28.253982Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-04-23T04:43:28.262190Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T04:43:28.273175Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"warn","ts":"2026-04-23T04:43:28.630894Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60658","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.630994Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60660","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.651339Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60672","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.663899Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60684","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.677944Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60708","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.703194Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60742","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.712342Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60746","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.719829Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60766","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.730011Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60788","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.737610Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60804","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.745089Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60826","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.754980Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60850","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.767856Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60856","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.780671Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60872","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.788126Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60894","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.796225Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60920","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.805085Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60948","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.814852Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60972","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.826056Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:60986","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.834617Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32784","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.843923Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32796","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.853751Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32814","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.904662Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32834","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.910096Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32840","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.919707Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32868","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.930245Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32884","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.941258Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32906","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.954860Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32914","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.960782Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32936","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.972015Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32962","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.981070Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:32998","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:28.994167Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33014","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.003106Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33034","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.014942Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33048","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.026468Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33058","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.034711Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33084","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.056315Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33104","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.068496Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33116","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.089352Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33168","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.096403Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33186","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.104375Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33200","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.113828Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33222","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.121502Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33254","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.129953Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33270","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.141082Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33286","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.156520Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33304","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.167452Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33316","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.176784Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33338","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.185715Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33348","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.193703Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33370","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.205391Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33382","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.223221Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33436","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.231415Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33448","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.240295Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33450","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.250254Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33466","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.259068Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33488","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.273983Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33516","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.283802Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33522","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.295579Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33540","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:43:29.359704Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:33554","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-23T04:43:31.757668Z","caller":"traceutil/trace.go:172","msg":"trace[616135190] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"174.306561ms","start":"2026-04-23T04:43:31.583331Z","end":"2026-04-23T04:43:31.757638Z","steps":["trace[616135190] 'process raft request' (duration: 90.843945ms)","trace[616135190] 'compare' (duration: 83.319842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:33.965364Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"207.160243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T04:43:33.965655Z","caller":"traceutil/trace.go:172","msg":"trace[990121179] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:312; }","duration":"207.577745ms","start":"2026-04-23T04:43:33.758046Z","end":"2026-04-23T04:43:33.965624Z","steps":["trace[990121179] 'range keys from in-memory index tree' (duration: 205.981776ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.118494Z","caller":"traceutil/trace.go:172","msg":"trace[178729635] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"232.147908ms","start":"2026-04-23T04:43:33.886329Z","end":"2026-04-23T04:43:34.118477Z","steps":["trace[178729635] 'process raft request' (duration: 231.998132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:34.118606Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"172.934025ms","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-23T04:43:34.118668Z","caller":"traceutil/trace.go:172","msg":"trace[763813277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"173.016367ms","start":"2026-04-23T04:43:33.945636Z","end":"2026-04-23T04:43:34.118653Z","steps":["trace[763813277] 'agreement among raft nodes before linearized reading' (duration: 172.79272ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.119197Z","caller":"traceutil/trace.go:172","msg":"trace[639426247] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:320; }","duration":"172.573312ms","start":"2026-04-23T04:43:33.945650Z","end":"2026-04-23T04:43:34.118224Z","steps":["trace[639426247] 'read index received' (duration: 172.560342ms)","trace[639426247] 'applied index is now lower than readState.Index' (duration: 11.29µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:34.151288Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"119.836997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:43:34.151378Z","caller":"traceutil/trace.go:172","msg":"trace[2047246063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:313; }","duration":"119.937311ms","start":"2026-04-23T04:43:34.031423Z","end":"2026-04-23T04:43:34.151360Z","steps":["trace[2047246063] 'agreement among raft nodes before linearized reading' (duration: 119.781036ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.152198Z","caller":"traceutil/trace.go:172","msg":"trace[926805483] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"182.723489ms","start":"2026-04-23T04:43:33.969405Z","end":"2026-04-23T04:43:34.152128Z","steps":["trace[926805483] 'process raft request' (duration: 182.537922ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.431018Z","caller":"traceutil/trace.go:172","msg":"trace[2043460027] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:323; }","duration":"271.915688ms","start":"2026-04-23T04:43:34.159065Z","end":"2026-04-23T04:43:34.430981Z","steps":["trace[2043460027] 'read index received' (duration: 271.904388ms)","trace[2043460027] 'applied index is now lower than readState.Index' (duration: 8µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:34.431484Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"272.392223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:43:34.431543Z","caller":"traceutil/trace.go:172","msg":"trace[950876936] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:315; }","duration":"272.468305ms","start":"2026-04-23T04:43:34.159060Z","end":"2026-04-23T04:43:34.431529Z","steps":["trace[950876936] 'agreement among raft nodes before linearized reading' (duration: 272.346972ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.432051Z","caller":"traceutil/trace.go:172","msg":"trace[912362315] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"273.045333ms","start":"2026-04-23T04:43:34.158992Z","end":"2026-04-23T04:43:34.432037Z","steps":["trace[912362315] 'process raft request' (duration: 272.98311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:34.436752Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"276.359233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-controller\" limit:1 ","response":"range_response_count:1 size:5690"} {"level":"info","ts":"2026-04-23T04:43:34.436798Z","caller":"traceutil/trace.go:172","msg":"trace[1986929227] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller; range_end:; response_count:1; response_revision:315; }","duration":"276.407644ms","start":"2026-04-23T04:43:34.160380Z","end":"2026-04-23T04:43:34.436787Z","steps":["trace[1986929227] 'agreement among raft nodes before linearized reading' (duration: 276.26075ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.436993Z","caller":"traceutil/trace.go:172","msg":"trace[378365130] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"275.89847ms","start":"2026-04-23T04:43:34.161085Z","end":"2026-04-23T04:43:34.436983Z","steps":["trace[378365130] 'process raft request' (duration: 275.681422ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.437057Z","caller":"traceutil/trace.go:172","msg":"trace[1134623791] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"277.984354ms","start":"2026-04-23T04:43:34.159067Z","end":"2026-04-23T04:43:34.437052Z","steps":["trace[1134623791] 'process raft request' (duration: 277.604452ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.437485Z","caller":"traceutil/trace.go:172","msg":"trace[219994601] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"276.956251ms","start":"2026-04-23T04:43:34.160521Z","end":"2026-04-23T04:43:34.437477Z","steps":["trace[219994601] 'process raft request' (duration: 276.217159ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:34.437103Z","caller":"traceutil/trace.go:172","msg":"trace[568993653] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"275.5769ms","start":"2026-04-23T04:43:34.161510Z","end":"2026-04-23T04:43:34.437087Z","steps":["trace[568993653] 'process raft request' (duration: 275.28398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:37.782792Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742384941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T04:43:37.828060Z","caller":"traceutil/trace.go:172","msg":"trace[445585650] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:361; }","duration":"545.63803ms","start":"2026-04-23T04:43:37.282385Z","end":"2026-04-23T04:43:37.828023Z","steps":["trace[445585650] 'read index received' (duration: 545.628819ms)","trace[445585650] 'applied index is now lower than readState.Index' (duration: 7.581µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:37.828372Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"545.97043ms","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-23T04:43:37.828421Z","caller":"traceutil/trace.go:172","msg":"trace[1851008589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:348; }","duration":"546.030382ms","start":"2026-04-23T04:43:37.282367Z","end":"2026-04-23T04:43:37.828397Z","steps":["trace[1851008589] 'agreement among raft nodes before linearized reading' (duration: 545.813134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:37.828458Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:37.282338Z","time spent":"546.113334ms","remote":"127.0.0.1:32912","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":"info","ts":"2026-04-23T04:43:37.828476Z","caller":"traceutil/trace.go:172","msg":"trace[973799603] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"568.159143ms","start":"2026-04-23T04:43:37.260286Z","end":"2026-04-23T04:43:37.828445Z","steps":["trace[973799603] 'process raft request' (duration: 567.980738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:37.829190Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:37.260255Z","time spent":"568.318708ms","remote":"127.0.0.1:32798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T04:43:38.241732Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"182.990862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner\" limit:1 ","response":"range_response_count:1 size:238"} {"level":"info","ts":"2026-04-23T04:43:38.241918Z","caller":"traceutil/trace.go:172","msg":"trace[63437676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/legacy-service-account-token-cleaner; range_end:; response_count:1; response_revision:354; }","duration":"183.161367ms","start":"2026-04-23T04:43:38.058698Z","end":"2026-04-23T04:43:38.241859Z","steps":["trace[63437676] 'range keys from in-memory index tree' (duration: 182.734055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:38.241749Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"132.602829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" limit:1 ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-23T04:43:38.242485Z","caller":"traceutil/trace.go:172","msg":"trace[355341654] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:354; }","duration":"133.353102ms","start":"2026-04-23T04:43:38.109093Z","end":"2026-04-23T04:43:38.242446Z","steps":["trace[355341654] 'range keys from in-memory index tree' (duration: 132.273179ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:40.104905Z","caller":"traceutil/trace.go:172","msg":"trace[1688595818] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"189.120939ms","start":"2026-04-23T04:43:39.915742Z","end":"2026-04-23T04:43:40.104863Z","steps":["trace[1688595818] 'process raft request' (duration: 188.917983ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:40.308362Z","caller":"traceutil/trace.go:172","msg":"trace[808865993] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"122.432923ms","start":"2026-04-23T04:43:40.185904Z","end":"2026-04-23T04:43:40.308337Z","steps":["trace[808865993] 'process raft request' (duration: 77.548852ms)","trace[808865993] 'compare' (duration: 44.775168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:40.756330Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"292.305556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:43:40.756623Z","caller":"traceutil/trace.go:172","msg":"trace[691499538] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"278.349121ms","start":"2026-04-23T04:43:40.478251Z","end":"2026-04-23T04:43:40.756600Z","steps":["trace[691499538] 'process raft request' (duration: 278.256188ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:40.756771Z","caller":"traceutil/trace.go:172","msg":"trace[1060668411] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"360.052997ms","start":"2026-04-23T04:43:40.396674Z","end":"2026-04-23T04:43:40.756727Z","steps":["trace[1060668411] 'process raft request' (duration: 66.846341ms)","trace[1060668411] 'compare' (duration: 292.0801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:40.756967Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:40.396639Z","time spent":"360.222762ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:46.069131Z","caller":"traceutil/trace.go:172","msg":"trace[707199777] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:447; }","duration":"356.02549ms","start":"2026-04-23T04:43:45.713067Z","end":"2026-04-23T04:43:46.069093Z","steps":["trace[707199777] 'read index received' (duration: 356.002459ms)","trace[707199777] 'applied index is now lower than readState.Index' (duration: 21.281µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:46.069324Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"356.248648ms","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-23T04:43:46.069378Z","caller":"traceutil/trace.go:172","msg":"trace[934600282] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"390.710261ms","start":"2026-04-23T04:43:45.678644Z","end":"2026-04-23T04:43:46.069354Z","steps":["trace[934600282] 'process raft request' (duration: 390.448943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:46.069454Z","caller":"traceutil/trace.go:172","msg":"trace[540507647] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:432; }","duration":"356.402933ms","start":"2026-04-23T04:43:45.713024Z","end":"2026-04-23T04:43:46.069427Z","steps":["trace[540507647] 'agreement among raft nodes before linearized reading' (duration: 356.180886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:46.069540Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"346.393273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/controller\" limit:1 ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-23T04:43:46.069586Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:45.678595Z","time spent":"390.855466ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7335,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:46.069640Z","caller":"traceutil/trace.go:172","msg":"trace[245472332] range","detail":"{range_begin:/registry/minions/controller; range_end:; response_count:1; response_revision:433; }","duration":"346.467336ms","start":"2026-04-23T04:43:45.723124Z","end":"2026-04-23T04:43:46.069591Z","steps":["trace[245472332] 'agreement among raft nodes before linearized reading' (duration: 346.217338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:46.069697Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:45.723090Z","time spent":"346.59418ms","remote":"127.0.0.1:60968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":32,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/controller\" limit:1 "} {"level":"info","ts":"2026-04-23T04:43:46.404963Z","caller":"traceutil/trace.go:172","msg":"trace[62363371] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:448; }","duration":"275.366576ms","start":"2026-04-23T04:43:46.129558Z","end":"2026-04-23T04:43:46.404925Z","steps":["trace[62363371] 'read index received' (duration: 275.350485ms)","trace[62363371] 'applied index is now lower than readState.Index' (duration: 13.481µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:46.691012Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"561.421087ms","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-23T04:43:46.691121Z","caller":"traceutil/trace.go:172","msg":"trace[1075718024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:433; }","duration":"561.549381ms","start":"2026-04-23T04:43:46.129547Z","end":"2026-04-23T04:43:46.691096Z","steps":["trace[1075718024] 'agreement among raft nodes before linearized reading' (duration: 275.507301ms)","trace[1075718024] 'range keys from in-memory index tree' (duration: 285.749822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:46.691171Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"286.015939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-23T04:43:46.691204Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.129523Z","time spent":"561.670855ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-04-23T04:43:46.691249Z","caller":"traceutil/trace.go:172","msg":"trace[1941823362] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"599.656972ms","start":"2026-04-23T04:43:46.091579Z","end":"2026-04-23T04:43:46.691236Z","steps":["trace[1941823362] 'process raft request' (duration: 313.481958ms)","trace[1941823362] 'compare' (duration: 285.895346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:46.691308Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.091552Z","time spent":"599.730605ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:46.740483Z","caller":"traceutil/trace.go:172","msg":"trace[1328536025] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:449; }","duration":"335.392965ms","start":"2026-04-23T04:43:46.405055Z","end":"2026-04-23T04:43:46.740447Z","steps":["trace[1328536025] 'read index received' (duration: 335.380243ms)","trace[1328536025] 'applied index is now lower than readState.Index' (duration: 10.862µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:43:46.740727Z","caller":"traceutil/trace.go:172","msg":"trace[924296798] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"649.08814ms","start":"2026-04-23T04:43:46.091613Z","end":"2026-04-23T04:43:46.740701Z","steps":["trace[924296798] 'process raft request' (duration: 648.931625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:46.740816Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"379.734861ms","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-23T04:43:46.740882Z","caller":"traceutil/trace.go:172","msg":"trace[1270479403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"379.822154ms","start":"2026-04-23T04:43:46.361045Z","end":"2026-04-23T04:43:46.740867Z","steps":["trace[1270479403] 'agreement among raft nodes before linearized reading' (duration: 379.547675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:46.740885Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.091586Z","time spent":"649.229243ms","remote":"127.0.0.1:60968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4009,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:43:46.740923Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.361020Z","time spent":"379.894416ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:43:47.406716Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"694.802482ms","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-23T04:43:47.406852Z","caller":"traceutil/trace.go:172","msg":"trace[1221052812] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:435; }","duration":"694.937396ms","start":"2026-04-23T04:43:46.711872Z","end":"2026-04-23T04:43:47.406809Z","steps":["trace[1221052812] 'agreement among raft nodes before linearized reading' (duration: 102.97613ms)","trace[1221052812] 'range keys from in-memory index tree' (duration: 591.786381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:47.406892Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"591.873254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:43:47.407208Z","caller":"traceutil/trace.go:172","msg":"trace[1832472145] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"662.013367ms","start":"2026-04-23T04:43:46.745172Z","end":"2026-04-23T04:43:47.407185Z","steps":["trace[1832472145] 'process raft request' (duration: 661.854702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:47.407300Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.745123Z","time spent":"662.140971ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:47.407342Z","caller":"traceutil/trace.go:172","msg":"trace[1067564573] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"711.873228ms","start":"2026-04-23T04:43:46.695457Z","end":"2026-04-23T04:43:47.407330Z","steps":["trace[1067564573] 'process raft request' (duration: 119.473786ms)","trace[1067564573] 'compare' (duration: 591.724649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:47.407436Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.695427Z","time spent":"711.94743ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:47.408560Z","caller":"traceutil/trace.go:172","msg":"trace[2084271914] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"396.427412ms","start":"2026-04-23T04:43:47.012052Z","end":"2026-04-23T04:43:47.408479Z","steps":["trace[2084271914] 'process raft request' (duration: 396.381851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:47.408681Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:47.012017Z","time spent":"396.625059ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:47.408950Z","caller":"traceutil/trace.go:172","msg":"trace[590037172] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"588.757942ms","start":"2026-04-23T04:43:46.820178Z","end":"2026-04-23T04:43:47.408936Z","steps":["trace[590037172] 'process raft request' (duration: 588.075959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:47.409016Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:46.816437Z","time spent":"592.544366ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:43:47.888923Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"387.469664ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T04:43:47.889054Z","caller":"traceutil/trace.go:172","msg":"trace[2109645060] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"351.94627ms","start":"2026-04-23T04:43:47.537088Z","end":"2026-04-23T04:43:47.889034Z","steps":["trace[2109645060] 'read index received' (duration: 79.963µs)","trace[2109645060] 'applied index is now lower than readState.Index' (duration: 351.864987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:47.889337Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.965093ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:43:47.889427Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"352.324384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/controller\" limit:1 ","response":"range_response_count:1 size:4023"} {"level":"info","ts":"2026-04-23T04:43:47.889447Z","caller":"traceutil/trace.go:172","msg":"trace[1592531092] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:439; }","duration":"177.100818ms","start":"2026-04-23T04:43:47.712324Z","end":"2026-04-23T04:43:47.889425Z","steps":["trace[1592531092] 'agreement among raft nodes before linearized reading' (duration: 176.905241ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:47.889476Z","caller":"traceutil/trace.go:172","msg":"trace[633697906] range","detail":"{range_begin:/registry/minions/controller; range_end:; response_count:1; response_revision:439; }","duration":"352.377406ms","start":"2026-04-23T04:43:47.537081Z","end":"2026-04-23T04:43:47.889459Z","steps":["trace[633697906] 'agreement among raft nodes before linearized reading' (duration: 352.107706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:47.889512Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:47.537053Z","time spent":"352.450337ms","remote":"127.0.0.1:60968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":32,"response count":1,"response size":4046,"request content":"key:\"/registry/minions/controller\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:43:49.912180Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:43:50.209557Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.323934567s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T04:43:50.209922Z","caller":"traceutil/trace.go:172","msg":"trace[1870659060] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:455; }","duration":"798.016486ms","start":"2026-04-23T04:43:49.411854Z","end":"2026-04-23T04:43:50.209870Z","steps":["trace[1870659060] 'read index received' (duration: 798.002746ms)","trace[1870659060] 'applied index is now lower than readState.Index' (duration: 11.65µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:43:50.210279Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"798.392439ms","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-23T04:43:50.210336Z","caller":"traceutil/trace.go:172","msg":"trace[2038948098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:439; }","duration":"798.473342ms","start":"2026-04-23T04:43:49.411848Z","end":"2026-04-23T04:43:50.210321Z","steps":["trace[2038948098] 'agreement among raft nodes before linearized reading' (duration: 798.184462ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:50.210330Z","caller":"traceutil/trace.go:172","msg":"trace[565519767] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"1.324822278s","start":"2026-04-23T04:43:48.885479Z","end":"2026-04-23T04:43:50.210301Z","steps":["trace[565519767] 'process raft request' (duration: 1.324485156s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:50.210385Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:49.411822Z","time spent":"798.550895ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:43:50.210518Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:48.885452Z","time spent":"1.324975043s","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:43:50.213794Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"183.582268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:43:50.213881Z","caller":"traceutil/trace.go:172","msg":"trace[633021534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"183.684102ms","start":"2026-04-23T04:43:50.030178Z","end":"2026-04-23T04:43:50.213862Z","steps":["trace[633021534] 'agreement among raft nodes before linearized reading' (duration: 183.499906ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:50.213874Z","caller":"traceutil/trace.go:172","msg":"trace[818903838] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"800.562963ms","start":"2026-04-23T04:43:49.413292Z","end":"2026-04-23T04:43:50.213855Z","steps":["trace[818903838] 'process raft request' (duration: 800.408157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:50.213934Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"801.963801ms","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":"warn","ts":"2026-04-23T04:43:50.214004Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:49.413278Z","time spent":"800.646256ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:50.214067Z","caller":"traceutil/trace.go:172","msg":"trace[1770393559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:440; }","duration":"802.114865ms","start":"2026-04-23T04:43:49.411923Z","end":"2026-04-23T04:43:50.214038Z","steps":["trace[1770393559] 'agreement among raft nodes before linearized reading' (duration: 801.739443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:50.214203Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:49.411910Z","time spent":"802.27418ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-04-23T04:43:50.214311Z","caller":"traceutil/trace.go:172","msg":"trace[1076094930] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"800.250201ms","start":"2026-04-23T04:43:49.414036Z","end":"2026-04-23T04:43:50.214286Z","steps":["trace[1076094930] 'process raft request' (duration: 799.771006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:50.214018Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"500.875759ms","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-23T04:43:50.214459Z","caller":"traceutil/trace.go:172","msg":"trace[1041567775] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:441; }","duration":"501.433198ms","start":"2026-04-23T04:43:49.713000Z","end":"2026-04-23T04:43:50.214434Z","steps":["trace[1041567775] 'agreement among raft nodes before linearized reading' (duration: 500.776866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:50.214470Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:43:49.413997Z","time spent":"800.381417ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:43:53.528096Z","caller":"traceutil/trace.go:172","msg":"trace[515540015] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"105.455039ms","start":"2026-04-23T04:43:53.422608Z","end":"2026-04-23T04:43:53.528063Z","steps":["trace[515540015] 'process raft request' (duration: 105.172318ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:58.408493Z","caller":"traceutil/trace.go:172","msg":"trace[2026359616] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:506; }","duration":"152.427623ms","start":"2026-04-23T04:43:58.256019Z","end":"2026-04-23T04:43:58.408446Z","steps":["trace[2026359616] 'read index received' (duration: 152.415113ms)","trace[2026359616] 'applied index is now lower than readState.Index' (duration: 10.62µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:43:58.408736Z","caller":"traceutil/trace.go:172","msg":"trace[1431130720] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"168.101556ms","start":"2026-04-23T04:43:58.240604Z","end":"2026-04-23T04:43:58.408706Z","steps":["trace[1431130720] 'process raft request' (duration: 167.92705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:58.408826Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"152.772355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T04:43:58.408878Z","caller":"traceutil/trace.go:172","msg":"trace[1623347902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:486; }","duration":"152.851938ms","start":"2026-04-23T04:43:58.256012Z","end":"2026-04-23T04:43:58.408864Z","steps":["trace[1623347902] 'agreement among raft nodes before linearized reading' (duration: 152.61105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:43:58.418768Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"161.511393ms","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-23T04:43:58.418831Z","caller":"traceutil/trace.go:172","msg":"trace[1013900138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"161.589517ms","start":"2026-04-23T04:43:58.257228Z","end":"2026-04-23T04:43:58.418818Z","steps":["trace[1013900138] 'agreement among raft nodes before linearized reading' (duration: 161.391549ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:58.419268Z","caller":"traceutil/trace.go:172","msg":"trace[1338806257] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"177.434285ms","start":"2026-04-23T04:43:58.241824Z","end":"2026-04-23T04:43:58.419259Z","steps":["trace[1338806257] 'process raft request' (duration: 177.29514ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:43:58.524010Z","caller":"traceutil/trace.go:172","msg":"trace[893354068] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"110.199305ms","start":"2026-04-23T04:43:58.413790Z","end":"2026-04-23T04:43:58.523989Z","steps":["trace[893354068] 'process raft request' (duration: 68.479054ms)","trace[893354068] 'compare' (duration: 41.540374ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:43:58.524367Z","caller":"traceutil/trace.go:172","msg":"trace[1696884247] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"101.602282ms","start":"2026-04-23T04:43:58.422730Z","end":"2026-04-23T04:43:58.524332Z","steps":["trace[1696884247] 'process raft request' (duration: 101.221769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:01.213125Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T04:44:01.293500Z","caller":"traceutil/trace.go:172","msg":"trace[1588984001] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:512; }","duration":"580.464684ms","start":"2026-04-23T04:44:00.712985Z","end":"2026-04-23T04:44:01.293450Z","steps":["trace[1588984001] 'read index received' (duration: 580.454194ms)","trace[1588984001] 'applied index is now lower than readState.Index' (duration: 8.57µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:01.293652Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"580.63853ms","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-23T04:44:01.293725Z","caller":"traceutil/trace.go:172","msg":"trace[1459142195] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:492; }","duration":"580.728503ms","start":"2026-04-23T04:44:00.712978Z","end":"2026-04-23T04:44:01.293707Z","steps":["trace[1459142195] 'agreement among raft nodes before linearized reading' (duration: 580.585148ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:01.294252Z","caller":"traceutil/trace.go:172","msg":"trace[760703090] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"737.399816ms","start":"2026-04-23T04:44:00.556822Z","end":"2026-04-23T04:44:01.294222Z","steps":["trace[760703090] 'process raft request' (duration: 736.782243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:01.294500Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:00.556777Z","time spent":"737.580252ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:01.599392Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"567.581777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:01.599493Z","caller":"traceutil/trace.go:172","msg":"trace[1833898263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"567.696892ms","start":"2026-04-23T04:44:01.031771Z","end":"2026-04-23T04:44:01.599467Z","steps":["trace[1833898263] 'agreement among raft nodes before linearized reading' (duration: 328.798456ms)","trace[1833898263] 'range keys from in-memory index tree' (duration: 238.740419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:01.599534Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:01.031746Z","time spent":"567.779445ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:01.599573Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"238.886425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:01.599826Z","caller":"traceutil/trace.go:172","msg":"trace[1633843229] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"220.442215ms","start":"2026-04-23T04:44:01.379365Z","end":"2026-04-23T04:44:01.599807Z","steps":["trace[1633843229] 'process raft request' (duration: 220.360933ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:01.599943Z","caller":"traceutil/trace.go:172","msg":"trace[738288979] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.042882858s","start":"2026-04-23T04:44:00.557022Z","end":"2026-04-23T04:44:01.599905Z","steps":["trace[738288979] 'process raft request' (duration: 803.556057ms)","trace[738288979] 'compare' (duration: 238.667097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:01.600126Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:00.557002Z","time spent":"1.043021383s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:02.088612Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"375.321102ms","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-23T04:44:02.088716Z","caller":"traceutil/trace.go:172","msg":"trace[1762978108] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:495; }","duration":"375.465757ms","start":"2026-04-23T04:44:01.713226Z","end":"2026-04-23T04:44:02.088692Z","steps":["trace[1762978108] 'range keys from in-memory index tree' (duration: 375.25183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:02.088878Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"290.985369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" limit:1 ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-23T04:44:02.089009Z","caller":"traceutil/trace.go:172","msg":"trace[1841520848] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:495; }","duration":"291.109324ms","start":"2026-04-23T04:44:01.797849Z","end":"2026-04-23T04:44:02.088958Z","steps":["trace[1841520848] 'range keys from in-memory index tree' (duration: 290.654097ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:02.717604Z","caller":"traceutil/trace.go:172","msg":"trace[1476455155] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"215.151341ms","start":"2026-04-23T04:44:02.502405Z","end":"2026-04-23T04:44:02.717556Z","steps":["trace[1476455155] 'process raft request' (duration: 191.635486ms)","trace[1476455155] 'compare' (duration: 23.196043ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:03.484062Z","caller":"traceutil/trace.go:172","msg":"trace[737184098] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"180.176652ms","start":"2026-04-23T04:44:03.303856Z","end":"2026-04-23T04:44:03.484033Z","steps":["trace[737184098] 'process raft request' (duration: 180.012526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:03.800987Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"196.709998ms","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-23T04:44:03.801088Z","caller":"traceutil/trace.go:172","msg":"trace[1263734620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"196.839842ms","start":"2026-04-23T04:44:03.604231Z","end":"2026-04-23T04:44:03.801071Z","steps":["trace[1263734620] 'range keys from in-memory index tree' (duration: 196.472959ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:03.801089Z","caller":"traceutil/trace.go:172","msg":"trace[1839455548] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"117.342898ms","start":"2026-04-23T04:44:03.683723Z","end":"2026-04-23T04:44:03.801066Z","steps":["trace[1839455548] 'process raft request' (duration: 116.27008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:04.815042Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"101.820144ms","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-23T04:44:04.815206Z","caller":"traceutil/trace.go:172","msg":"trace[312464342] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:502; }","duration":"102.008031ms","start":"2026-04-23T04:44:04.713173Z","end":"2026-04-23T04:44:04.815181Z","steps":["trace[312464342] 'range keys from in-memory index tree' (duration: 101.740431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:07.534871Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:08.035388Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:08.246553Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.659962181s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T04:44:08.246818Z","caller":"traceutil/trace.go:172","msg":"trace[756415263] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:524; }","duration":"1.213712601s","start":"2026-04-23T04:44:07.033083Z","end":"2026-04-23T04:44:08.246795Z","steps":["trace[756415263] 'read index received' (duration: 1.213704261s)","trace[756415263] 'applied index is now lower than readState.Index' (duration: 6.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.354022Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.640958597s","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-23T04:44:08.354107Z","caller":"traceutil/trace.go:172","msg":"trace[1962300469] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:503; }","duration":"2.641061582s","start":"2026-04-23T04:44:05.713027Z","end":"2026-04-23T04:44:08.354088Z","steps":["trace[1962300469] 'range keys from in-memory index tree' (duration: 2.640882724s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.354601Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.52761149s","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-23T04:44:08.354638Z","caller":"traceutil/trace.go:172","msg":"trace[2100642002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"2.527650721s","start":"2026-04-23T04:44:05.826976Z","end":"2026-04-23T04:44:08.354627Z","steps":["trace[2100642002] 'range keys from in-memory index tree' (duration: 2.527270477s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.354669Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:05.826937Z","time spent":"2.527724374s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:08.355046Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.322825836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:08.355079Z","caller":"traceutil/trace.go:172","msg":"trace[14292042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"3.322859588s","start":"2026-04-23T04:44:05.032211Z","end":"2026-04-23T04:44:08.355070Z","steps":["trace[14292042] 'range keys from in-memory index tree' (duration: 3.322731163s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.355105Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:05.032181Z","time spent":"3.32291835s","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:08.355129Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.77043267s","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-23T04:44:08.355107Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.322015062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:08.355203Z","caller":"traceutil/trace.go:172","msg":"trace[1144821131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"2.770510743s","start":"2026-04-23T04:44:05.584680Z","end":"2026-04-23T04:44:08.355190Z","steps":["trace[1144821131] 'range keys from in-memory index tree' (duration: 2.769634911s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.355237Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:05.584659Z","time spent":"2.770569445s","remote":"127.0.0.1:32912","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":"info","ts":"2026-04-23T04:44:08.355247Z","caller":"traceutil/trace.go:172","msg":"trace[652446680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"1.322153157s","start":"2026-04-23T04:44:07.033065Z","end":"2026-04-23T04:44:08.355218Z","steps":["trace[652446680] 'agreement among raft nodes before linearized reading' (duration: 1.213817936s)","trace[652446680] 'range keys from in-memory index tree' (duration: 108.164544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.355306Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:07.033041Z","time spent":"1.32225062s","remote":"127.0.0.1:60666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:08.355868Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"108.880511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:08.355942Z","caller":"traceutil/trace.go:172","msg":"trace[237357265] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.769469055s","start":"2026-04-23T04:44:06.586456Z","end":"2026-04-23T04:44:08.355925Z","steps":["trace[237357265] 'process raft request' (duration: 1.660458699s)","trace[237357265] 'compare' (duration: 108.655613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.356008Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:06.586423Z","time spent":"1.769549697s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:08.747799Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385295,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T04:44:08.784708Z","caller":"traceutil/trace.go:172","msg":"trace[1999720494] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:525; }","duration":"537.817516ms","start":"2026-04-23T04:44:08.246862Z","end":"2026-04-23T04:44:08.784679Z","steps":["trace[1999720494] 'read index received' (duration: 537.804556ms)","trace[1999720494] 'applied index is now lower than readState.Index' (duration: 11.34µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.924952Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.172332051s","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-23T04:44:08.925030Z","caller":"traceutil/trace.go:172","msg":"trace[452430164] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:504; }","duration":"1.172421215s","start":"2026-04-23T04:44:07.752590Z","end":"2026-04-23T04:44:08.925011Z","steps":["trace[452430164] 'agreement among raft nodes before linearized reading' (duration: 1.032126109s)","trace[452430164] 'range keys from in-memory index tree' (duration: 140.147701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.925069Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:07.752560Z","time spent":"1.172500528s","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":28,"request content":"key:\"/registry/secrets\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:08.925231Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"140.315207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:08.925485Z","caller":"traceutil/trace.go:172","msg":"trace[115824664] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"2.057630296s","start":"2026-04-23T04:44:06.867782Z","end":"2026-04-23T04:44:08.925413Z","steps":["trace[115824664] 'process raft request' (duration: 1.917012217s)","trace[115824664] 'compare' (duration: 140.173172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.925693Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:06.867762Z","time spent":"2.057832873s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:08.925734Z","caller":"traceutil/trace.go:172","msg":"trace[1683016341] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"140.923289ms","start":"2026-04-23T04:44:08.784783Z","end":"2026-04-23T04:44:08.925707Z","steps":["trace[1683016341] 'read index received' (duration: 138.984058ms)","trace[1683016341] 'applied index is now lower than readState.Index' (duration: 1.936741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:08.925810Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"571.592257ms","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-23T04:44:08.925837Z","caller":"traceutil/trace.go:172","msg":"trace[103684087] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:506; }","duration":"571.624138ms","start":"2026-04-23T04:44:08.354204Z","end":"2026-04-23T04:44:08.925828Z","steps":["trace[103684087] 'agreement among raft nodes before linearized reading' (duration: 571.570167ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:08.925959Z","caller":"traceutil/trace.go:172","msg":"trace[1146014618] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"565.663089ms","start":"2026-04-23T04:44:08.360259Z","end":"2026-04-23T04:44:08.925922Z","steps":["trace[1146014618] 'process raft request' (duration: 565.12784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.926062Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:08.360234Z","time spent":"565.775585ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:08.926106Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"561.616181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:08.926190Z","caller":"traceutil/trace.go:172","msg":"trace[664856923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"561.699644ms","start":"2026-04-23T04:44:08.364480Z","end":"2026-04-23T04:44:08.926180Z","steps":["trace[664856923] 'agreement among raft nodes before linearized reading' (duration: 561.59561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.926220Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:08.364470Z","time spent":"561.742055ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T04:44:08.927278Z","caller":"traceutil/trace.go:172","msg":"trace[2010876368] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"566.514431ms","start":"2026-04-23T04:44:08.360749Z","end":"2026-04-23T04:44:08.927263Z","steps":["trace[2010876368] 'process raft request' (duration: 565.098599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.927358Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:08.360739Z","time spent":"566.587493ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:08.927600Z","caller":"traceutil/trace.go:172","msg":"trace[2117902257] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"566.679787ms","start":"2026-04-23T04:44:08.360903Z","end":"2026-04-23T04:44:08.927583Z","steps":["trace[2117902257] 'process raft request' (duration: 566.097496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:08.927687Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:08.360883Z","time spent":"566.763881ms","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":769,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T04:44:11.893704Z","caller":"traceutil/trace.go:172","msg":"trace[363314375] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"134.788201ms","start":"2026-04-23T04:44:11.758901Z","end":"2026-04-23T04:44:11.893689Z","steps":["trace[363314375] 'process raft request' (duration: 134.591833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:13.214199Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:13.715286Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:14.216228Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:14.716851Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:14.735553Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.025389482s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T04:44:14.735762Z","caller":"traceutil/trace.go:172","msg":"trace[1615701028] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:572; }","duration":"2.022418632s","start":"2026-04-23T04:44:12.713310Z","end":"2026-04-23T04:44:14.735729Z","steps":["trace[1615701028] 'read index received' (duration: 2.022407292s)","trace[1615701028] 'applied index is now lower than readState.Index' (duration: 9.8µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:14.735914Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.022580808s","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-23T04:44:14.736010Z","caller":"traceutil/trace.go:172","msg":"trace[969843292] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:548; }","duration":"2.022684342s","start":"2026-04-23T04:44:12.713301Z","end":"2026-04-23T04:44:14.735986Z","steps":["trace[969843292] 'agreement among raft nodes before linearized reading' (duration: 2.022528226s)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:14.736081Z","caller":"traceutil/trace.go:172","msg":"trace[2027368693] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"2.368737035s","start":"2026-04-23T04:44:12.367317Z","end":"2026-04-23T04:44:14.736054Z","steps":["trace[2027368693] 'process raft request' (duration: 2.368555539s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:14.736258Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:12.367290Z","time spent":"2.368884172s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:15.028968Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.000153597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-23T04:44:15.029047Z","caller":"traceutil/trace.go:172","msg":"trace[519094432] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00026229s","start":"2026-04-23T04:44:13.028767Z","end":"2026-04-23T04:44:15.029029Z","steps":["trace[519094432] 'agreement among raft nodes before linearized reading' (duration: 2.000149586s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:15.029101Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:13.028744Z","time spent":"2.000348774s","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/23 04:44:15 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-23T04:44:15.235954Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385386,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:15.736693Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385386,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:16.237262Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385386,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:16.738311Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385386,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:16.759366Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"2.022460678s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T04:44:16.759620Z","caller":"traceutil/trace.go:172","msg":"trace[1620051111] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:573; }","duration":"2.023749537s","start":"2026-04-23T04:44:14.735842Z","end":"2026-04-23T04:44:16.759591Z","steps":["trace[1620051111] 'read index received' (duration: 2.023740867s)","trace[1620051111] 'applied index is now lower than readState.Index' (duration: 7.13µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:16.770429Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.847729746s","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-23T04:44:16.770475Z","caller":"traceutil/trace.go:172","msg":"trace[1847105138] range","detail":"{range_begin:/registry/secrets; range_end:; response_count:0; response_revision:549; }","duration":"2.847796108s","start":"2026-04-23T04:44:13.922667Z","end":"2026-04-23T04:44:16.770464Z","steps":["trace[1847105138] 'agreement among raft nodes before linearized reading' (duration: 2.83697181s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:16.770501Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:13.922639Z","time spent":"2.847855541s","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":28,"request content":"key:\"/registry/secrets\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:16.770770Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.828008739s","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-23T04:44:16.770813Z","caller":"traceutil/trace.go:172","msg":"trace[632686513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"3.828053301s","start":"2026-04-23T04:44:12.942750Z","end":"2026-04-23T04:44:16.770803Z","steps":["trace[632686513] 'agreement among raft nodes before linearized reading' (duration: 3.81691506s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:16.770841Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:12.942733Z","time spent":"3.828100253s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:16.770826Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.828130904s","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-23T04:44:16.771060Z","caller":"traceutil/trace.go:172","msg":"trace[1372456081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"3.828379773s","start":"2026-04-23T04:44:12.942660Z","end":"2026-04-23T04:44:16.771040Z","steps":["trace[1372456081] 'agreement among raft nodes before linearized reading' (duration: 3.817020194s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:16.771236Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:12.942646Z","time spent":"3.82857436s","remote":"127.0.0.1:32912","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":"info","ts":"2026-04-23T04:44:16.771451Z","caller":"traceutil/trace.go:172","msg":"trace[121389935] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.797965805s","start":"2026-04-23T04:44:13.973474Z","end":"2026-04-23T04:44:16.771440Z","steps":["trace[121389935] 'process raft request' (duration: 2.797888212s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:16.771544Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:13.973419Z","time spent":"2.798081259s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:16.771684Z","caller":"traceutil/trace.go:172","msg":"trace[1060891883] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"3.830262234s","start":"2026-04-23T04:44:12.941407Z","end":"2026-04-23T04:44:16.771669Z","steps":["trace[1060891883] 'process raft request' (duration: 3.818301962s)","trace[1060891883] 'compare' (duration: 10.272199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:16.771978Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:12.941379Z","time spent":"3.830328877s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:16.812001Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.07407523s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:16.812026Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.407636658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:16.812086Z","caller":"traceutil/trace.go:172","msg":"trace[836624352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:551; }","duration":"2.074168073s","start":"2026-04-23T04:44:14.737898Z","end":"2026-04-23T04:44:16.812066Z","steps":["trace[836624352] 'agreement among raft nodes before linearized reading' (duration: 2.074038729s)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:16.812117Z","caller":"traceutil/trace.go:172","msg":"trace[1890425185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.407736162s","start":"2026-04-23T04:44:15.404365Z","end":"2026-04-23T04:44:16.812102Z","steps":["trace[1890425185] 'agreement among raft nodes before linearized reading' (duration: 1.407596166s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:16.812330Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:15.404346Z","time spent":"1.407971069s","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:16.811998Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.782702277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:16.812393Z","caller":"traceutil/trace.go:172","msg":"trace[73162987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.783105021s","start":"2026-04-23T04:44:15.029271Z","end":"2026-04-23T04:44:16.812376Z","steps":["trace[73162987] 'agreement among raft nodes before linearized reading' (duration: 1.782656015s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:16.812438Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:15.029257Z","time spent":"1.783169273s","remote":"127.0.0.1:60666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:17.464449Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"175.818686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:17.464718Z","caller":"traceutil/trace.go:172","msg":"trace[1620384439] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"229.359777ms","start":"2026-04-23T04:44:17.235332Z","end":"2026-04-23T04:44:17.464691Z","steps":["trace[1620384439] 'process raft request' (duration: 52.922027ms)","trace[1620384439] 'compare' (duration: 175.671721ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:17.464850Z","caller":"traceutil/trace.go:172","msg":"trace[699016392] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"190.526194ms","start":"2026-04-23T04:44:17.274312Z","end":"2026-04-23T04:44:17.464839Z","steps":["trace[699016392] 'process raft request' (duration: 190.395839ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:17.465556Z","caller":"traceutil/trace.go:172","msg":"trace[679040082] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"229.103966ms","start":"2026-04-23T04:44:17.236446Z","end":"2026-04-23T04:44:17.465550Z","steps":["trace[679040082] 'process raft request' (duration: 228.093908ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:17.633514Z","caller":"traceutil/trace.go:172","msg":"trace[1967791132] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"139.470968ms","start":"2026-04-23T04:44:17.494010Z","end":"2026-04-23T04:44:17.633481Z","steps":["trace[1967791132] 'process raft request' (duration: 131.094911ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:17.634068Z","caller":"traceutil/trace.go:172","msg":"trace[136851371] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"109.495642ms","start":"2026-04-23T04:44:17.524548Z","end":"2026-04-23T04:44:17.634043Z","steps":["trace[136851371] 'process raft request' (duration: 109.269723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:23.823639Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"111.024336ms","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-23T04:44:23.823756Z","caller":"traceutil/trace.go:172","msg":"trace[1612150270] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:659; }","duration":"111.153081ms","start":"2026-04-23T04:44:23.712582Z","end":"2026-04-23T04:44:23.823735Z","steps":["trace[1612150270] 'range keys from in-memory index tree' (duration: 111.003646ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:23.860306Z","caller":"traceutil/trace.go:172","msg":"trace[435521099] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"188.391773ms","start":"2026-04-23T04:44:23.671893Z","end":"2026-04-23T04:44:23.860285Z","steps":["trace[435521099] 'process raft request' (duration: 182.541707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:24.113961Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"156.293325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:24.114195Z","caller":"traceutil/trace.go:172","msg":"trace[479309170] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"192.369389ms","start":"2026-04-23T04:44:23.921788Z","end":"2026-04-23T04:44:24.114158Z","steps":["trace[479309170] 'process raft request' (duration: 35.818193ms)","trace[479309170] 'compare' (duration: 156.038236ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:24.787255Z","caller":"traceutil/trace.go:172","msg":"trace[840670478] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"182.518127ms","start":"2026-04-23T04:44:24.604701Z","end":"2026-04-23T04:44:24.787219Z","steps":["trace[840670478] 'process raft request' (duration: 181.640753ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:25.080906Z","caller":"traceutil/trace.go:172","msg":"trace[512679642] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:692; }","duration":"201.396785ms","start":"2026-04-23T04:44:24.879484Z","end":"2026-04-23T04:44:25.080881Z","steps":["trace[512679642] 'read index received' (duration: 201.387595ms)","trace[512679642] 'applied index is now lower than readState.Index' (duration: 7.32µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:25.081255Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"201.734708ms","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-23T04:44:25.081396Z","caller":"traceutil/trace.go:172","msg":"trace[1128031281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"201.889194ms","start":"2026-04-23T04:44:24.879479Z","end":"2026-04-23T04:44:25.081368Z","steps":["trace[1128031281] 'agreement among raft nodes before linearized reading' (duration: 201.5191ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:25.081507Z","caller":"traceutil/trace.go:172","msg":"trace[231180569] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"275.990912ms","start":"2026-04-23T04:44:24.805491Z","end":"2026-04-23T04:44:25.081482Z","steps":["trace[231180569] 'process raft request' (duration: 275.448782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:25.169007Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"289.395471ms","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-23T04:44:25.169074Z","caller":"traceutil/trace.go:172","msg":"trace[951197985] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"257.932412ms","start":"2026-04-23T04:44:24.911066Z","end":"2026-04-23T04:44:25.168999Z","steps":["trace[951197985] 'process raft request' (duration: 257.891621ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:25.169073Z","caller":"traceutil/trace.go:172","msg":"trace[886554076] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"289.520366ms","start":"2026-04-23T04:44:24.879501Z","end":"2026-04-23T04:44:25.169021Z","steps":["trace[886554076] 'process raft request' (duration: 289.355379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:25.169073Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"137.393444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:25.169238Z","caller":"traceutil/trace.go:172","msg":"trace[932271624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"137.589341ms","start":"2026-04-23T04:44:25.031624Z","end":"2026-04-23T04:44:25.169213Z","steps":["trace[932271624] 'agreement among raft nodes before linearized reading' (duration: 137.282259ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:25.169116Z","caller":"traceutil/trace.go:172","msg":"trace[39413566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"289.514366ms","start":"2026-04-23T04:44:24.879580Z","end":"2026-04-23T04:44:25.169094Z","steps":["trace[39413566] 'agreement among raft nodes before linearized reading' (duration: 289.260486ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:25.321490Z","caller":"traceutil/trace.go:172","msg":"trace[55599215] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"143.379986ms","start":"2026-04-23T04:44:25.178079Z","end":"2026-04-23T04:44:25.321459Z","steps":["trace[55599215] 'process raft request' (duration: 78.81881ms)","trace[55599215] 'compare' (duration: 64.273025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:25.320938Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"137.303659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:25.322302Z","caller":"traceutil/trace.go:172","msg":"trace[2108942075] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:666; }","duration":"138.682343ms","start":"2026-04-23T04:44:25.183605Z","end":"2026-04-23T04:44:25.322287Z","steps":["trace[2108942075] 'agreement among raft nodes before linearized reading' (duration: 73.249083ms)","trace[2108942075] 'range keys from in-memory index tree' (duration: 64.017735ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:25.606605Z","caller":"traceutil/trace.go:172","msg":"trace[111040352] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:698; }","duration":"123.511193ms","start":"2026-04-23T04:44:25.483062Z","end":"2026-04-23T04:44:25.606573Z","steps":["trace[111040352] 'read index received' (duration: 123.500663ms)","trace[111040352] 'applied index is now lower than readState.Index' (duration: 8.38µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:25.633912Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"150.834324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T04:44:25.633974Z","caller":"traceutil/trace.go:172","msg":"trace[1427226114] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"305.916175ms","start":"2026-04-23T04:44:25.328035Z","end":"2026-04-23T04:44:25.633951Z","steps":["trace[1427226114] 'process raft request' (duration: 278.559083ms)","trace[1427226114] 'compare' (duration: 27.196785ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:25.633988Z","caller":"traceutil/trace.go:172","msg":"trace[496957618] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:668; }","duration":"150.925139ms","start":"2026-04-23T04:44:25.483045Z","end":"2026-04-23T04:44:25.633970Z","steps":["trace[496957618] 'agreement among raft nodes before linearized reading' (duration: 123.627189ms)","trace[496957618] 'range keys from in-memory index tree' (duration: 27.068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:25.634080Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:25.328017Z","time spent":"306.011799ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2738,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T04:44:26.213160Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:26.714336Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:26.926657Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.23863929s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T04:44:26.926902Z","caller":"traceutil/trace.go:172","msg":"trace[20308734] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:700; }","duration":"1.214480603s","start":"2026-04-23T04:44:25.712397Z","end":"2026-04-23T04:44:26.926878Z","steps":["trace[20308734] 'read index received' (duration: 1.214469312s)","trace[20308734] 'applied index is now lower than readState.Index' (duration: 9.731µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:27.023769Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.311344336s","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-23T04:44:27.023870Z","caller":"traceutil/trace.go:172","msg":"trace[1579780316] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:670; }","duration":"1.31145487s","start":"2026-04-23T04:44:25.712390Z","end":"2026-04-23T04:44:27.023845Z","steps":["trace[1579780316] 'agreement among raft nodes before linearized reading' (duration: 1.214569716s)","trace[1579780316] 'range keys from in-memory index tree' (duration: 96.748529ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:27.024690Z","caller":"traceutil/trace.go:172","msg":"trace[1168124682] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.380484861s","start":"2026-04-23T04:44:25.644191Z","end":"2026-04-23T04:44:27.024676Z","steps":["trace[1168124682] 'process raft request' (duration: 1.380419978s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:27.024829Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:25.644168Z","time spent":"1.380577124s","remote":"127.0.0.1:32856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2879,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:27.024958Z","caller":"traceutil/trace.go:172","msg":"trace[1775308360] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"1.384225315s","start":"2026-04-23T04:44:25.640706Z","end":"2026-04-23T04:44:27.024932Z","steps":["trace[1775308360] 'process raft request' (duration: 1.383785789s)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:27.025019Z","caller":"traceutil/trace.go:172","msg":"trace[1028142380] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.384523778s","start":"2026-04-23T04:44:25.640470Z","end":"2026-04-23T04:44:27.024994Z","steps":["trace[1028142380] 'process raft request' (duration: 1.286475647s)","trace[1028142380] 'compare' (duration: 97.184286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:27.025178Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:25.640460Z","time spent":"1.384635862s","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":933,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T04:44:27.025875Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:25.640677Z","time spent":"1.384362871s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3268,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:27.427514Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385602,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T04:44:27.726663Z","caller":"traceutil/trace.go:172","msg":"trace[381964448] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:703; }","duration":"799.658342ms","start":"2026-04-23T04:44:26.926974Z","end":"2026-04-23T04:44:27.726632Z","steps":["trace[381964448] 'read index received' (duration: 799.645782ms)","trace[381964448] 'applied index is now lower than readState.Index' (duration: 10.44µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:28.226787Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:28.449361Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.114098847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqbnp\" limit:1 ","response":"range_response_count:1 size:18340"} {"level":"info","ts":"2026-04-23T04:44:28.449431Z","caller":"traceutil/trace.go:172","msg":"trace[1853718670] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqbnp; range_end:; response_count:1; response_revision:673; }","duration":"2.11418192s","start":"2026-04-23T04:44:26.335232Z","end":"2026-04-23T04:44:28.449414Z","steps":["trace[1853718670] 'agreement among raft nodes before linearized reading' (duration: 1.391426806s)","trace[1853718670] 'range keys from in-memory index tree' (duration: 722.520035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:28.449489Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:26.335202Z","time spent":"2.114277664s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":18363,"request content":"key:\"/registry/pods/kube-system/cilium-gqbnp\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:28.449488Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.413681588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:28.449592Z","caller":"traceutil/trace.go:172","msg":"trace[158673542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"2.413832214s","start":"2026-04-23T04:44:26.035739Z","end":"2026-04-23T04:44:28.449571Z","steps":["trace[158673542] 'agreement among raft nodes before linearized reading' (duration: 1.690983547s)","trace[158673542] 'range keys from in-memory index tree' (duration: 722.662921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:28.449645Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:26.035712Z","time spent":"2.413912857s","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:28.450070Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"723.385389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:28.450241Z","caller":"traceutil/trace.go:172","msg":"trace[1701176654] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.573213443s","start":"2026-04-23T04:44:26.877016Z","end":"2026-04-23T04:44:28.450229Z","steps":["trace[1701176654] 'process raft request' (duration: 1.573150551s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:28.450307Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:26.876990Z","time spent":"1.573281616s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:28.450248Z","caller":"traceutil/trace.go:172","msg":"trace[1110334123] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"2.754608656s","start":"2026-04-23T04:44:25.695611Z","end":"2026-04-23T04:44:28.450220Z","steps":["trace[1110334123] 'process raft request' (duration: 2.031023469s)","trace[1110334123] 'compare' (duration: 723.237363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:28.450666Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:25.695590Z","time spent":"2.754824035s","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":692,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T04:44:28.727546Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:29.228667Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:29.729605Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:30.036504Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.999931116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T04:44:30.036664Z","caller":"traceutil/trace.go:172","msg":"trace[894360709] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000104222s","start":"2026-04-23T04:44:28.036530Z","end":"2026-04-23T04:44:30.036635Z","steps":["trace[894360709] 'agreement among raft nodes before linearized reading' (duration: 1.999926036s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:30.036737Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:28.036507Z","time spent":"2.000218897s","remote":"127.0.0.1:60666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:30.238113Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:30.738725Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:31.239381Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:31.740383Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:32.021348Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"4.29397762s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T04:44:32.022192Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.995988479s","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-23T04:44:32.022240Z","caller":"traceutil/trace.go:172","msg":"trace[1039934678] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:675; }","duration":"4.996055541s","start":"2026-04-23T04:44:27.026174Z","end":"2026-04-23T04:44:32.022230Z","steps":["trace[1039934678] 'agreement among raft nodes before linearized reading' (duration: 4.995924746s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.022216Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.695695599s","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-23T04:44:32.022319Z","caller":"traceutil/trace.go:172","msg":"trace[1428991142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"4.695815784s","start":"2026-04-23T04:44:27.326480Z","end":"2026-04-23T04:44:32.022296Z","steps":["trace[1428991142] 'agreement among raft nodes before linearized reading' (duration: 4.695503982s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.022404Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:27.326458Z","time spent":"4.695920069s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:32.022710Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.847681501s","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-23T04:44:32.022753Z","caller":"traceutil/trace.go:172","msg":"trace[978801969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"4.847725622s","start":"2026-04-23T04:44:27.175015Z","end":"2026-04-23T04:44:32.022740Z","steps":["trace[978801969] 'agreement among raft nodes before linearized reading' (duration: 4.847013324s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.022794Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:27.174998Z","time spent":"4.847787675s","remote":"127.0.0.1:32912","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":"info","ts":"2026-04-23T04:44:32.021952Z","caller":"traceutil/trace.go:172","msg":"trace[1650114072] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:705; }","duration":"4.295191639s","start":"2026-04-23T04:44:27.726737Z","end":"2026-04-23T04:44:32.021929Z","steps":["trace[1650114072] 'read index received' (duration: 4.295183718s)","trace[1650114072] 'applied index is now lower than readState.Index' (duration: 6.561µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:32.063131Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.018166123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T04:44:32.063209Z","caller":"traceutil/trace.go:172","msg":"trace[443341571] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.018248626s","start":"2026-04-23T04:44:30.044947Z","end":"2026-04-23T04:44:32.063195Z","steps":["trace[443341571] 'agreement among raft nodes before linearized reading' (duration: 2.018164452s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.063527Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.044911Z","time spent":"2.01860526s","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:32.086386Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:27.087483Z","time spent":"4.998896564s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/23 04:44:32 WARNING: [core] [Server #6]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-23T04:44:32.138684Z","caller":"traceutil/trace.go:172","msg":"trace[872877351] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"4.961064904s","start":"2026-04-23T04:44:27.177585Z","end":"2026-04-23T04:44:32.138650Z","steps":["trace[872877351] 'process raft request' (duration: 4.920716889s)","trace[872877351] 'compare' (duration: 40.22985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:32.138816Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:27.177568Z","time spent":"4.961183319s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:32.138825Z","caller":"traceutil/trace.go:172","msg":"trace[813475590] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"3.672637721s","start":"2026-04-23T04:44:28.466177Z","end":"2026-04-23T04:44:32.138815Z","steps":["trace[813475590] 'process raft request' (duration: 3.67259357s)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:32.138828Z","caller":"traceutil/trace.go:172","msg":"trace[76470893] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"115.845179ms","start":"2026-04-23T04:44:32.022963Z","end":"2026-04-23T04:44:32.138809Z","steps":["trace[76470893] 'read index received' (duration: 107.487439ms)","trace[76470893] 'applied index is now lower than readState.Index' (duration: 8.35639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:32.138875Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:28.466154Z","time spent":"3.672695684s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:32.138996Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"331.000793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" limit:1 ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-23T04:44:32.139020Z","caller":"traceutil/trace.go:172","msg":"trace[1278948459] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:678; }","duration":"331.038025ms","start":"2026-04-23T04:44:31.807976Z","end":"2026-04-23T04:44:32.139014Z","steps":["trace[1278948459] 'agreement among raft nodes before linearized reading' (duration: 330.923221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139042Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:31.807954Z","time spent":"331.081537ms","remote":"127.0.0.1:60694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:32.139128Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.313925799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:32.139206Z","caller":"traceutil/trace.go:172","msg":"trace[186740184] range","detail":"{range_begin:/registry/deployments; range_end:; response_count:0; response_revision:678; }","duration":"1.314001302s","start":"2026-04-23T04:44:30.825192Z","end":"2026-04-23T04:44:32.139193Z","steps":["trace[186740184] 'agreement among raft nodes before linearized reading' (duration: 1.313900578s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139246Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.050250089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:32.139253Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.825121Z","time spent":"1.314121988s","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":25,"response count":0,"response size":28,"request content":"key:\"/registry/deployments\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:32.139269Z","caller":"traceutil/trace.go:172","msg":"trace[1659581068] range","detail":"{range_begin:/registry/controllerrevisions; range_end:; response_count:0; response_revision:678; }","duration":"2.05026878s","start":"2026-04-23T04:44:30.088991Z","end":"2026-04-23T04:44:32.139259Z","steps":["trace[1659581068] 'agreement among raft nodes before linearized reading' (duration: 2.050237528s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139291Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.088976Z","time spent":"2.050310772s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":33,"response count":0,"response size":28,"request content":"key:\"/registry/controllerrevisions\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:32.139378Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.179838728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:32.139326Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.196662412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicybindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:32.139425Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"570.895822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:32.139428Z","caller":"traceutil/trace.go:172","msg":"trace[1994114348] range","detail":"{range_begin:/registry/validatingadmissionpolicybindings; range_end:; response_count:0; response_revision:678; }","duration":"2.196778316s","start":"2026-04-23T04:44:29.942636Z","end":"2026-04-23T04:44:32.139414Z","steps":["trace[1994114348] 'agreement among raft nodes before linearized reading' (duration: 2.19661662s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139472Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.009533586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:32.139476Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:29.942610Z","time spent":"2.196859369s","remote":"127.0.0.1:33464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/validatingadmissionpolicybindings\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:32.139493Z","caller":"traceutil/trace.go:172","msg":"trace[16583837] range","detail":"{range_begin:/registry/jobs; range_end:; response_count:0; response_revision:678; }","duration":"1.009556747s","start":"2026-04-23T04:44:31.129932Z","end":"2026-04-23T04:44:32.139489Z","steps":["trace[16583837] 'agreement among raft nodes before linearized reading' (duration: 1.009525176s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139214Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.71212223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:32.139480Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.24806883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:32.139590Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.096143865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-controller.18a8e2d2e1e18190\" limit:1 ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-04-23T04:44:32.139615Z","caller":"traceutil/trace.go:172","msg":"trace[1103892637] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-controller.18a8e2d2e1e18190; range_end:; response_count:1; response_revision:678; }","duration":"2.096171126s","start":"2026-04-23T04:44:30.043440Z","end":"2026-04-23T04:44:32.139611Z","steps":["trace[1103892637] 'agreement among raft nodes before linearized reading' (duration: 2.096105813s)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:32.139447Z","caller":"traceutil/trace.go:172","msg":"trace[2076953887] range","detail":"{range_begin:/registry/validatingwebhookconfigurations; range_end:; response_count:0; response_revision:678; }","duration":"570.917883ms","start":"2026-04-23T04:44:31.568524Z","end":"2026-04-23T04:44:32.139442Z","steps":["trace[2076953887] 'agreement among raft nodes before linearized reading' (duration: 570.886882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139631Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.043421Z","time spent":"2.096205656s","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":807,"request content":"key:\"/registry/events/kube-system/kube-apiserver-controller.18a8e2d2e1e18190\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:32.139659Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:31.568502Z","time spent":"571.141792ms","remote":"127.0.0.1:33408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:32.139579Z","caller":"traceutil/trace.go:172","msg":"trace[1704609845] range","detail":"{range_begin:/registry/ingressclasses; range_end:; response_count:0; response_revision:678; }","duration":"1.248184415s","start":"2026-04-23T04:44:30.891376Z","end":"2026-04-23T04:44:32.139561Z","steps":["trace[1704609845] 'agreement among raft nodes before linearized reading' (duration: 1.248002108s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139833Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.891351Z","time spent":"1.248470066s","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:32.139401Z","caller":"traceutil/trace.go:172","msg":"trace[2071936423] range","detail":"{range_begin:/registry/ingress; range_end:; response_count:0; response_revision:678; }","duration":"1.179862698s","start":"2026-04-23T04:44:30.959532Z","end":"2026-04-23T04:44:32.139395Z","steps":["trace[2071936423] 'agreement among raft nodes before linearized reading' (duration: 1.179824677s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.139993Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.959516Z","time spent":"1.180469442s","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":28,"request content":"key:\"/registry/ingress\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:32.139521Z","caller":"traceutil/trace.go:172","msg":"trace[1902891298] range","detail":"{range_begin:/registry/volumeattachments; range_end:; response_count:0; response_revision:678; }","duration":"1.712430223s","start":"2026-04-23T04:44:30.427085Z","end":"2026-04-23T04:44:32.139515Z","steps":["trace[1902891298] 'agreement among raft nodes before linearized reading' (duration: 1.71210377s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:32.140255Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:30.427064Z","time spent":"1.713180622s","remote":"127.0.0.1:33182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:32.139508Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:31.129871Z","time spent":"1.00963376s","remote":"127.0.0.1:32856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/jobs\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:32.657797Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:33.158527Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742385616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T04:44:33.534759Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.380234191s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T04:44:33.534960Z","caller":"traceutil/trace.go:172","msg":"trace[754284443] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:711; }","duration":"1.377234541s","start":"2026-04-23T04:44:32.157696Z","end":"2026-04-23T04:44:33.534931Z","steps":["trace[754284443] 'read index received' (duration: 1.377222601s)","trace[754284443] 'applied index is now lower than readState.Index' (duration: 10.5µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:33.616252Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"903.441414ms","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-23T04:44:33.616328Z","caller":"traceutil/trace.go:172","msg":"trace[1103177527] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:680; }","duration":"903.57864ms","start":"2026-04-23T04:44:32.712733Z","end":"2026-04-23T04:44:33.616311Z","steps":["trace[1103177527] 'range keys from in-memory index tree' (duration: 903.416523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:33.616350Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.458632016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T04:44:33.616419Z","caller":"traceutil/trace.go:172","msg":"trace[2012475711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"1.458708919s","start":"2026-04-23T04:44:32.157692Z","end":"2026-04-23T04:44:33.616401Z","steps":["trace[2012475711] 'agreement among raft nodes before linearized reading' (duration: 1.377366197s)","trace[2012475711] 'range keys from in-memory index tree' (duration: 81.154825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:33.616451Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:32.157674Z","time spent":"1.458768561s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:33.616570Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:32.140330Z","time spent":"1.476237143s","remote":"127.0.0.1:60694","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T04:44:33.616913Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:32.146464Z","time spent":"1.470442003s","remote":"127.0.0.1:60750","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T04:44:33.616953Z","caller":"traceutil/trace.go:172","msg":"trace[653193907] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.272424997s","start":"2026-04-23T04:44:32.344518Z","end":"2026-04-23T04:44:33.616943Z","steps":["trace[653193907] 'process raft request' (duration: 1.272324423s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:33.617015Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"206.313112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:33.617038Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:32.344488Z","time spent":"1.272494429s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:33.617066Z","caller":"traceutil/trace.go:172","msg":"trace[813485982] range","detail":"{range_begin:/registry/csistoragecapacities; range_end:; response_count:0; response_revision:681; }","duration":"206.371064ms","start":"2026-04-23T04:44:33.410683Z","end":"2026-04-23T04:44:33.617054Z","steps":["trace[813485982] 'agreement among raft nodes before linearized reading' (duration: 206.283801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:33.617153Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.453633199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:33.617177Z","caller":"traceutil/trace.go:172","msg":"trace[1590552072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"1.453676402s","start":"2026-04-23T04:44:32.163493Z","end":"2026-04-23T04:44:33.617170Z","steps":["trace[1590552072] 'agreement among raft nodes before linearized reading' (duration: 1.453615098s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:33.617197Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:32.163476Z","time spent":"1.453717523s","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T04:44:33.889534Z","caller":"traceutil/trace.go:172","msg":"trace[837933895] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:715; }","duration":"266.225228ms","start":"2026-04-23T04:44:33.623282Z","end":"2026-04-23T04:44:33.889508Z","steps":["trace[837933895] 'read index received' (duration: 266.216147ms)","trace[837933895] 'applied index is now lower than readState.Index' (duration: 7.88µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:34.362998Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"739.686308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-controller.18a8e2d2e1e18190\" limit:1 ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-04-23T04:44:34.363101Z","caller":"traceutil/trace.go:172","msg":"trace[1998137784] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-controller.18a8e2d2e1e18190; range_end:; response_count:1; response_revision:682; }","duration":"739.796692ms","start":"2026-04-23T04:44:33.623277Z","end":"2026-04-23T04:44:34.363073Z","steps":["trace[1998137784] 'agreement among raft nodes before linearized reading' (duration: 266.331592ms)","trace[1998137784] 'range keys from in-memory index tree' (duration: 473.309945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:34.363164Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:33.623258Z","time spent":"739.895857ms","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":807,"request content":"key:\"/registry/events/kube-system/kube-apiserver-controller.18a8e2d2e1e18190\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:34.363909Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"474.304023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:34.363986Z","caller":"traceutil/trace.go:172","msg":"trace[1400360800] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"474.386427ms","start":"2026-04-23T04:44:33.889588Z","end":"2026-04-23T04:44:34.363974Z","steps":["trace[1400360800] 'read index received' (duration: 34.842µs)","trace[1400360800] 'applied index is now lower than readState.Index' (duration: 474.349615ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:34.364098Z","caller":"traceutil/trace.go:172","msg":"trace[777806768] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"745.619154ms","start":"2026-04-23T04:44:33.618450Z","end":"2026-04-23T04:44:34.364069Z","steps":["trace[777806768] 'process raft request' (duration: 271.075631ms)","trace[777806768] 'compare' (duration: 474.179919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:34.364172Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:33.618428Z","time spent":"745.696596ms","remote":"127.0.0.1:60694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:34.364286Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"738.935178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:34.364284Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"661.506651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipaddresses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:34.364348Z","caller":"traceutil/trace.go:172","msg":"trace[719715556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"738.993111ms","start":"2026-04-23T04:44:33.625341Z","end":"2026-04-23T04:44:34.364334Z","steps":["trace[719715556] 'agreement among raft nodes before linearized reading' (duration: 738.911218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:34.364399Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:33.625331Z","time spent":"739.058754ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:34.364388Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"651.685742ms","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-23T04:44:34.364451Z","caller":"traceutil/trace.go:172","msg":"trace[1222675688] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:683; }","duration":"651.770186ms","start":"2026-04-23T04:44:33.712664Z","end":"2026-04-23T04:44:34.364434Z","steps":["trace[1222675688] 'agreement among raft nodes before linearized reading' (duration: 651.642971ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:34.364352Z","caller":"traceutil/trace.go:172","msg":"trace[844349074] range","detail":"{range_begin:/registry/ipaddresses; range_end:; response_count:0; response_revision:683; }","duration":"661.597045ms","start":"2026-04-23T04:44:33.702743Z","end":"2026-04-23T04:44:34.364340Z","steps":["trace[844349074] 'agreement among raft nodes before linearized reading' (duration: 661.425547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:34.364572Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:33.702728Z","time spent":"661.837354ms","remote":"127.0.0.1:33012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":25,"response count":0,"response size":28,"request content":"key:\"/registry/ipaddresses\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:34.367384Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"386.370361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:34.367434Z","caller":"traceutil/trace.go:172","msg":"trace[816175375] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"221.770137ms","start":"2026-04-23T04:44:34.145656Z","end":"2026-04-23T04:44:34.367426Z","steps":["trace[816175375] 'process raft request' (duration: 221.703814ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:34.367453Z","caller":"traceutil/trace.go:172","msg":"trace[1595543472] range","detail":"{range_begin:/registry/prioritylevelconfigurations; range_end:; response_count:0; response_revision:683; }","duration":"386.441783ms","start":"2026-04-23T04:44:33.980996Z","end":"2026-04-23T04:44:34.367438Z","steps":["trace[1595543472] 'agreement among raft nodes before linearized reading' (duration: 386.302448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:34.367498Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:33.980979Z","time spent":"386.508006ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/prioritylevelconfigurations\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:34.367700Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"208.650726ms","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-23T04:44:34.367970Z","caller":"traceutil/trace.go:172","msg":"trace[615600325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"208.921527ms","start":"2026-04-23T04:44:34.159041Z","end":"2026-04-23T04:44:34.367962Z","steps":["trace[615600325] 'agreement among raft nodes before linearized reading' (duration: 208.564642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:34.367756Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"208.674496ms","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-23T04:44:34.368086Z","caller":"traceutil/trace.go:172","msg":"trace[1774003200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"209.005009ms","start":"2026-04-23T04:44:34.159067Z","end":"2026-04-23T04:44:34.368072Z","steps":["trace[1774003200] 'agreement among raft nodes before linearized reading' (duration: 208.53137ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:35.752966Z","caller":"traceutil/trace.go:172","msg":"trace[1779589297] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:721; }","duration":"203.426246ms","start":"2026-04-23T04:44:35.549519Z","end":"2026-04-23T04:44:35.752945Z","steps":["trace[1779589297] 'read index received' (duration: 203.419776ms)","trace[1779589297] 'applied index is now lower than readState.Index' (duration: 5.14µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:35.753119Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"203.582842ms","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-23T04:44:35.753182Z","caller":"traceutil/trace.go:172","msg":"trace[1496670263] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:688; }","duration":"203.657375ms","start":"2026-04-23T04:44:35.549515Z","end":"2026-04-23T04:44:35.753172Z","steps":["trace[1496670263] 'agreement among raft nodes before linearized reading' (duration: 203.552781ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:35.753289Z","caller":"traceutil/trace.go:172","msg":"trace[1920865064] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"390.369458ms","start":"2026-04-23T04:44:35.362897Z","end":"2026-04-23T04:44:35.753266Z","steps":["trace[1920865064] 'process raft request' (duration: 390.145818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:35.753429Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:35.362882Z","time spent":"390.486283ms","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":747,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T04:44:36.031723Z","caller":"traceutil/trace.go:172","msg":"trace[1003495094] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:722; }","duration":"278.652474ms","start":"2026-04-23T04:44:35.753046Z","end":"2026-04-23T04:44:36.031698Z","steps":["trace[1003495094] 'read index received' (duration: 278.644543ms)","trace[1003495094] 'applied index is now lower than readState.Index' (duration: 6.641µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:36.031870Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"298.17363ms","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-23T04:44:36.031906Z","caller":"traceutil/trace.go:172","msg":"trace[1847043348] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:689; }","duration":"298.223382ms","start":"2026-04-23T04:44:35.733671Z","end":"2026-04-23T04:44:36.031894Z","steps":["trace[1847043348] 'agreement among raft nodes before linearized reading' (duration: 298.144298ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:36.031943Z","caller":"traceutil/trace.go:172","msg":"trace[1452679019] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"409.022663ms","start":"2026-04-23T04:44:35.622904Z","end":"2026-04-23T04:44:36.031926Z","steps":["trace[1452679019] 'process raft request' (duration: 408.985472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:36.032057Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:35.622871Z","time spent":"409.132517ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:36.032187Z","caller":"traceutil/trace.go:172","msg":"trace[467442928] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"655.16383ms","start":"2026-04-23T04:44:35.377007Z","end":"2026-04-23T04:44:36.032171Z","steps":["trace[467442928] 'process raft request' (duration: 654.748873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:36.032288Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:35.376988Z","time spent":"655.230212ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18595,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:44:36.623405Z","caller":"traceutil/trace.go:172","msg":"trace[393958713] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:728; }","duration":"236.602278ms","start":"2026-04-23T04:44:36.386765Z","end":"2026-04-23T04:44:36.623367Z","steps":["trace[393958713] 'read index received' (duration: 236.582768ms)","trace[393958713] 'applied index is now lower than readState.Index' (duration: 16.78µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:36.637174Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.369578ms","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-23T04:44:36.637242Z","caller":"traceutil/trace.go:172","msg":"trace[1014762209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:695; }","duration":"250.469881ms","start":"2026-04-23T04:44:36.386759Z","end":"2026-04-23T04:44:36.637229Z","steps":["trace[1014762209] 'agreement among raft nodes before linearized reading' (duration: 236.711853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:36.637819Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"268.303853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqbnp\" limit:1 ","response":"range_response_count:1 size:18610"} {"level":"info","ts":"2026-04-23T04:44:36.637850Z","caller":"traceutil/trace.go:172","msg":"trace[168330370] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqbnp; range_end:; response_count:1; response_revision:695; }","duration":"268.336224ms","start":"2026-04-23T04:44:36.369507Z","end":"2026-04-23T04:44:36.637843Z","steps":["trace[168330370] 'range keys from in-memory index tree' (duration: 268.188868ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:36.638128Z","caller":"traceutil/trace.go:172","msg":"trace[1233476199] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"264.915188ms","start":"2026-04-23T04:44:36.373175Z","end":"2026-04-23T04:44:36.638090Z","steps":["trace[1233476199] 'process raft request' (duration: 250.277235ms)","trace[1233476199] 'compare' (duration: 14.133013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:36.638524Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"251.613666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T04:44:36.638586Z","caller":"traceutil/trace.go:172","msg":"trace[1633255823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"251.686769ms","start":"2026-04-23T04:44:36.386884Z","end":"2026-04-23T04:44:36.638570Z","steps":["trace[1633255823] 'agreement among raft nodes before linearized reading' (duration: 251.507762ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:37.498065Z","caller":"traceutil/trace.go:172","msg":"trace[1368877492] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"116.379802ms","start":"2026-04-23T04:44:37.381648Z","end":"2026-04-23T04:44:37.498028Z","steps":["trace[1368877492] 'process raft request' (duration: 116.164563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.092731Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50230","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.108425Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50256","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.130913Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50286","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.151241Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50292","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.213998Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50310","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.236062Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50342","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.278242Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50374","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.298792Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50398","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.396617Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50420","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.460912Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50446","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.493243Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"105.974805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-75968c57df-68c8m.18a8e2d9a34eb1ea\" limit:1 ","response":"range_response_count:1 size:865"} {"level":"info","ts":"2026-04-23T04:44:38.493286Z","caller":"traceutil/trace.go:172","msg":"trace[1541056978] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-75968c57df-68c8m.18a8e2d9a34eb1ea; range_end:; response_count:1; response_revision:707; }","duration":"106.026567ms","start":"2026-04-23T04:44:38.387250Z","end":"2026-04-23T04:44:38.493276Z","steps":["trace[1541056978] 'range keys from in-memory index tree' (duration: 105.882802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.493541Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.367792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqbnp\" limit:1 ","response":"range_response_count:1 size:18876"} {"level":"info","ts":"2026-04-23T04:44:38.493562Z","caller":"traceutil/trace.go:172","msg":"trace[619276513] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqbnp; range_end:; response_count:1; response_revision:707; }","duration":"106.390483ms","start":"2026-04-23T04:44:38.387167Z","end":"2026-04-23T04:44:38.493558Z","steps":["trace[619276513] 'range keys from in-memory index tree' (duration: 106.293569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.547035Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50464","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.559699Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50476","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-23T04:44:38.689601Z","caller":"traceutil/trace.go:172","msg":"trace[1984650327] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:741; }","duration":"133.956287ms","start":"2026-04-23T04:44:38.555604Z","end":"2026-04-23T04:44:38.689561Z","steps":["trace[1984650327] 'read index received' (duration: 133.948307ms)","trace[1984650327] 'applied index is now lower than readState.Index' (duration: 6.66µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:38.690177Z","caller":"traceutil/trace.go:172","msg":"trace[1973058852] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"191.036895ms","start":"2026-04-23T04:44:38.499124Z","end":"2026-04-23T04:44:38.690161Z","steps":["trace[1973058852] 'process raft request' (duration: 190.855657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.689785Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"134.166326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:38.690620Z","caller":"traceutil/trace.go:172","msg":"trace[340158953] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies; range_end:; response_count:0; response_revision:707; }","duration":"135.01985ms","start":"2026-04-23T04:44:38.555587Z","end":"2026-04-23T04:44:38.690607Z","steps":["trace[340158953] 'agreement among raft nodes before linearized reading' (duration: 134.114634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.713179Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"157.532152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:38.713227Z","caller":"traceutil/trace.go:172","msg":"trace[14344932] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:708; }","duration":"157.593854ms","start":"2026-04-23T04:44:38.555625Z","end":"2026-04-23T04:44:38.713218Z","steps":["trace[14344932] 'agreement among raft nodes before linearized reading' (duration: 157.505901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.713288Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"144.842874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:38.713369Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"145.050462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:38.713391Z","caller":"traceutil/trace.go:172","msg":"trace[1715064728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:708; }","duration":"145.077303ms","start":"2026-04-23T04:44:38.568307Z","end":"2026-04-23T04:44:38.713384Z","steps":["trace[1715064728] 'agreement among raft nodes before linearized reading' (duration: 145.033731ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:38.713393Z","caller":"traceutil/trace.go:172","msg":"trace[1759519037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants; range_end:; response_count:0; response_revision:708; }","duration":"144.991129ms","start":"2026-04-23T04:44:38.568383Z","end":"2026-04-23T04:44:38.713374Z","steps":["trace[1759519037] 'agreement among raft nodes before linearized reading' (duration: 144.77031ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:38.713555Z","caller":"traceutil/trace.go:172","msg":"trace[1779200891] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"205.051386ms","start":"2026-04-23T04:44:38.508493Z","end":"2026-04-23T04:44:38.713544Z","steps":["trace[1779200891] 'process raft request' (duration: 204.804646ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:38.713662Z","caller":"traceutil/trace.go:172","msg":"trace[877991302] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"204.382139ms","start":"2026-04-23T04:44:38.509272Z","end":"2026-04-23T04:44:38.713654Z","steps":["trace[877991302] 'process raft request' (duration: 204.171301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:38.720092Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50488","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.776808Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50522","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.801424Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50528","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.831813Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50544","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.859569Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50562","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.884616Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50574","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.897745Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50596","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.923794Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50608","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.953434Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50630","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.981208Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50650","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:38.997435Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50654","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:39.013377Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50668","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:39.054619Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50694","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-23T04:44:39.515739Z","caller":"traceutil/trace.go:172","msg":"trace[287991097] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"107.397613ms","start":"2026-04-23T04:44:39.408325Z","end":"2026-04-23T04:44:39.515723Z","steps":["trace[287991097] 'process raft request' (duration: 107.282628ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:40.620399Z","caller":"traceutil/trace.go:172","msg":"trace[915166808] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"202.890584ms","start":"2026-04-23T04:44:40.417489Z","end":"2026-04-23T04:44:40.620380Z","steps":["trace[915166808] 'process raft request' (duration: 202.761798ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:40.878836Z","caller":"traceutil/trace.go:172","msg":"trace[1771851075] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"112.548759ms","start":"2026-04-23T04:44:40.766262Z","end":"2026-04-23T04:44:40.878810Z","steps":["trace[1771851075] 'process raft request' (duration: 68.183933ms)","trace[1771851075] 'compare' (duration: 44.195548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:41.170205Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"139.795857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.170270Z","caller":"traceutil/trace.go:172","msg":"trace[1306667820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"139.87248ms","start":"2026-04-23T04:44:41.030384Z","end":"2026-04-23T04:44:41.170257Z","steps":["trace[1306667820] 'range keys from in-memory index tree' (duration: 139.716904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.545903Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"171.194213ms","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:494"} {"level":"warn","ts":"2026-04-23T04:44:41.545963Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.37802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:41.545982Z","caller":"traceutil/trace.go:172","msg":"trace[2086611059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"171.284127ms","start":"2026-04-23T04:44:41.374680Z","end":"2026-04-23T04:44:41.545964Z","steps":["trace[2086611059] 'agreement among raft nodes before linearized reading' (duration: 64.866236ms)","trace[2086611059] 'range keys from in-memory index tree' (duration: 106.219823ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:41.546061Z","caller":"traceutil/trace.go:172","msg":"trace[2138214553] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"106.546516ms","start":"2026-04-23T04:44:41.439503Z","end":"2026-04-23T04:44:41.546050Z","steps":["trace[2138214553] 'read index received' (duration: 75.673µs)","trace[2138214553] 'applied index is now lower than readState.Index' (duration: 106.469953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:41.546116Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"168.816996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546159Z","caller":"traceutil/trace.go:172","msg":"trace[694946627] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"168.838057ms","start":"2026-04-23T04:44:41.377288Z","end":"2026-04-23T04:44:41.546126Z","steps":["trace[694946627] 'agreement among raft nodes before linearized reading' (duration: 168.793835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546200Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"164.475221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546293Z","caller":"traceutil/trace.go:172","msg":"trace[167864923] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"164.586786ms","start":"2026-04-23T04:44:41.381686Z","end":"2026-04-23T04:44:41.546272Z","steps":["trace[167864923] 'agreement among raft nodes before linearized reading' (duration: 164.382848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546396Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"168.91959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546436Z","caller":"traceutil/trace.go:172","msg":"trace[939923531] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"168.960992ms","start":"2026-04-23T04:44:41.377469Z","end":"2026-04-23T04:44:41.546430Z","steps":["trace[939923531] 'agreement among raft nodes before linearized reading' (duration: 168.894949ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:41.546476Z","caller":"traceutil/trace.go:172","msg":"trace[707576340] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"173.744785ms","start":"2026-04-23T04:44:41.372719Z","end":"2026-04-23T04:44:41.546464Z","steps":["trace[707576340] 'process raft request' (duration: 66.816855ms)","trace[707576340] 'compare' (duration: 106.263143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:41.546465Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.158379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:41.546519Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"159.546392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546533Z","caller":"traceutil/trace.go:172","msg":"trace[697448203] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"159.558893ms","start":"2026-04-23T04:44:41.386971Z","end":"2026-04-23T04:44:41.546530Z","steps":["trace[697448203] 'agreement among raft nodes before linearized reading' (duration: 159.541112ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:41.546536Z","caller":"traceutil/trace.go:172","msg":"trace[885411128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"167.246002ms","start":"2026-04-23T04:44:41.379277Z","end":"2026-04-23T04:44:41.546523Z","steps":["trace[885411128] 'agreement among raft nodes before linearized reading' (duration: 167.093797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546576Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"132.711781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546588Z","caller":"traceutil/trace.go:172","msg":"trace[1043551302] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"132.723712ms","start":"2026-04-23T04:44:41.413861Z","end":"2026-04-23T04:44:41.546585Z","steps":["trace[1043551302] 'agreement among raft nodes before linearized reading' (duration: 132.704961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546672Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"162.366397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546692Z","caller":"traceutil/trace.go:172","msg":"trace[468899677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"162.387408ms","start":"2026-04-23T04:44:41.384301Z","end":"2026-04-23T04:44:41.546688Z","steps":["trace[468899677] 'agreement among raft nodes before linearized reading' (duration: 162.358137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546701Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.144459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546725Z","caller":"traceutil/trace.go:172","msg":"trace[1257514592] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"167.16338ms","start":"2026-04-23T04:44:41.379551Z","end":"2026-04-23T04:44:41.546714Z","steps":["trace[1257514592] 'agreement among raft nodes before linearized reading' (duration: 167.132419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546497Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.401179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546784Z","caller":"traceutil/trace.go:172","msg":"trace[490161538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"167.687301ms","start":"2026-04-23T04:44:41.379070Z","end":"2026-04-23T04:44:41.546778Z","steps":["trace[490161538] 'agreement among raft nodes before linearized reading' (duration: 167.391919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:41.546666Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"165.579967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.546906Z","caller":"traceutil/trace.go:172","msg":"trace[105743039] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"165.818206ms","start":"2026-04-23T04:44:41.381080Z","end":"2026-04-23T04:44:41.546898Z","steps":["trace[105743039] 'agreement among raft nodes before linearized reading' (duration: 165.568526ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:41.731270Z","caller":"traceutil/trace.go:172","msg":"trace[1951275981] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:759; }","duration":"153.104642ms","start":"2026-04-23T04:44:41.578142Z","end":"2026-04-23T04:44:41.731246Z","steps":["trace[1951275981] 'read index received' (duration: 153.097092ms)","trace[1951275981] 'applied index is now lower than readState.Index' (duration: 6.53µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:41.732517Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.354583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:41.732621Z","caller":"traceutil/trace.go:172","msg":"trace[242225155] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:725; }","duration":"154.477958ms","start":"2026-04-23T04:44:41.578122Z","end":"2026-04-23T04:44:41.732600Z","steps":["trace[242225155] 'agreement among raft nodes before linearized reading' (duration: 153.227697ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:41.732749Z","caller":"traceutil/trace.go:172","msg":"trace[301017889] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"179.691094ms","start":"2026-04-23T04:44:41.553040Z","end":"2026-04-23T04:44:41.732731Z","steps":["trace[301017889] 'process raft request' (duration: 178.224205ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:41.907668Z","caller":"traceutil/trace.go:172","msg":"trace[1890549708] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"126.903217ms","start":"2026-04-23T04:44:41.780739Z","end":"2026-04-23T04:44:41.907642Z","steps":["trace[1890549708] 'process raft request' (duration: 126.73158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:44.451303Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50708","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:46.354299Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50738","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:46.367350Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50756","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:46.470799Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50764","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:46.494094Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:50790","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:44:47.854642Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:43132","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-23T04:44:49.685517Z","caller":"traceutil/trace.go:172","msg":"trace[62058634] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"210.634594ms","start":"2026-04-23T04:44:49.474856Z","end":"2026-04-23T04:44:49.685490Z","steps":["trace[62058634] 'process raft request' (duration: 202.081104ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:49.685956Z","caller":"traceutil/trace.go:172","msg":"trace[136060298] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"209.020357ms","start":"2026-04-23T04:44:49.476927Z","end":"2026-04-23T04:44:49.685947Z","steps":["trace[136060298] 'process raft request' (duration: 208.933443ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:49.947376Z","caller":"traceutil/trace.go:172","msg":"trace[2048580617] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:860; }","duration":"235.085204ms","start":"2026-04-23T04:44:49.712247Z","end":"2026-04-23T04:44:49.947332Z","steps":["trace[2048580617] 'read index received' (duration: 235.060623ms)","trace[2048580617] 'applied index is now lower than readState.Index' (duration: 16.091µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:49.990640Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"278.369556ms","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-23T04:44:49.990727Z","caller":"traceutil/trace.go:172","msg":"trace[943345098] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:823; }","duration":"278.47006ms","start":"2026-04-23T04:44:49.712238Z","end":"2026-04-23T04:44:49.990708Z","steps":["trace[943345098] 'agreement among raft nodes before linearized reading' (duration: 235.186988ms)","trace[943345098] 'range keys from in-memory index tree' (duration: 43.154527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:49.991900Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"226.220191ms","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:494"} {"level":"info","ts":"2026-04-23T04:44:49.991994Z","caller":"traceutil/trace.go:172","msg":"trace[1711891087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"226.323396ms","start":"2026-04-23T04:44:49.765651Z","end":"2026-04-23T04:44:49.991974Z","steps":["trace[1711891087] 'agreement among raft nodes before linearized reading' (duration: 226.100817ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:49.992260Z","caller":"traceutil/trace.go:172","msg":"trace[673038745] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"296.969467ms","start":"2026-04-23T04:44:49.695258Z","end":"2026-04-23T04:44:49.992227Z","steps":["trace[673038745] 'process raft request' (duration: 252.07746ms)","trace[673038745] 'compare' (duration: 44.209929ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:52.430876Z","caller":"traceutil/trace.go:172","msg":"trace[1121871227] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:890; }","duration":"158.332736ms","start":"2026-04-23T04:44:52.272467Z","end":"2026-04-23T04:44:52.430799Z","steps":["trace[1121871227] 'read index received' (duration: 158.312085ms)","trace[1121871227] 'applied index is now lower than readState.Index' (duration: 8.631µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:52.431030Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.548554ms","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-23T04:44:52.431125Z","caller":"traceutil/trace.go:172","msg":"trace[1163035152] range","detail":"{range_begin:/registry/csidrivers; range_end:; response_count:0; response_revision:852; }","duration":"158.657319ms","start":"2026-04-23T04:44:52.272451Z","end":"2026-04-23T04:44:52.431109Z","steps":["trace[1163035152] 'agreement among raft nodes before linearized reading' (duration: 158.499772ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:52.431530Z","caller":"traceutil/trace.go:172","msg":"trace[1896171269] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"219.504322ms","start":"2026-04-23T04:44:52.212002Z","end":"2026-04-23T04:44:52.431506Z","steps":["trace[1896171269] 'process raft request' (duration: 218.932858ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:54.682589Z","caller":"traceutil/trace.go:172","msg":"trace[1589901205] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"174.88207ms","start":"2026-04-23T04:44:54.507673Z","end":"2026-04-23T04:44:54.682555Z","steps":["trace[1589901205] 'process raft request' (duration: 106.549389ms)","trace[1589901205] 'compare' (duration: 67.866552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:54.988012Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"154.620695ms","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-23T04:44:54.988104Z","caller":"traceutil/trace.go:172","msg":"trace[768016386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:863; }","duration":"154.740429ms","start":"2026-04-23T04:44:54.833346Z","end":"2026-04-23T04:44:54.988087Z","steps":["trace[768016386] 'range keys from in-memory index tree' (duration: 151.053846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:54.988782Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"151.894521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T04:44:54.988978Z","caller":"traceutil/trace.go:172","msg":"trace[881965302] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"176.506407ms","start":"2026-04-23T04:44:54.812436Z","end":"2026-04-23T04:44:54.988942Z","steps":["trace[881965302] 'process raft request' (duration: 24.361816ms)","trace[881965302] 'compare' (duration: 150.971973ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:55.159540Z","caller":"traceutil/trace.go:172","msg":"trace[98585800] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:902; }","duration":"139.172155ms","start":"2026-04-23T04:44:55.020324Z","end":"2026-04-23T04:44:55.159496Z","steps":["trace[98585800] 'read index received' (duration: 139.151774ms)","trace[98585800] 'applied index is now lower than readState.Index' (duration: 17.561µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:55.207435Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"187.077677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattributesclasses\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:55.207486Z","caller":"traceutil/trace.go:172","msg":"trace[82131387] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"213.556962ms","start":"2026-04-23T04:44:54.993908Z","end":"2026-04-23T04:44:55.207465Z","steps":["trace[82131387] 'process raft request' (duration: 165.842888ms)","trace[82131387] 'compare' (duration: 47.564388ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:55.207527Z","caller":"traceutil/trace.go:172","msg":"trace[750158069] range","detail":"{range_begin:/registry/volumeattributesclasses; range_end:; response_count:0; response_revision:864; }","duration":"187.189661ms","start":"2026-04-23T04:44:55.020320Z","end":"2026-04-23T04:44:55.207509Z","steps":["trace[750158069] 'agreement among raft nodes before linearized reading' (duration: 139.328312ms)","trace[750158069] 'range keys from in-memory index tree' (duration: 47.687893ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:55.234835Z","caller":"traceutil/trace.go:172","msg":"trace[768015527] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"239.565496ms","start":"2026-04-23T04:44:54.995247Z","end":"2026-04-23T04:44:55.234812Z","steps":["trace[768015527] 'process raft request' (duration: 239.432661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:55.234926Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"213.779531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T04:44:55.234989Z","caller":"traceutil/trace.go:172","msg":"trace[1443041128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"213.855044ms","start":"2026-04-23T04:44:55.021118Z","end":"2026-04-23T04:44:55.234973Z","steps":["trace[1443041128] 'agreement among raft nodes before linearized reading' (duration: 213.575993ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:55.354418Z","caller":"traceutil/trace.go:172","msg":"trace[570129144] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"113.669781ms","start":"2026-04-23T04:44:55.240719Z","end":"2026-04-23T04:44:55.354389Z","steps":["trace[570129144] 'process raft request' (duration: 73.856464ms)","trace[570129144] 'compare' (duration: 39.617549ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T04:44:55.407252Z","caller":"traceutil/trace.go:172","msg":"trace[1779025288] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"165.195462ms","start":"2026-04-23T04:44:55.241773Z","end":"2026-04-23T04:44:55.406969Z","steps":["trace[1779025288] 'process raft request' (duration: 165.034726ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:55.814281Z","caller":"traceutil/trace.go:172","msg":"trace[1831564764] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:909; }","duration":"100.720536ms","start":"2026-04-23T04:44:55.713528Z","end":"2026-04-23T04:44:55.814249Z","steps":["trace[1831564764] 'read index received' (duration: 100.711376ms)","trace[1831564764] 'applied index is now lower than readState.Index' (duration: 7.76µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:55.814429Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.878032ms","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-23T04:44:55.814466Z","caller":"traceutil/trace.go:172","msg":"trace[1111578310] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:871; }","duration":"100.929834ms","start":"2026-04-23T04:44:55.713520Z","end":"2026-04-23T04:44:55.814450Z","steps":["trace[1111578310] 'agreement among raft nodes before linearized reading' (duration: 100.845351ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:55.814748Z","caller":"traceutil/trace.go:172","msg":"trace[2052805692] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"119.857917ms","start":"2026-04-23T04:44:55.694850Z","end":"2026-04-23T04:44:55.814708Z","steps":["trace[2052805692] 'process raft request' (duration: 119.524603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:56.970303Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310875742386104,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T04:44:57.149582Z","caller":"traceutil/trace.go:172","msg":"trace[16941195] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:910; }","duration":"679.919822ms","start":"2026-04-23T04:44:56.469636Z","end":"2026-04-23T04:44:57.149556Z","steps":["trace[16941195] 'read index received' (duration: 679.904811ms)","trace[16941195] 'applied index is now lower than readState.Index' (duration: 13.751µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:57.209641Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.14402655s","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":"info","ts":"2026-04-23T04:44:57.209723Z","caller":"traceutil/trace.go:172","msg":"trace[1949301164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:872; }","duration":"1.144101463s","start":"2026-04-23T04:44:56.065599Z","end":"2026-04-23T04:44:57.209701Z","steps":["trace[1949301164] 'range keys from in-memory index tree' (duration: 1.143819962s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:57.209732Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.178894595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:57.209782Z","caller":"traceutil/trace.go:172","msg":"trace[765874065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"1.178944677s","start":"2026-04-23T04:44:56.030825Z","end":"2026-04-23T04:44:57.209770Z","steps":["trace[765874065] 'range keys from in-memory index tree' (duration: 1.178825661s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:57.209789Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.065576Z","time spent":"1.144194797s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:57.209818Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.030803Z","time spent":"1.179003819s","remote":"127.0.0.1:60666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T04:44:57.210184Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"832.571889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:57.210230Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"740.59277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:57.210239Z","caller":"traceutil/trace.go:172","msg":"trace[1247857873] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:872; }","duration":"832.644412ms","start":"2026-04-23T04:44:56.377584Z","end":"2026-04-23T04:44:57.210228Z","steps":["trace[1247857873] 'range keys from in-memory index tree' (duration: 832.425383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:57.210272Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.377561Z","time spent":"832.704215ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:57.210272Z","caller":"traceutil/trace.go:172","msg":"trace[1966530683] range","detail":"{range_begin:/registry/services/specs; range_end:; response_count:0; response_revision:872; }","duration":"740.631311ms","start":"2026-04-23T04:44:56.469626Z","end":"2026-04-23T04:44:57.210257Z","steps":["trace[1966530683] 'agreement among raft nodes before linearized reading' (duration: 680.069788ms)","trace[1966530683] 'range keys from in-memory index tree' (duration: 60.49456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:57.210305Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.469607Z","time spent":"740.690914ms","remote":"127.0.0.1:32788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:57.210415Z","caller":"traceutil/trace.go:172","msg":"trace[1711488258] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"758.247401ms","start":"2026-04-23T04:44:56.452130Z","end":"2026-04-23T04:44:57.210377Z","steps":["trace[1711488258] 'process raft request' (duration: 697.583294ms)","trace[1711488258] 'compare' (duration: 59.944997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:44:57.210447Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"634.843919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:57.210511Z","caller":"traceutil/trace.go:172","msg":"trace[398870780] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices; range_end:; response_count:0; response_revision:873; }","duration":"634.900141ms","start":"2026-04-23T04:44:56.575586Z","end":"2026-04-23T04:44:57.210486Z","steps":["trace[398870780] 'agreement among raft nodes before linearized reading' (duration: 634.814218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:57.210532Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.452076Z","time spent":"758.388416ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:44:57.210619Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"522.703403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaimtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:44:57.210650Z","caller":"traceutil/trace.go:172","msg":"trace[142212491] range","detail":"{range_begin:/registry/resourceclaimtemplates; range_end:; response_count:0; response_revision:873; }","duration":"522.734855ms","start":"2026-04-23T04:44:56.687909Z","end":"2026-04-23T04:44:57.210643Z","steps":["trace[142212491] 'agreement among raft nodes before linearized reading' (duration: 522.685313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:57.210667Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.687879Z","time spent":"522.783487ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/resourceclaimtemplates\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:44:57.211004Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.088882836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true ","response":"range_response_count:100 size:8678"} {"level":"info","ts":"2026-04-23T04:44:57.211079Z","caller":"traceutil/trace.go:172","msg":"trace[1328365616] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:100; response_revision:872; }","duration":"1.08896529s","start":"2026-04-23T04:44:56.122099Z","end":"2026-04-23T04:44:57.211064Z","steps":["trace[1328365616] 'range keys from in-memory index tree' (duration: 1.08801715s)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:44:57.211127Z","caller":"traceutil/trace.go:172","msg":"trace[687072374] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"201.179666ms","start":"2026-04-23T04:44:57.009939Z","end":"2026-04-23T04:44:57.211119Z","steps":["trace[687072374] 'process raft request' (duration: 200.435475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:44:57.211158Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.122071Z","time spent":"1.089044213s","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":8701,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true "} {"level":"warn","ts":"2026-04-23T04:44:57.209073Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"496.145015ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T04:44:57.213905Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:44:56.575563Z","time spent":"638.278642ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices\" limit:1 "} {"level":"info","ts":"2026-04-23T04:44:57.213974Z","caller":"traceutil/trace.go:172","msg":"trace[1161406405] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:872; }","duration":"501.125041ms","start":"2026-04-23T04:44:56.712824Z","end":"2026-04-23T04:44:57.213949Z","steps":["trace[1161406405] 'range keys from in-memory index tree' (duration: 496.089773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:45:10.115914Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46388","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:45:10.128367Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46398","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-04-23T04:45:10.140506Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46414","server-name":"","error":"EOF"} {"level":"info","ts":"2026-04-23T04:45:29.120468Z","caller":"traceutil/trace.go:172","msg":"trace[55036083] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"105.867152ms","start":"2026-04-23T04:45:29.014581Z","end":"2026-04-23T04:45:29.120448Z","steps":["trace[55036083] 'process raft request' (duration: 105.719846ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:45:29.302603Z","caller":"traceutil/trace.go:172","msg":"trace[409866827] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"172.284555ms","start":"2026-04-23T04:45:29.130298Z","end":"2026-04-23T04:45:29.302583Z","steps":["trace[409866827] 'process raft request' (duration: 172.130259ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:46:16.242027Z","caller":"traceutil/trace.go:172","msg":"trace[1978837393] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1238; }","duration":"478.720747ms","start":"2026-04-23T04:46:15.762910Z","end":"2026-04-23T04:46:16.241630Z","steps":["trace[1978837393] 'read index received' (duration: 478.706857ms)","trace[1978837393] 'applied index is now lower than readState.Index' (duration: 12.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:46:16.242931Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"480.001234ms","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-23T04:46:16.243002Z","caller":"traceutil/trace.go:172","msg":"trace[785389777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"480.085588ms","start":"2026-04-23T04:46:15.762900Z","end":"2026-04-23T04:46:16.242986Z","steps":["trace[785389777] 'agreement among raft nodes before linearized reading' (duration: 478.875845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:16.243051Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:46:15.762891Z","time spent":"480.146061ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-04-23T04:46:16.243438Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"480.530257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:46:16.243491Z","caller":"traceutil/trace.go:172","msg":"trace[1186793586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"480.58307ms","start":"2026-04-23T04:46:15.762898Z","end":"2026-04-23T04:46:16.243481Z","steps":["trace[1186793586] 'agreement among raft nodes before linearized reading' (duration: 478.77273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:16.243516Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:46:15.762890Z","time spent":"480.619341ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T04:46:16.243688Z","caller":"traceutil/trace.go:172","msg":"trace[547360640] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"480.177852ms","start":"2026-04-23T04:46:15.763484Z","end":"2026-04-23T04:46:16.243662Z","steps":["trace[547360640] 'process raft request' (duration: 480.135691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:16.243794Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:46:15.763474Z","time spent":"480.260156ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T04:46:16.243814Z","caller":"traceutil/trace.go:172","msg":"trace[1907582758] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"482.428942ms","start":"2026-04-23T04:46:15.761374Z","end":"2026-04-23T04:46:16.243803Z","steps":["trace[1907582758] 'process raft request' (duration: 480.285417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:16.243874Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:46:15.761357Z","time spent":"482.483844ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:46:16.244061Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"468.989846ms","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-23T04:46:16.244089Z","caller":"traceutil/trace.go:172","msg":"trace[304654909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1187; }","duration":"469.015587ms","start":"2026-04-23T04:46:15.775064Z","end":"2026-04-23T04:46:16.244080Z","steps":["trace[304654909] 'agreement among raft nodes before linearized reading' (duration: 468.939924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:16.244112Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:46:15.775048Z","time spent":"469.056658ms","remote":"127.0.0.1:32912","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":"info","ts":"2026-04-23T04:46:16.244299Z","caller":"traceutil/trace.go:172","msg":"trace[1213249198] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"482.775976ms","start":"2026-04-23T04:46:15.761513Z","end":"2026-04-23T04:46:16.244289Z","steps":["trace[1213249198] 'process raft request' (duration: 482.013303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:16.244361Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-04-23T04:46:15.761492Z","time spent":"482.83054ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T04:46:16.244577Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"213.372371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T04:46:16.244626Z","caller":"traceutil/trace.go:172","msg":"trace[158942734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"213.420693ms","start":"2026-04-23T04:46:16.031191Z","end":"2026-04-23T04:46:16.244612Z","steps":["trace[158942734] 'agreement among raft nodes before linearized reading' (duration: 213.34437ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:46:18.425105Z","caller":"traceutil/trace.go:172","msg":"trace[504587511] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1246; }","duration":"171.33031ms","start":"2026-04-23T04:46:18.253749Z","end":"2026-04-23T04:46:18.425080Z","steps":["trace[504587511] 'read index received' (duration: 171.32092ms)","trace[504587511] 'applied index is now lower than readState.Index' (duration: 8.19µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T04:46:18.425414Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"171.649894ms","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:494"} {"level":"info","ts":"2026-04-23T04:46:18.425448Z","caller":"traceutil/trace.go:172","msg":"trace[285630664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"171.704726ms","start":"2026-04-23T04:46:18.253736Z","end":"2026-04-23T04:46:18.425441Z","steps":["trace[285630664] 'agreement among raft nodes before linearized reading' (duration: 171.501288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:18.425702Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.918926ms","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-23T04:46:18.425802Z","caller":"traceutil/trace.go:172","msg":"trace[1866073760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"170.01014ms","start":"2026-04-23T04:46:18.255754Z","end":"2026-04-23T04:46:18.425764Z","steps":["trace[1866073760] 'agreement among raft nodes before linearized reading' (duration: 169.818032ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:46:18.425826Z","caller":"traceutil/trace.go:172","msg":"trace[1601111570] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"173.632383ms","start":"2026-04-23T04:46:18.252164Z","end":"2026-04-23T04:46:18.425796Z","steps":["trace[1601111570] 'process raft request' (duration: 172.939172ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T04:46:18.425867Z","caller":"traceutil/trace.go:172","msg":"trace[1827980979] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"173.666235ms","start":"2026-04-23T04:46:18.252178Z","end":"2026-04-23T04:46:18.425844Z","steps":["trace[1827980979] 'process raft request' (duration: 173.234545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T04:46:18.425942Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"170.143258ms","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-23T04:46:18.425964Z","caller":"traceutil/trace.go:172","msg":"trace[1971201667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"170.165829ms","start":"2026-04-23T04:46:18.255793Z","end":"2026-04-23T04:46:18.425959Z","steps":["trace[1971201667] 'agreement among raft nodes before linearized reading' (duration: 170.091546ms)"],"step_count":1}