{"level":"warn","ts":"2026-06-30T17:08:19.404263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T17:08:19.404377Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-30T17:08:19.404475Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T17:08:19.404486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-30T17:08:19.404551Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-30T17:08:19.405498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-06-30T17:08:19.405653Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-30T17:08:19.408646Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.604757ms"} {"level":"info","ts":"2026-06-30T17:08:19.412958Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-06-30T17:08:19.413011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T17:08:19.413028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-06-30T17:08:19.413043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T17:08:19.41305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-06-30T17:08:19.4131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-06-30T17:08:19.42061Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T17:08:19.42329Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T17:08:19.425747Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-30T17:08:19.428777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T17:08:19.428893Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T17:08:19.42911Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-30T17:08:19.429177Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-30T17:08:19.429209Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-30T17:08:19.43036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-06-30T17:08:19.430534Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-30T17:08:19.433554Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-30T17:08:19.433634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-30T17:08:19.433679Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-30T17:08:19.433747Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T17:08:19.433781Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T17:08:19.514206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T17:08:19.514288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T17:08:19.51433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-06-30T17:08:19.514352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-06-30T17:08:19.514362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-30T17:08:19.514379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-06-30T17:08:19.514392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-30T17:08:19.526928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T17:08:19.526931Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:08:19.526962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:08:19.527027Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:08:19.527769Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:08:19.527831Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:08:19.527849Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:08:19.527941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T17:08:19.527958Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T17:08:19.530177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T17:08:19.530645Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"warn","ts":"2026-06-30T17:08:41.352848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.898021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-06-30T17:08:41.352978Z","caller":"traceutil/trace.go:171","msg":"trace[1679509445] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:426; }","duration":"129.040255ms","start":"2026-06-30T17:08:41.223914Z","end":"2026-06-30T17:08:41.352954Z","steps":["trace[1679509445] 'range keys from in-memory index tree' (duration: 128.684186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:47.196967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.225751ms","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-06-30T17:08:47.197115Z","caller":"traceutil/trace.go:171","msg":"trace[1388141651] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:469; }","duration":"172.385085ms","start":"2026-06-30T17:08:47.024713Z","end":"2026-06-30T17:08:47.197098Z","steps":["trace[1388141651] 'range keys from in-memory index tree' (duration: 172.207981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:55.825143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873774267750780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:08:56.230102Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047792775s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T17:08:56.230597Z","caller":"traceutil/trace.go:171","msg":"trace[143206231] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.04836489s","start":"2026-06-30T17:08:55.182209Z","end":"2026-06-30T17:08:56.230574Z","steps":["trace[143206231] 'process raft request' (duration: 1.048237077s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.231077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.182189Z","time spent":"1.048480994s","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.236357Z","caller":"traceutil/trace.go:171","msg":"trace[108369988] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.033735686s","start":"2026-06-30T17:08:55.202548Z","end":"2026-06-30T17:08:56.236283Z","steps":["trace[108369988] 'process raft request' (duration: 1.033409088s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.23663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.202542Z","time spent":"1.033937582s","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.236755Z","caller":"traceutil/trace.go:171","msg":"trace[1058108286] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.037818976s","start":"2026-06-30T17:08:55.198915Z","end":"2026-06-30T17:08:56.236734Z","steps":["trace[1058108286] 'process raft request' (duration: 1.036876901s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.236852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.198894Z","time spent":"1.03791126s","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.236939Z","caller":"traceutil/trace.go:171","msg":"trace[1888898427] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:539; }","duration":"912.147713ms","start":"2026-06-30T17:08:55.324721Z","end":"2026-06-30T17:08:56.236868Z","steps":["trace[1888898427] 'read index received' (duration: 905.71542ms)","trace[1888898427] 'applied index is now lower than readState.Index' (duration: 6.431123ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:08:56.237054Z","caller":"traceutil/trace.go:171","msg":"trace[2024754998] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.001334681s","start":"2026-06-30T17:08:55.235703Z","end":"2026-06-30T17:08:56.237038Z","steps":["trace[2024754998] 'process raft request' (duration: 1.000294793s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.237167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.235687Z","time spent":"1.001411413s","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237241Z","caller":"traceutil/trace.go:171","msg":"trace[2055996800] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.00091513s","start":"2026-06-30T17:08:55.236312Z","end":"2026-06-30T17:08:56.237227Z","steps":["trace[2055996800] 'process raft request' (duration: 999.725908ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:08:56.237255Z","caller":"traceutil/trace.go:171","msg":"trace[956200899] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.03798278s","start":"2026-06-30T17:08:55.198991Z","end":"2026-06-30T17:08:56.236974Z","steps":["trace[956200899] 'process raft request' (duration: 1.036917402s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.237315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.23628Z","time spent":"1.001003073s","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237341Z","caller":"traceutil/trace.go:171","msg":"trace[1166653020] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"967.364594ms","start":"2026-06-30T17:08:55.269961Z","end":"2026-06-30T17:08:56.237326Z","steps":["trace[1166653020] 'process raft request' (duration: 966.440978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.238447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.198984Z","time spent":"1.039423118s","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T17:08:56.238527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.269942Z","time spent":"968.373191ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:08:56.237354Z","caller":"traceutil/trace.go:171","msg":"trace[1193343628] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"994.144207ms","start":"2026-06-30T17:08:55.2432Z","end":"2026-06-30T17:08:56.237344Z","steps":["trace[1193343628] 'process raft request' (duration: 992.880433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.23872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.243184Z","time spent":"995.498024ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237389Z","caller":"traceutil/trace.go:171","msg":"trace[1658918446] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"994.109466ms","start":"2026-06-30T17:08:55.24327Z","end":"2026-06-30T17:08:56.23738Z","steps":["trace[1658918446] 'process raft request' (duration: 992.845122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.238983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.243257Z","time spent":"995.692478ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237389Z","caller":"traceutil/trace.go:171","msg":"trace[1227913172] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"965.648557ms","start":"2026-06-30T17:08:55.271727Z","end":"2026-06-30T17:08:56.237375Z","steps":["trace[1227913172] 'process raft request' (duration: 964.745433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.23917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.271708Z","time spent":"967.442616ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237418Z","caller":"traceutil/trace.go:171","msg":"trace[1055095040] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"982.01503ms","start":"2026-06-30T17:08:55.255395Z","end":"2026-06-30T17:08:56.23741Z","steps":["trace[1055095040] 'process raft request' (duration: 980.817688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.239266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.255375Z","time spent":"983.87543ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237431Z","caller":"traceutil/trace.go:171","msg":"trace[987668190] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"947.805805ms","start":"2026-06-30T17:08:55.289615Z","end":"2026-06-30T17:08:56.237421Z","steps":["trace[987668190] 'process raft request' (duration: 946.992004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.239479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.289603Z","time spent":"949.843651ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:08:56.237464Z","caller":"traceutil/trace.go:171","msg":"trace[1293759671] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"859.82747ms","start":"2026-06-30T17:08:55.377627Z","end":"2026-06-30T17:08:56.237455Z","steps":["trace[1293759671] 'process raft request' (duration: 859.122921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.239607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.377582Z","time spent":"861.993848ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T17:08:56.237668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.71154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T17:08:56.239804Z","caller":"traceutil/trace.go:171","msg":"trace[826494046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"506.896459ms","start":"2026-06-30T17:08:55.732894Z","end":"2026-06-30T17:08:56.239791Z","steps":["trace[826494046] 'agreement among raft nodes before linearized reading' (duration: 504.71389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.239853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.73288Z","time spent":"506.96108ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T17:08:56.237768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.030537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T17:08:56.239978Z","caller":"traceutil/trace.go:171","msg":"trace[1891135153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"915.280137ms","start":"2026-06-30T17:08:55.324687Z","end":"2026-06-30T17:08:56.239967Z","steps":["trace[1891135153] 'agreement among raft nodes before linearized reading' (duration: 912.79686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:08:56.24002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:08:55.324672Z","time spent":"915.33777ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-30T17:09:02.70846Z","caller":"traceutil/trace.go:171","msg":"trace[641432694] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"130.933906ms","start":"2026-06-30T17:09:02.577499Z","end":"2026-06-30T17:09:02.708433Z","steps":["trace[641432694] 'read index received' (duration: 130.146495ms)","trace[641432694] 'applied index is now lower than readState.Index' (duration: 786.411µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:02.708564Z","caller":"traceutil/trace.go:171","msg":"trace[941051936] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"132.800847ms","start":"2026-06-30T17:09:02.575719Z","end":"2026-06-30T17:09:02.70852Z","steps":["trace[941051936] 'process raft request' (duration: 132.205931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:02.708682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.162372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-30T17:09:02.708775Z","caller":"traceutil/trace.go:171","msg":"trace[1384862401] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:633; }","duration":"131.288585ms","start":"2026-06-30T17:09:02.577465Z","end":"2026-06-30T17:09:02.708754Z","steps":["trace[1384862401] 'agreement among raft nodes before linearized reading' (duration: 131.09138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:03.772629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.788216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:09:03.772831Z","caller":"traceutil/trace.go:171","msg":"trace[1024294228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"767.035193ms","start":"2026-06-30T17:09:03.005771Z","end":"2026-06-30T17:09:03.772806Z","steps":["trace[1024294228] 'range keys from in-memory index tree' (duration: 766.612472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:03.772895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:03.005755Z","time spent":"767.128605ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-30T17:09:04.727324Z","caller":"traceutil/trace.go:171","msg":"trace[770784443] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"328.348207ms","start":"2026-06-30T17:09:04.398948Z","end":"2026-06-30T17:09:04.727296Z","steps":["trace[770784443] 'process raft request' (duration: 328.189072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:04.727469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:04.398912Z","time spent":"328.483341ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:09:04.742083Z","caller":"traceutil/trace.go:171","msg":"trace[1962321829] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"181.644245ms","start":"2026-06-30T17:09:04.560416Z","end":"2026-06-30T17:09:04.74206Z","steps":["trace[1962321829] 'read index received' (duration: 166.735662ms)","trace[1962321829] 'applied index is now lower than readState.Index' (duration: 14.907533ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:04.742346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.917022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-30T17:09:04.742422Z","caller":"traceutil/trace.go:171","msg":"trace[1725469932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"182.037605ms","start":"2026-06-30T17:09:04.560371Z","end":"2026-06-30T17:09:04.742409Z","steps":["trace[1725469932] 'agreement among raft nodes before linearized reading' (duration: 181.83718ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:04.742659Z","caller":"traceutil/trace.go:171","msg":"trace[818996058] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"342.129889ms","start":"2026-06-30T17:09:04.400516Z","end":"2026-06-30T17:09:04.742646Z","steps":["trace[818996058] 'process raft request' (duration: 341.41965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:04.7428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:04.400496Z","time spent":"342.250283ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:09:05.231314Z","caller":"traceutil/trace.go:171","msg":"trace[1027717971] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"341.504782ms","start":"2026-06-30T17:09:04.889784Z","end":"2026-06-30T17:09:05.231288Z","steps":["trace[1027717971] 'read index received' (duration: 341.308956ms)","trace[1027717971] 'applied index is now lower than readState.Index' (duration: 194.626µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:05.231486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.686637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:05.231458Z","caller":"traceutil/trace.go:171","msg":"trace[48471926] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"484.882155ms","start":"2026-06-30T17:09:04.746548Z","end":"2026-06-30T17:09:05.23143Z","steps":["trace[48471926] 'process raft request' (duration: 484.553356ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:05.23153Z","caller":"traceutil/trace.go:171","msg":"trace[159428998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"341.785469ms","start":"2026-06-30T17:09:04.889734Z","end":"2026-06-30T17:09:05.23152Z","steps":["trace[159428998] 'agreement among raft nodes before linearized reading' (duration: 341.648086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:05.231572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:04.889715Z","time spent":"341.848642ms","remote":"127.0.0.1:32838","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-06-30T17:09:05.231648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:04.746528Z","time spent":"485.010568ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:09:07.014191Z","caller":"traceutil/trace.go:171","msg":"trace[261837984] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"241.256595ms","start":"2026-06-30T17:09:06.772906Z","end":"2026-06-30T17:09:07.014163Z","steps":["trace[261837984] 'process raft request' (duration: 210.483514ms)","trace[261837984] 'compare' (duration: 30.35341ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:07.654975Z","caller":"traceutil/trace.go:171","msg":"trace[375775434] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"832.84796ms","start":"2026-06-30T17:09:06.822106Z","end":"2026-06-30T17:09:07.654954Z","steps":["trace[375775434] 'process raft request' (duration: 832.674716ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:07.655036Z","caller":"traceutil/trace.go:171","msg":"trace[256424460] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:701; }","duration":"764.061055ms","start":"2026-06-30T17:09:06.890953Z","end":"2026-06-30T17:09:07.655014Z","steps":["trace[256424460] 'read index received' (duration: 92.393386ms)","trace[256424460] 'applied index is now lower than readState.Index' (duration: 671.666069ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:07.655093Z","caller":"traceutil/trace.go:171","msg":"trace[891466738] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"736.667994ms","start":"2026-06-30T17:09:06.918412Z","end":"2026-06-30T17:09:07.65508Z","steps":["trace[891466738] 'process raft request' (duration: 736.476698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.655104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:06.822083Z","time spent":"832.950704ms","remote":"127.0.0.1:33246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:09:07.655173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.198278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:09:07.655168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:06.918388Z","time spent":"736.751467ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:09:07.655198Z","caller":"traceutil/trace.go:171","msg":"trace[2103345816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"764.269969ms","start":"2026-06-30T17:09:06.890921Z","end":"2026-06-30T17:09:07.655191Z","steps":["trace[2103345816] 'agreement among raft nodes before linearized reading' (duration: 764.181747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.655224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:06.890905Z","time spent":"764.313301ms","remote":"127.0.0.1:32828","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-06-30T17:09:07.670285Z","caller":"traceutil/trace.go:171","msg":"trace[914486458] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"351.359409ms","start":"2026-06-30T17:09:07.318904Z","end":"2026-06-30T17:09:07.670263Z","steps":["trace[914486458] 'process raft request' (duration: 351.168524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.670379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:07.318887Z","time spent":"351.44436ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T17:09:07.670389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.605211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:07.670448Z","caller":"traceutil/trace.go:171","msg":"trace[524320465] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:682; }","duration":"152.710695ms","start":"2026-06-30T17:09:07.517722Z","end":"2026-06-30T17:09:07.670433Z","steps":["trace[524320465] 'agreement among raft nodes before linearized reading' (duration: 152.55782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.670647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.547245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-30T17:09:07.670795Z","caller":"traceutil/trace.go:171","msg":"trace[578437497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"434.72534ms","start":"2026-06-30T17:09:07.236047Z","end":"2026-06-30T17:09:07.670772Z","steps":["trace[578437497] 'agreement among raft nodes before linearized reading' (duration: 434.269857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.670963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:07.236027Z","time spent":"434.915805ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T17:09:07.670814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.138467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:07.671131Z","caller":"traceutil/trace.go:171","msg":"trace[190127645] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:682; }","duration":"553.521457ms","start":"2026-06-30T17:09:07.117535Z","end":"2026-06-30T17:09:07.671056Z","steps":["trace[190127645] 'agreement among raft nodes before linearized reading' (duration: 552.762356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.671441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:07.117514Z","time spent":"553.906378ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-06-30T17:09:07.776568Z","caller":"traceutil/trace.go:171","msg":"trace[1683516935] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"100.685189ms","start":"2026-06-30T17:09:07.675763Z","end":"2026-06-30T17:09:07.776449Z","steps":["trace[1683516935] 'process raft request' (duration: 100.438413ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:07.836067Z","caller":"traceutil/trace.go:171","msg":"trace[1127502264] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"107.181245ms","start":"2026-06-30T17:09:07.728862Z","end":"2026-06-30T17:09:07.836043Z","steps":["trace[1127502264] 'process raft request' (duration: 106.97789ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:07.836067Z","caller":"traceutil/trace.go:171","msg":"trace[40262339] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"107.089042ms","start":"2026-06-30T17:09:07.728954Z","end":"2026-06-30T17:09:07.836043Z","steps":["trace[40262339] 'process raft request' (duration: 107.00981ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:07.836271Z","caller":"traceutil/trace.go:171","msg":"trace[1420991781] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"105.867799ms","start":"2026-06-30T17:09:07.730385Z","end":"2026-06-30T17:09:07.836253Z","steps":["trace[1420991781] 'process raft request' (duration: 105.616973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:07.836539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.062205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rdpqc\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-06-30T17:09:07.836566Z","caller":"traceutil/trace.go:171","msg":"trace[1843044662] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rdpqc; range_end:; response_count:1; response_revision:687; }","duration":"106.130506ms","start":"2026-06-30T17:09:07.730428Z","end":"2026-06-30T17:09:07.836559Z","steps":["trace[1843044662] 'agreement among raft nodes before linearized reading' (duration: 106.017013ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:07.993238Z","caller":"traceutil/trace.go:171","msg":"trace[218890422] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"142.489368ms","start":"2026-06-30T17:09:07.850714Z","end":"2026-06-30T17:09:07.993203Z","steps":["trace[218890422] 'process raft request' (duration: 47.708518ms)","trace[218890422] 'compare' (duration: 94.548223ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:07.993237Z","caller":"traceutil/trace.go:171","msg":"trace[2106040450] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"103.445873ms","start":"2026-06-30T17:09:07.889693Z","end":"2026-06-30T17:09:07.993138Z","steps":["trace[2106040450] 'read index received' (duration: 8.656903ms)","trace[2106040450] 'applied index is now lower than readState.Index' (duration: 94.78784ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:07.993398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.69219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:07.993467Z","caller":"traceutil/trace.go:171","msg":"trace[553907920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"103.778483ms","start":"2026-06-30T17:09:07.889673Z","end":"2026-06-30T17:09:07.993452Z","steps":["trace[553907920] 'agreement among raft nodes before linearized reading' (duration: 103.65392ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:09.238146Z","caller":"traceutil/trace.go:171","msg":"trace[317841952] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"155.89511ms","start":"2026-06-30T17:09:09.08222Z","end":"2026-06-30T17:09:09.238115Z","steps":["trace[317841952] 'process raft request' (duration: 155.750796ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:09.245498Z","caller":"traceutil/trace.go:171","msg":"trace[344425591] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"124.961484ms","start":"2026-06-30T17:09:09.120514Z","end":"2026-06-30T17:09:09.245476Z","steps":["trace[344425591] 'process raft request' (duration: 124.843431ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:09.420648Z","caller":"traceutil/trace.go:171","msg":"trace[1222530514] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"164.421129ms","start":"2026-06-30T17:09:09.256192Z","end":"2026-06-30T17:09:09.420613Z","steps":["trace[1222530514] 'process raft request' (duration: 164.156003ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:09.482666Z","caller":"traceutil/trace.go:171","msg":"trace[1336232001] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"141.048509ms","start":"2026-06-30T17:09:09.341593Z","end":"2026-06-30T17:09:09.482641Z","steps":["trace[1336232001] 'process raft request' (duration: 140.894025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:10.276051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.041114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:09:10.277573Z","caller":"traceutil/trace.go:171","msg":"trace[1731460417] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"358.016828ms","start":"2026-06-30T17:09:09.919477Z","end":"2026-06-30T17:09:10.277494Z","steps":["trace[1731460417] 'process raft request' (duration: 39.919698ms)","trace[1731460417] 'compare' (duration: 315.772707ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:10.27774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:09.919459Z","time spent":"358.191804ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:09:10.456362Z","caller":"traceutil/trace.go:171","msg":"trace[669424815] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"338.374888ms","start":"2026-06-30T17:09:10.117959Z","end":"2026-06-30T17:09:10.456334Z","steps":["trace[669424815] 'read index received' (duration: 243.805334ms)","trace[669424815] 'applied index is now lower than readState.Index' (duration: 94.568524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:10.456599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.624874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-06-30T17:09:10.45664Z","caller":"traceutil/trace.go:171","msg":"trace[820716343] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"338.685905ms","start":"2026-06-30T17:09:10.117942Z","end":"2026-06-30T17:09:10.456628Z","steps":["trace[820716343] 'agreement among raft nodes before linearized reading' (duration: 338.527991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:10.456677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:10.117921Z","time spent":"338.746868ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-06-30T17:09:10.456965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.664942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-06-30T17:09:10.457134Z","caller":"traceutil/trace.go:171","msg":"trace[1454791900] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"531.571175ms","start":"2026-06-30T17:09:09.925536Z","end":"2026-06-30T17:09:10.457108Z","steps":["trace[1454791900] 'process raft request' (duration: 456.378864ms)","trace[1454791900] 'compare' (duration: 74.249605ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:10.457551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:09.925519Z","time spent":"531.880583ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:09:10.457694Z","caller":"traceutil/trace.go:171","msg":"trace[1751609584] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"139.825466ms","start":"2026-06-30T17:09:10.317222Z","end":"2026-06-30T17:09:10.457048Z","steps":["trace[1751609584] 'agreement among raft nodes before linearized reading' (duration: 139.324792ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:10.745283Z","caller":"traceutil/trace.go:171","msg":"trace[1932101629] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"212.937052ms","start":"2026-06-30T17:09:10.532325Z","end":"2026-06-30T17:09:10.745262Z","steps":["trace[1932101629] 'process raft request' (duration: 140.240538ms)","trace[1932101629] 'compare' (duration: 72.440086ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:10.745221Z","caller":"traceutil/trace.go:171","msg":"trace[1729711983] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"212.224281ms","start":"2026-06-30T17:09:10.532976Z","end":"2026-06-30T17:09:10.745201Z","steps":["trace[1729711983] 'read index received' (duration: 139.369304ms)","trace[1729711983] 'applied index is now lower than readState.Index' (duration: 72.853957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:10.745847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.848048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T17:09:10.745896Z","caller":"traceutil/trace.go:171","msg":"trace[1690188862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"212.942501ms","start":"2026-06-30T17:09:10.532942Z","end":"2026-06-30T17:09:10.745884Z","steps":["trace[1690188862] 'agreement among raft nodes before linearized reading' (duration: 212.738615ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:10.982175Z","caller":"traceutil/trace.go:171","msg":"trace[708591765] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:745; }","duration":"190.249337ms","start":"2026-06-30T17:09:10.791901Z","end":"2026-06-30T17:09:10.982151Z","steps":["trace[708591765] 'read index received' (duration: 51.384128ms)","trace[708591765] 'applied index is now lower than readState.Index' (duration: 138.864299ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:10.982355Z","caller":"traceutil/trace.go:171","msg":"trace[842891257] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"195.733535ms","start":"2026-06-30T17:09:10.786602Z","end":"2026-06-30T17:09:10.982336Z","steps":["trace[842891257] 'process raft request' (duration: 195.379826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:10.982444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.518345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T17:09:10.982508Z","caller":"traceutil/trace.go:171","msg":"trace[327168026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"190.623047ms","start":"2026-06-30T17:09:10.791869Z","end":"2026-06-30T17:09:10.982492Z","steps":["trace[327168026] 'agreement among raft nodes before linearized reading' (duration: 190.419202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:10.982634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.403488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T17:09:10.982945Z","caller":"traceutil/trace.go:171","msg":"trace[962829239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"131.737237ms","start":"2026-06-30T17:09:10.851188Z","end":"2026-06-30T17:09:10.982925Z","steps":["trace[962829239] 'agreement among raft nodes before linearized reading' (duration: 131.349116ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:11.194778Z","caller":"traceutil/trace.go:171","msg":"trace[893964991] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"206.013604ms","start":"2026-06-30T17:09:10.988735Z","end":"2026-06-30T17:09:11.194748Z","steps":["trace[893964991] 'process raft request' (duration: 148.758367ms)","trace[893964991] 'compare' (duration: 57.051951ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:11.815108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.56407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.815193Z","caller":"traceutil/trace.go:171","msg":"trace[242197369] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:728; }","duration":"259.721393ms","start":"2026-06-30T17:09:11.555453Z","end":"2026-06-30T17:09:11.815174Z","steps":["trace[242197369] 'count revisions from in-memory index tree' (duration: 259.488938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.815231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.64172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.815324Z","caller":"traceutil/trace.go:171","msg":"trace[1178626516] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:728; }","duration":"243.750693ms","start":"2026-06-30T17:09:11.571555Z","end":"2026-06-30T17:09:11.815305Z","steps":["trace[1178626516] 'range keys from in-memory index tree' (duration: 243.608369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.815652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.128822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.815684Z","caller":"traceutil/trace.go:171","msg":"trace[519336430] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:728; }","duration":"244.174954ms","start":"2026-06-30T17:09:11.571501Z","end":"2026-06-30T17:09:11.815676Z","steps":["trace[519336430] 'count revisions from in-memory index tree' (duration: 244.04974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.815668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.809527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:09:11.815684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.164738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.815732Z","caller":"traceutil/trace.go:171","msg":"trace[224243032] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:728; }","duration":"276.274221ms","start":"2026-06-30T17:09:11.539446Z","end":"2026-06-30T17:09:11.81572Z","steps":["trace[224243032] 'range keys from in-memory index tree' (duration: 275.928932ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:11.81573Z","caller":"traceutil/trace.go:171","msg":"trace[1994630121] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:728; }","duration":"275.915962ms","start":"2026-06-30T17:09:11.5398Z","end":"2026-06-30T17:09:11.815716Z","steps":["trace[1994630121] 'count revisions from in-memory index tree' (duration: 275.769856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.815787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.108137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.815855Z","caller":"traceutil/trace.go:171","msg":"trace[735169648] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:728; }","duration":"276.19536ms","start":"2026-06-30T17:09:11.539647Z","end":"2026-06-30T17:09:11.815842Z","steps":["trace[735169648] 'range keys from in-memory index tree' (duration: 276.012084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.815969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.538955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.816017Z","caller":"traceutil/trace.go:171","msg":"trace[1982230663] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:728; }","duration":"260.593418ms","start":"2026-06-30T17:09:11.555406Z","end":"2026-06-30T17:09:11.816Z","steps":["trace[1982230663] 'range keys from in-memory index tree' (duration: 260.506285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.81596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.560096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.816244Z","caller":"traceutil/trace.go:171","msg":"trace[1715431892] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:728; }","duration":"260.897545ms","start":"2026-06-30T17:09:11.555325Z","end":"2026-06-30T17:09:11.816223Z","steps":["trace[1715431892] 'range keys from in-memory index tree' (duration: 260.412823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:11.815766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.662411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:11.816934Z","caller":"traceutil/trace.go:171","msg":"trace[1926845768] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:728; }","duration":"244.863602ms","start":"2026-06-30T17:09:11.572052Z","end":"2026-06-30T17:09:11.816915Z","steps":["trace[1926845768] 'range keys from in-memory index tree' (duration: 243.502795ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:12.191973Z","caller":"traceutil/trace.go:171","msg":"trace[106761633] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"236.653801ms","start":"2026-06-30T17:09:11.955289Z","end":"2026-06-30T17:09:12.191943Z","steps":["trace[106761633] 'process raft request' (duration: 236.494027ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:12.192049Z","caller":"traceutil/trace.go:171","msg":"trace[803396479] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"187.554675ms","start":"2026-06-30T17:09:12.004474Z","end":"2026-06-30T17:09:12.192029Z","steps":["trace[803396479] 'read index received' (duration: 187.301049ms)","trace[803396479] 'applied index is now lower than readState.Index' (duration: 248.516µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:12.192265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.76393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:12.192342Z","caller":"traceutil/trace.go:171","msg":"trace[337078246] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:732; }","duration":"187.894115ms","start":"2026-06-30T17:09:12.004431Z","end":"2026-06-30T17:09:12.192325Z","steps":["trace[337078246] 'agreement among raft nodes before linearized reading' (duration: 187.74256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:12.192329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.830972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:12.19241Z","caller":"traceutil/trace.go:171","msg":"trace[319839667] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:732; }","duration":"187.951226ms","start":"2026-06-30T17:09:12.004445Z","end":"2026-06-30T17:09:12.192397Z","steps":["trace[319839667] 'agreement among raft nodes before linearized reading' (duration: 187.672858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:12.199845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.607896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:12.199878Z","caller":"traceutil/trace.go:171","msg":"trace[2087490254] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:734; }","duration":"194.665857ms","start":"2026-06-30T17:09:12.005204Z","end":"2026-06-30T17:09:12.19987Z","steps":["trace[2087490254] 'agreement among raft nodes before linearized reading' (duration: 194.603685ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:16.849022Z","caller":"traceutil/trace.go:171","msg":"trace[1913240705] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"107.944775ms","start":"2026-06-30T17:09:16.741055Z","end":"2026-06-30T17:09:16.849Z","steps":["trace[1913240705] 'process raft request' (duration: 107.38093ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:18.177937Z","caller":"traceutil/trace.go:171","msg":"trace[1457704219] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"177.477722ms","start":"2026-06-30T17:09:18.00044Z","end":"2026-06-30T17:09:18.177918Z","steps":["trace[1457704219] 'read index received' (duration: 177.37027ms)","trace[1457704219] 'applied index is now lower than readState.Index' (duration: 106.682µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:18.178206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.715469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:09:18.178338Z","caller":"traceutil/trace.go:171","msg":"trace[1061113472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"177.873683ms","start":"2026-06-30T17:09:18.000396Z","end":"2026-06-30T17:09:18.17827Z","steps":["trace[1061113472] 'agreement among raft nodes before linearized reading' (duration: 177.601885ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:18.178502Z","caller":"traceutil/trace.go:171","msg":"trace[1595506759] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"178.106599ms","start":"2026-06-30T17:09:18.000381Z","end":"2026-06-30T17:09:18.178487Z","steps":["trace[1595506759] 'process raft request' (duration: 177.39055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:18.412232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.226308ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-30T17:09:18.412597Z","caller":"traceutil/trace.go:171","msg":"trace[1883443251] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"231.245304ms","start":"2026-06-30T17:09:18.181334Z","end":"2026-06-30T17:09:18.412579Z","steps":["trace[1883443251] 'process raft request' (duration: 231.041329ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:18.412966Z","caller":"traceutil/trace.go:171","msg":"trace[1973408423] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"119.190238ms","start":"2026-06-30T17:09:18.293738Z","end":"2026-06-30T17:09:18.412929Z","steps":["trace[1973408423] 'process raft request' (duration: 118.781937ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:18.822206Z","caller":"traceutil/trace.go:171","msg":"trace[1930108441] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"115.383245ms","start":"2026-06-30T17:09:18.706799Z","end":"2026-06-30T17:09:18.822183Z","steps":["trace[1930108441] 'process raft request' (duration: 115.233931ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:18.963069Z","caller":"traceutil/trace.go:171","msg":"trace[861170033] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"134.755999ms","start":"2026-06-30T17:09:18.828282Z","end":"2026-06-30T17:09:18.963038Z","steps":["trace[861170033] 'process raft request' (duration: 134.548183ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:19.017595Z","caller":"traceutil/trace.go:171","msg":"trace[501892602] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"155.954182ms","start":"2026-06-30T17:09:18.861618Z","end":"2026-06-30T17:09:19.017572Z","steps":["trace[501892602] 'read index received' (duration: 101.246714ms)","trace[501892602] 'applied index is now lower than readState.Index' (duration: 54.706738ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:19.017702Z","caller":"traceutil/trace.go:171","msg":"trace[969042646] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"186.559228ms","start":"2026-06-30T17:09:18.831098Z","end":"2026-06-30T17:09:19.017657Z","steps":["trace[969042646] 'process raft request' (duration: 186.328282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:19.017913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.25677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T17:09:19.018027Z","caller":"traceutil/trace.go:171","msg":"trace[1046180215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"156.416713ms","start":"2026-06-30T17:09:18.861586Z","end":"2026-06-30T17:09:19.018003Z","steps":["trace[1046180215] 'agreement among raft nodes before linearized reading' (duration: 156.099925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:19.018143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.466166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:19.018449Z","caller":"traceutil/trace.go:171","msg":"trace[1503266731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"119.871777ms","start":"2026-06-30T17:09:18.898553Z","end":"2026-06-30T17:09:19.018425Z","steps":["trace[1503266731] 'agreement among raft nodes before linearized reading' (duration: 119.398754ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:21.237739Z","caller":"traceutil/trace.go:171","msg":"trace[308982020] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"197.73147ms","start":"2026-06-30T17:09:21.039982Z","end":"2026-06-30T17:09:21.237713Z","steps":["trace[308982020] 'process raft request' (duration: 197.040951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:23.967376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.39536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9gv77\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-30T17:09:23.967457Z","caller":"traceutil/trace.go:171","msg":"trace[1085570949] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9gv77; range_end:; response_count:1; response_revision:810; }","duration":"144.506693ms","start":"2026-06-30T17:09:23.82293Z","end":"2026-06-30T17:09:23.967437Z","steps":["trace[1085570949] 'range keys from in-memory index tree' (duration: 144.257757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:24.700222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873774267751385,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T17:09:24.852754Z","caller":"traceutil/trace.go:171","msg":"trace[927243829] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"653.089997ms","start":"2026-06-30T17:09:24.199632Z","end":"2026-06-30T17:09:24.852722Z","steps":["trace[927243829] 'read index received' (duration: 652.970664ms)","trace[927243829] 'applied index is now lower than readState.Index' (duration: 118.573µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:24.852915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.274712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-30T17:09:24.852943Z","caller":"traceutil/trace.go:171","msg":"trace[1052211865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"653.361095ms","start":"2026-06-30T17:09:24.199575Z","end":"2026-06-30T17:09:24.852936Z","steps":["trace[1052211865] 'agreement among raft nodes before linearized reading' (duration: 653.247301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:24.853019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:24.199552Z","time spent":"653.459847ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T17:09:24.853435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.592747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:09:24.853478Z","caller":"traceutil/trace.go:171","msg":"trace[1792445309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"410.665359ms","start":"2026-06-30T17:09:24.442805Z","end":"2026-06-30T17:09:24.85347Z","steps":["trace[1792445309] 'agreement among raft nodes before linearized reading' (duration: 410.083034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:24.853497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:24.44279Z","time spent":"410.702451ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-30T17:09:24.852854Z","caller":"traceutil/trace.go:171","msg":"trace[1536014349] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"877.510407ms","start":"2026-06-30T17:09:23.975312Z","end":"2026-06-30T17:09:24.852822Z","steps":["trace[1536014349] 'process raft request' (duration: 877.304631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:24.85458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:23.975261Z","time spent":"879.250124ms","remote":"127.0.0.1:33028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:09:29.050045Z","caller":"traceutil/trace.go:171","msg":"trace[1901768857] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"141.907403ms","start":"2026-06-30T17:09:28.908117Z","end":"2026-06-30T17:09:29.050024Z","steps":["trace[1901768857] 'process raft request' (duration: 141.78236ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:29.054643Z","caller":"traceutil/trace.go:171","msg":"trace[1352864709] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"146.492535ms","start":"2026-06-30T17:09:28.908127Z","end":"2026-06-30T17:09:29.054619Z","steps":["trace[1352864709] 'process raft request' (duration: 146.26617ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:29.054777Z","caller":"traceutil/trace.go:171","msg":"trace[999164357] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"146.539517ms","start":"2026-06-30T17:09:28.908225Z","end":"2026-06-30T17:09:29.054764Z","steps":["trace[999164357] 'process raft request' (duration: 146.307151ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:29.054886Z","caller":"traceutil/trace.go:171","msg":"trace[1054631595] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:894; }","duration":"117.666328ms","start":"2026-06-30T17:09:28.937197Z","end":"2026-06-30T17:09:29.054864Z","steps":["trace[1054631595] 'read index received' (duration: 112.706714ms)","trace[1054631595] 'applied index is now lower than readState.Index' (duration: 4.957824ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:09:29.054929Z","caller":"traceutil/trace.go:171","msg":"trace[1135725425] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"145.907931ms","start":"2026-06-30T17:09:28.909012Z","end":"2026-06-30T17:09:29.05492Z","steps":["trace[1135725425] 'process raft request' (duration: 145.559331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:09:29.055003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.785881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:09:29.055025Z","caller":"traceutil/trace.go:171","msg":"trace[1293827552] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:0; response_revision:868; }","duration":"117.852044ms","start":"2026-06-30T17:09:28.937164Z","end":"2026-06-30T17:09:29.055016Z","steps":["trace[1293827552] 'agreement among raft nodes before linearized reading' (duration: 117.787462ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:37.60876Z","caller":"traceutil/trace.go:171","msg":"trace[936202491] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"105.321365ms","start":"2026-06-30T17:09:37.503413Z","end":"2026-06-30T17:09:37.608735Z","steps":["trace[936202491] 'process raft request' (duration: 105.110149ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:38.431744Z","caller":"traceutil/trace.go:171","msg":"trace[1453591811] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"105.619273ms","start":"2026-06-30T17:09:38.326108Z","end":"2026-06-30T17:09:38.431728Z","steps":["trace[1453591811] 'read index received' (duration: 99.407395ms)","trace[1453591811] 'applied index is now lower than readState.Index' (duration: 6.210748ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:09:38.431905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.778698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-dgck2\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-06-30T17:09:38.431955Z","caller":"traceutil/trace.go:171","msg":"trace[1091132461] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-dgck2; range_end:; response_count:1; response_revision:943; }","duration":"105.87406ms","start":"2026-06-30T17:09:38.326066Z","end":"2026-06-30T17:09:38.43194Z","steps":["trace[1091132461] 'agreement among raft nodes before linearized reading' (duration: 105.725595ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:09:38.566307Z","caller":"traceutil/trace.go:171","msg":"trace[1364257666] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"127.325927ms","start":"2026-06-30T17:09:38.438959Z","end":"2026-06-30T17:09:38.566285Z","steps":["trace[1364257666] 'process raft request' (duration: 127.182734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.269042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.139855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:00.269131Z","caller":"traceutil/trace.go:171","msg":"trace[1355449871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"379.250038ms","start":"2026-06-30T17:09:59.889861Z","end":"2026-06-30T17:10:00.269111Z","steps":["trace[1355449871] 'range keys from in-memory index tree' (duration: 379.078693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.269177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:59.889846Z","time spent":"379.319739ms","remote":"127.0.0.1:32838","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-06-30T17:10:00.269247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.315443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:00.269303Z","caller":"traceutil/trace.go:171","msg":"trace[1544849147] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1026; }","duration":"628.855584ms","start":"2026-06-30T17:09:59.640434Z","end":"2026-06-30T17:10:00.26929Z","steps":["trace[1544849147] 'count revisions from in-memory index tree' (duration: 627.260811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.269344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:59.640423Z","time spent":"628.900775ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:10:00.26955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.146039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T17:10:00.26957Z","caller":"traceutil/trace.go:171","msg":"trace[253453276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"636.20074ms","start":"2026-06-30T17:09:59.633362Z","end":"2026-06-30T17:10:00.269563Z","steps":["trace[253453276] 'range keys from in-memory index tree' (duration: 636.010766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.269591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:59.633342Z","time spent":"636.243212ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T17:10:00.269638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.872058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-30T17:10:00.269863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.239761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T17:10:00.269893Z","caller":"traceutil/trace.go:171","msg":"trace[1336561993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"468.313803ms","start":"2026-06-30T17:09:59.801573Z","end":"2026-06-30T17:10:00.269887Z","steps":["trace[1336561993] 'range keys from in-memory index tree' (duration: 468.082937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.269916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:59.801552Z","time spent":"468.360065ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-30T17:10:00.270286Z","caller":"traceutil/trace.go:171","msg":"trace[84833912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"528.548487ms","start":"2026-06-30T17:09:59.741716Z","end":"2026-06-30T17:10:00.270264Z","steps":["trace[84833912] 'range keys from in-memory index tree' (duration: 526.250415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.270341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:09:59.7417Z","time spent":"528.628849ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T17:10:00.901427Z","caller":"traceutil/trace.go:171","msg":"trace[1023558459] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"626.191333ms","start":"2026-06-30T17:10:00.275208Z","end":"2026-06-30T17:10:00.9014Z","steps":["trace[1023558459] 'process raft request' (duration: 626.045639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:00.90154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:00.275192Z","time spent":"626.292486ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:01.445411Z","caller":"traceutil/trace.go:171","msg":"trace[1206931941] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1063; }","duration":"745.114298ms","start":"2026-06-30T17:10:00.700274Z","end":"2026-06-30T17:10:01.445388Z","steps":["trace[1206931941] 'read index received' (duration: 201.064776ms)","trace[1206931941] 'applied index is now lower than readState.Index' (duration: 544.048612ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:10:01.445523Z","caller":"traceutil/trace.go:171","msg":"trace[818304579] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.163578546s","start":"2026-06-30T17:10:00.281933Z","end":"2026-06-30T17:10:01.445512Z","steps":["trace[818304579] 'process raft request' (duration: 1.163407201s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.445623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:00.281926Z","time spent":"1.163628537s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:10:01.445719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.199151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:10:01.445781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.494809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:01.445846Z","caller":"traceutil/trace.go:171","msg":"trace[907202778] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1029; }","duration":"745.607961ms","start":"2026-06-30T17:10:00.700223Z","end":"2026-06-30T17:10:01.445831Z","steps":["trace[907202778] 'agreement among raft nodes before linearized reading' (duration: 745.397986ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:01.445796Z","caller":"traceutil/trace.go:171","msg":"trace[334045281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"555.309354ms","start":"2026-06-30T17:10:00.890465Z","end":"2026-06-30T17:10:01.445775Z","steps":["trace[334045281] 'agreement among raft nodes before linearized reading' (duration: 555.171321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.445879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:00.700203Z","time spent":"745.668752ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:10:01.445926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.401058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-30T17:10:01.44591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:00.890421Z","time spent":"555.470338ms","remote":"127.0.0.1:32838","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-06-30T17:10:01.445964Z","caller":"traceutil/trace.go:171","msg":"trace[1674299813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"141.449918ms","start":"2026-06-30T17:10:01.304499Z","end":"2026-06-30T17:10:01.445949Z","steps":["trace[1674299813] 'agreement among raft nodes before linearized reading' (duration: 141.365486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.445823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.818048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-30T17:10:01.446031Z","caller":"traceutil/trace.go:171","msg":"trace[1471790406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"170.073665ms","start":"2026-06-30T17:10:01.27595Z","end":"2026-06-30T17:10:01.446024Z","steps":["trace[1471790406] 'agreement among raft nodes before linearized reading' (duration: 169.714866ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:01.446541Z","caller":"traceutil/trace.go:171","msg":"trace[372432266] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.170163894s","start":"2026-06-30T17:10:00.275337Z","end":"2026-06-30T17:10:01.445501Z","steps":["trace[372432266] 'process raft request' (duration: 1.169793774s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.446689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:00.275307Z","time spent":"1.171300273s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:10:01.446769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.279767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:10:01.44687Z","caller":"traceutil/trace.go:171","msg":"trace[1287877144] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1029; }","duration":"339.400141ms","start":"2026-06-30T17:10:01.107408Z","end":"2026-06-30T17:10:01.446808Z","steps":["trace[1287877144] 'agreement among raft nodes before linearized reading' (duration: 338.414974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.446926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:01.107389Z","time spent":"339.521104ms","remote":"127.0.0.1:33292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:10:01.786188Z","caller":"traceutil/trace.go:171","msg":"trace[1916166979] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"335.221089ms","start":"2026-06-30T17:10:01.450912Z","end":"2026-06-30T17:10:01.786133Z","steps":["trace[1916166979] 'process raft request' (duration: 335.009824ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:01.786292Z","caller":"traceutil/trace.go:171","msg":"trace[380138311] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"335.860405ms","start":"2026-06-30T17:10:01.450417Z","end":"2026-06-30T17:10:01.786278Z","steps":["trace[380138311] 'process raft request' (duration: 327.151672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.786354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:01.450401Z","time spent":"335.916587ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:01.78626Z","caller":"traceutil/trace.go:171","msg":"trace[1943890320] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"330.753559ms","start":"2026-06-30T17:10:01.455474Z","end":"2026-06-30T17:10:01.786228Z","steps":["trace[1943890320] 'process raft request' (duration: 330.482412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:01.786671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:01.455453Z","time spent":"331.065047ms","remote":"127.0.0.1:33108","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-06-30T17:10:01.787009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:01.450893Z","time spent":"335.399763ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:11.98783Z","caller":"traceutil/trace.go:171","msg":"trace[682420167] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"135.53599ms","start":"2026-06-30T17:10:11.85226Z","end":"2026-06-30T17:10:11.987796Z","steps":["trace[682420167] 'process raft request' (duration: 135.350146ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:12.122902Z","caller":"traceutil/trace.go:171","msg":"trace[1485578783] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1102; }","duration":"233.618587ms","start":"2026-06-30T17:10:11.889254Z","end":"2026-06-30T17:10:12.122873Z","steps":["trace[1485578783] 'read index received' (duration: 98.316074ms)","trace[1485578783] 'applied index is now lower than readState.Index' (duration: 135.301453ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:10:12.12298Z","caller":"traceutil/trace.go:171","msg":"trace[1685101175] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"270.168686ms","start":"2026-06-30T17:10:11.852784Z","end":"2026-06-30T17:10:12.122952Z","steps":["trace[1685101175] 'process raft request' (duration: 268.07743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:12.123035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.768232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:12.123079Z","caller":"traceutil/trace.go:171","msg":"trace[1959025892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"233.861174ms","start":"2026-06-30T17:10:11.889202Z","end":"2026-06-30T17:10:12.123064Z","steps":["trace[1959025892] 'agreement among raft nodes before linearized reading' (duration: 233.775582ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:22.344492Z","caller":"traceutil/trace.go:171","msg":"trace[1203779187] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"174.562095ms","start":"2026-06-30T17:10:22.16991Z","end":"2026-06-30T17:10:22.344472Z","steps":["trace[1203779187] 'process raft request' (duration: 174.429971ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:28.168679Z","caller":"traceutil/trace.go:171","msg":"trace[804794795] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"116.101039ms","start":"2026-06-30T17:10:28.052552Z","end":"2026-06-30T17:10:28.168653Z","steps":["trace[804794795] 'process raft request' (duration: 116.004367ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:29.239935Z","caller":"traceutil/trace.go:171","msg":"trace[915089561] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"135.903171ms","start":"2026-06-30T17:10:29.10401Z","end":"2026-06-30T17:10:29.239913Z","steps":["trace[915089561] 'process raft request' (duration: 135.776267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:30.268258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.776553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T17:10:30.268331Z","caller":"traceutil/trace.go:171","msg":"trace[2037433899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1120; }","duration":"665.870836ms","start":"2026-06-30T17:10:29.602443Z","end":"2026-06-30T17:10:30.268314Z","steps":["trace[2037433899] 'range keys from in-memory index tree' (duration: 665.65241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:30.268312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.931186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-30T17:10:30.268371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:29.602438Z","time spent":"665.925507ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T17:10:30.268385Z","caller":"traceutil/trace.go:171","msg":"trace[576637615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1120; }","duration":"666.066201ms","start":"2026-06-30T17:10:29.602302Z","end":"2026-06-30T17:10:30.268368Z","steps":["trace[576637615] 'range keys from in-memory index tree' (duration: 665.654489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:30.268425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:29.602269Z","time spent":"666.146814ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-30T17:10:30.268592Z","caller":"traceutil/trace.go:171","msg":"trace[935528827] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"353.440328ms","start":"2026-06-30T17:10:29.915141Z","end":"2026-06-30T17:10:30.268582Z","steps":["trace[935528827] 'process raft request' (duration: 352.225045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:30.268671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:29.91512Z","time spent":"353.495099ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:31.859263Z","caller":"traceutil/trace.go:171","msg":"trace[871509061] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"611.759806ms","start":"2026-06-30T17:10:31.247478Z","end":"2026-06-30T17:10:31.859237Z","steps":["trace[871509061] 'process raft request' (duration: 611.608382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:31.85951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:31.247461Z","time spent":"611.965341ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:32.502447Z","caller":"traceutil/trace.go:171","msg":"trace[789966105] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"224.912205ms","start":"2026-06-30T17:10:32.277507Z","end":"2026-06-30T17:10:32.502419Z","steps":["trace[789966105] 'process raft request' (duration: 224.670638ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:32.502351Z","caller":"traceutil/trace.go:171","msg":"trace[2021979991] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"218.213066ms","start":"2026-06-30T17:10:32.284119Z","end":"2026-06-30T17:10:32.502333Z","steps":["trace[2021979991] 'read index received' (duration: 218.077781ms)","trace[2021979991] 'applied index is now lower than readState.Index' (duration: 134.325µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:10:32.502586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.457552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T17:10:32.502621Z","caller":"traceutil/trace.go:171","msg":"trace[1621411377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"218.535074ms","start":"2026-06-30T17:10:32.284077Z","end":"2026-06-30T17:10:32.502613Z","steps":["trace[1621411377] 'agreement among raft nodes before linearized reading' (duration: 218.413171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:32.502692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.064351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T17:10:32.502718Z","caller":"traceutil/trace.go:171","msg":"trace[25978213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"212.150032ms","start":"2026-06-30T17:10:32.29056Z","end":"2026-06-30T17:10:32.50271Z","steps":["trace[25978213] 'agreement among raft nodes before linearized reading' (duration: 212.08176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:32.50292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.106262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-30T17:10:32.502992Z","caller":"traceutil/trace.go:171","msg":"trace[1124736043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"212.227745ms","start":"2026-06-30T17:10:32.290747Z","end":"2026-06-30T17:10:32.502975Z","steps":["trace[1124736043] 'agreement among raft nodes before linearized reading' (duration: 211.998749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:33.219592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.933313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:33.219696Z","caller":"traceutil/trace.go:171","msg":"trace[1673846559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"328.095557ms","start":"2026-06-30T17:10:32.891579Z","end":"2026-06-30T17:10:33.219674Z","steps":["trace[1673846559] 'range keys from in-memory index tree' (duration: 327.82835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:33.219742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:32.89156Z","time spent":"328.171359ms","remote":"127.0.0.1:32838","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-06-30T17:10:34.186556Z","caller":"traceutil/trace.go:171","msg":"trace[2037060959] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"323.186906ms","start":"2026-06-30T17:10:33.863343Z","end":"2026-06-30T17:10:34.18653Z","steps":["trace[2037060959] 'read index received' (duration: 322.915989ms)","trace[2037060959] 'applied index is now lower than readState.Index' (duration: 269.607µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:10:34.186646Z","caller":"traceutil/trace.go:171","msg":"trace[1243766563] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"418.622193ms","start":"2026-06-30T17:10:33.767998Z","end":"2026-06-30T17:10:34.18662Z","steps":["trace[1243766563] 'process raft request' (duration: 418.355096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:34.186816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.452204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-30T17:10:34.187159Z","caller":"traceutil/trace.go:171","msg":"trace[985463619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1133; }","duration":"323.850764ms","start":"2026-06-30T17:10:33.863292Z","end":"2026-06-30T17:10:34.187143Z","steps":["trace[985463619] 'agreement among raft nodes before linearized reading' (duration: 323.36437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:34.187214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:33.86327Z","time spent":"323.932056ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T17:10:34.187396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:33.767959Z","time spent":"419.057825ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:48.450415Z","caller":"traceutil/trace.go:171","msg":"trace[380071038] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"165.780786ms","start":"2026-06-30T17:10:48.284609Z","end":"2026-06-30T17:10:48.45039Z","steps":["trace[380071038] 'process raft request' (duration: 165.612332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:48.987439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.33924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:10:48.987571Z","caller":"traceutil/trace.go:171","msg":"trace[1628555326] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1280; }","duration":"221.19ms","start":"2026-06-30T17:10:48.766368Z","end":"2026-06-30T17:10:48.987558Z","steps":["trace[1628555326] 'read index received' (duration: 220.865721ms)","trace[1628555326] 'applied index is now lower than readState.Index' (duration: 323.399µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:10:48.987771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.951328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-30T17:10:48.987797Z","caller":"traceutil/trace.go:171","msg":"trace[733804579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"213.994508ms","start":"2026-06-30T17:10:48.773787Z","end":"2026-06-30T17:10:48.987782Z","steps":["trace[733804579] 'agreement among raft nodes before linearized reading' (duration: 213.915216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:48.987792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.417055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T17:10:48.987862Z","caller":"traceutil/trace.go:171","msg":"trace[1430964397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"221.60329ms","start":"2026-06-30T17:10:48.766244Z","end":"2026-06-30T17:10:48.987847Z","steps":["trace[1430964397] 'agreement among raft nodes before linearized reading' (duration: 221.417295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:48.988028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.064063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:10:48.988047Z","caller":"traceutil/trace.go:171","msg":"trace[781088563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"213.147715ms","start":"2026-06-30T17:10:48.774894Z","end":"2026-06-30T17:10:48.988042Z","steps":["trace[781088563] 'agreement among raft nodes before linearized reading' (duration: 213.100674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:48.988108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.471433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T17:10:48.988128Z","caller":"traceutil/trace.go:171","msg":"trace[529796876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"213.505944ms","start":"2026-06-30T17:10:48.774617Z","end":"2026-06-30T17:10:48.988123Z","steps":["trace[529796876] 'agreement among raft nodes before linearized reading' (duration: 213.462573ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:48.98817Z","caller":"traceutil/trace.go:171","msg":"trace[318507548] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"703.11645ms","start":"2026-06-30T17:10:48.285042Z","end":"2026-06-30T17:10:48.988159Z","steps":["trace[318507548] 'process raft request' (duration: 301.999808ms)","trace[318507548] 'compare' (duration: 400.254908ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:10:48.98825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:48.285021Z","time spent":"703.173761ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:10:48.988356Z","caller":"traceutil/trace.go:171","msg":"trace[1959151947] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"284.225345ms","start":"2026-06-30T17:10:48.704123Z","end":"2026-06-30T17:10:48.988348Z","steps":["trace[1959151947] 'process raft request' (duration: 283.399613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:51.497693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873774267752362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:10:51.998453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873774267752362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:10:52.026762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.134806504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:52.026872Z","caller":"traceutil/trace.go:171","msg":"trace[1942489396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"2.134932498s","start":"2026-06-30T17:10:49.891895Z","end":"2026-06-30T17:10:52.026827Z","steps":["trace[1942489396] 'range keys from in-memory index tree' (duration: 2.13468441s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.026914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:49.891844Z","time spent":"2.135061181s","remote":"127.0.0.1:32838","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-06-30T17:10:52.027009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842990147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:10:52.027029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810098883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:52.027069Z","caller":"traceutil/trace.go:171","msg":"trace[1443845542] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1237; }","duration":"1.843103169s","start":"2026-06-30T17:10:50.183956Z","end":"2026-06-30T17:10:52.027059Z","steps":["trace[1443845542] 'count revisions from in-memory index tree' (duration: 1.842870674s)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:52.027079Z","caller":"traceutil/trace.go:171","msg":"trace[351967257] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1237; }","duration":"1.810205336s","start":"2026-06-30T17:10:50.216861Z","end":"2026-06-30T17:10:52.027067Z","steps":["trace[351967257] 'count revisions from in-memory index tree' (duration: 1.810000611s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.027094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:50.18393Z","time spent":"1.843157361s","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:10:52.027108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:50.216843Z","time spent":"1.810259276s","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:10:52.027087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57258804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-30T17:10:52.027158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.388061415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:52.027214Z","caller":"traceutil/trace.go:171","msg":"trace[1896594307] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1237; }","duration":"2.388162328s","start":"2026-06-30T17:10:49.639042Z","end":"2026-06-30T17:10:52.027204Z","steps":["trace[1896594307] 'count revisions from in-memory index tree' (duration: 2.387995024s)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:52.027218Z","caller":"traceutil/trace.go:171","msg":"trace[779894341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"1.572703933s","start":"2026-06-30T17:10:50.45449Z","end":"2026-06-30T17:10:52.027194Z","steps":["trace[779894341] 'range keys from in-memory index tree' (duration: 1.572402665s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.027265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:49.639028Z","time spent":"2.388221879s","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:10:52.027262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:50.454431Z","time spent":"1.572819866s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T17:10:52.029196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035399111s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T17:10:52.029432Z","caller":"traceutil/trace.go:171","msg":"trace[2016884179] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"1.032379261s","start":"2026-06-30T17:10:50.997041Z","end":"2026-06-30T17:10:52.02942Z","steps":["trace[2016884179] 'read index received' (duration: 1.032248176s)","trace[2016884179] 'applied index is now lower than readState.Index' (duration: 130.405µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:10:52.029638Z","caller":"traceutil/trace.go:171","msg":"trace[891307619] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"1.035889174s","start":"2026-06-30T17:10:50.993737Z","end":"2026-06-30T17:10:52.029627Z","steps":["trace[891307619] 'process raft request' (duration: 1.035583095s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.029714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:50.993722Z","time spent":"1.035939996s","remote":"127.0.0.1:33108","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-06-30T17:10:52.029861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032817002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T17:10:52.029899Z","caller":"traceutil/trace.go:171","msg":"trace[1895469293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"1.032871404s","start":"2026-06-30T17:10:50.997019Z","end":"2026-06-30T17:10:52.029891Z","steps":["trace[1895469293] 'agreement among raft nodes before linearized reading' (duration: 1.032761551s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.029924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:50.997012Z","time spent":"1.032905034s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T17:10:52.030045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028517378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-30T17:10:52.03008Z","caller":"traceutil/trace.go:171","msg":"trace[435561877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"1.028594171s","start":"2026-06-30T17:10:51.001478Z","end":"2026-06-30T17:10:52.030072Z","steps":["trace[435561877] 'agreement among raft nodes before linearized reading' (duration: 1.028518488s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.030085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.132288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:10:52.030105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:51.001452Z","time spent":"1.028645582s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-30T17:10:52.030123Z","caller":"traceutil/trace.go:171","msg":"trace[263116779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1238; }","duration":"110.2046ms","start":"2026-06-30T17:10:51.919903Z","end":"2026-06-30T17:10:52.030107Z","steps":["trace[263116779] 'agreement among raft nodes before linearized reading' (duration: 110.149619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.030144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026865195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T17:10:52.030177Z","caller":"traceutil/trace.go:171","msg":"trace[259485606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"1.026924096s","start":"2026-06-30T17:10:51.003243Z","end":"2026-06-30T17:10:52.030167Z","steps":["trace[259485606] 'agreement among raft nodes before linearized reading' (duration: 1.026833783s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.030203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:51.003228Z","time spent":"1.026968147s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T17:10:52.030275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027512081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:10:52.030305Z","caller":"traceutil/trace.go:171","msg":"trace[748935068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"1.027565853s","start":"2026-06-30T17:10:51.002731Z","end":"2026-06-30T17:10:52.030297Z","steps":["trace[748935068] 'agreement among raft nodes before linearized reading' (duration: 1.027486131s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:10:52.030331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:10:51.00272Z","time spent":"1.027603624s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T17:10:52.030337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.70771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:10:52.030386Z","caller":"traceutil/trace.go:171","msg":"trace[1067836833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"137.792133ms","start":"2026-06-30T17:10:51.892576Z","end":"2026-06-30T17:10:52.030369Z","steps":["trace[1067836833] 'agreement among raft nodes before linearized reading' (duration: 137.711161ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:10:52.719935Z","caller":"traceutil/trace.go:171","msg":"trace[674930242] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"106.532032ms","start":"2026-06-30T17:10:52.613378Z","end":"2026-06-30T17:10:52.71991Z","steps":["trace[674930242] 'process raft request' (duration: 35.844233ms)","trace[674930242] 'compare' (duration: 70.573766ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:04.28601Z","caller":"traceutil/trace.go:171","msg":"trace[35730032] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"146.271088ms","start":"2026-06-30T17:11:04.13972Z","end":"2026-06-30T17:11:04.285991Z","steps":["trace[35730032] 'process raft request' (duration: 146.151404ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:04.286255Z","caller":"traceutil/trace.go:171","msg":"trace[970811437] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"146.391741ms","start":"2026-06-30T17:11:04.139855Z","end":"2026-06-30T17:11:04.286247Z","steps":["trace[970811437] 'process raft request' (duration: 146.112574ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:04.286294Z","caller":"traceutil/trace.go:171","msg":"trace[1467814005] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"145.713603ms","start":"2026-06-30T17:11:04.140563Z","end":"2026-06-30T17:11:04.286277Z","steps":["trace[1467814005] 'process raft request' (duration: 145.652092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:05.769312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.903514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:05.769371Z","caller":"traceutil/trace.go:171","msg":"trace[657834860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"877.990137ms","start":"2026-06-30T17:11:04.891363Z","end":"2026-06-30T17:11:05.769353Z","steps":["trace[657834860] 'range keys from in-memory index tree' (duration: 877.837283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:05.769401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:04.891346Z","time spent":"878.041608ms","remote":"127.0.0.1:32838","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-06-30T17:11:06.399709Z","caller":"traceutil/trace.go:171","msg":"trace[1934051570] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"103.856871ms","start":"2026-06-30T17:11:06.295836Z","end":"2026-06-30T17:11:06.399693Z","steps":["trace[1934051570] 'process raft request' (duration: 103.82766ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:06.399862Z","caller":"traceutil/trace.go:171","msg":"trace[1941531812] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"105.066253ms","start":"2026-06-30T17:11:06.29477Z","end":"2026-06-30T17:11:06.399836Z","steps":["trace[1941531812] 'process raft request' (duration: 104.801216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:08.261447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.985631ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-30T17:11:08.261541Z","caller":"traceutil/trace.go:171","msg":"trace[758936149] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"369.79718ms","start":"2026-06-30T17:11:07.891731Z","end":"2026-06-30T17:11:08.261528Z","steps":["trace[758936149] 'read index received' (duration: 43.371µs)","trace[758936149] 'applied index is now lower than readState.Index' (duration: 369.752658ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:11:08.262349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.614511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:08.262384Z","caller":"traceutil/trace.go:171","msg":"trace[1161416266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"370.680353ms","start":"2026-06-30T17:11:07.891692Z","end":"2026-06-30T17:11:08.262372Z","steps":["trace[1161416266] 'agreement among raft nodes before linearized reading' (duration: 370.624301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:08.262417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:07.891674Z","time spent":"370.735724ms","remote":"127.0.0.1:32838","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-06-30T17:11:08.291302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.640459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-30T17:11:08.291418Z","caller":"traceutil/trace.go:171","msg":"trace[636398483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"131.884057ms","start":"2026-06-30T17:11:08.15951Z","end":"2026-06-30T17:11:08.291394Z","steps":["trace[636398483] 'agreement among raft nodes before linearized reading' (duration: 131.585868ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:08.291502Z","caller":"traceutil/trace.go:171","msg":"trace[139983261] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"186.362824ms","start":"2026-06-30T17:11:08.105121Z","end":"2026-06-30T17:11:08.291484Z","steps":["trace[139983261] 'process raft request' (duration: 185.704146ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:08.448701Z","caller":"traceutil/trace.go:171","msg":"trace[712555085] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"146.229206ms","start":"2026-06-30T17:11:08.302435Z","end":"2026-06-30T17:11:08.448664Z","steps":["trace[712555085] 'process raft request' (duration: 138.555623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:09.987699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873774267752513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:11:10.385055Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350026383s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T17:11:10.385571Z","caller":"traceutil/trace.go:171","msg":"trace[1257586666] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"898.480611ms","start":"2026-06-30T17:11:09.487067Z","end":"2026-06-30T17:11:10.385547Z","steps":["trace[1257586666] 'read index received' (duration: 898.230095ms)","trace[1257586666] 'applied index is now lower than readState.Index' (duration: 249.396µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:10.38562Z","caller":"traceutil/trace.go:171","msg":"trace[1343625664] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.3506413s","start":"2026-06-30T17:11:09.034949Z","end":"2026-06-30T17:11:10.38559Z","steps":["trace[1343625664] 'process raft request' (duration: 1.350400034s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.385753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:09.034932Z","time spent":"1.350746462s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:11:10.385749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.654995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:10.385797Z","caller":"traceutil/trace.go:171","msg":"trace[765199595] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1298; }","duration":"898.770268ms","start":"2026-06-30T17:11:09.487015Z","end":"2026-06-30T17:11:10.385785Z","steps":["trace[765199595] 'agreement among raft nodes before linearized reading' (duration: 898.646025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.385835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:09.486978Z","time spent":"898.84747ms","remote":"127.0.0.1:47492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:11:10.666241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.30196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:11:10.666544Z","caller":"traceutil/trace.go:171","msg":"trace[9015272] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"366.423259ms","start":"2026-06-30T17:11:10.30009Z","end":"2026-06-30T17:11:10.666513Z","steps":["trace[9015272] 'process raft request' (duration: 176.407109ms)","trace[9015272] 'compare' (duration: 189.035404ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:11:10.666669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:10.30006Z","time spent":"366.547923ms","remote":"127.0.0.1:33108","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-06-30T17:11:10.666702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.725242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:11:10.666712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.07625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-30T17:11:10.666761Z","caller":"traceutil/trace.go:171","msg":"trace[848526959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"774.814304ms","start":"2026-06-30T17:11:09.891932Z","end":"2026-06-30T17:11:10.666747Z","steps":["trace[848526959] 'agreement among raft nodes before linearized reading' (duration: 774.712392ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.666763Z","caller":"traceutil/trace.go:171","msg":"trace[780015132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"328.163503ms","start":"2026-06-30T17:11:10.338588Z","end":"2026-06-30T17:11:10.666752Z","steps":["trace[780015132] 'agreement among raft nodes before linearized reading' (duration: 328.015799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.666803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:09.891916Z","time spent":"774.879727ms","remote":"127.0.0.1:32838","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-06-30T17:11:10.667024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010015916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:10.667058Z","caller":"traceutil/trace.go:171","msg":"trace[1509554428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1299; }","duration":"1.010078478s","start":"2026-06-30T17:11:09.656971Z","end":"2026-06-30T17:11:10.66705Z","steps":["trace[1509554428] 'agreement among raft nodes before linearized reading' (duration: 1.010020107s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.667082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:09.656955Z","time spent":"1.010121029s","remote":"127.0.0.1:47436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:11:10.666808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:10.338571Z","time spent":"328.229285ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-30T17:11:10.66655Z","caller":"traceutil/trace.go:171","msg":"trace[1692848298] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"280.897776ms","start":"2026-06-30T17:11:10.385634Z","end":"2026-06-30T17:11:10.666531Z","steps":["trace[1692848298] 'read index received' (duration: 90.872576ms)","trace[1692848298] 'applied index is now lower than readState.Index' (duration: 190.02351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:11:10.66822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.657612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-30T17:11:10.66826Z","caller":"traceutil/trace.go:171","msg":"trace[2004734100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"212.724894ms","start":"2026-06-30T17:11:10.455523Z","end":"2026-06-30T17:11:10.668248Z","steps":["trace[2004734100] 'agreement among raft nodes before linearized reading' (duration: 212.57321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.668444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.926703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-30T17:11:10.668465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.034632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:11:10.668481Z","caller":"traceutil/trace.go:171","msg":"trace[226723820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1299; }","duration":"126.994235ms","start":"2026-06-30T17:11:10.541478Z","end":"2026-06-30T17:11:10.668472Z","steps":["trace[226723820] 'agreement among raft nodes before linearized reading' (duration: 126.888752ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.668496Z","caller":"traceutil/trace.go:171","msg":"trace[65690732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"169.091163ms","start":"2026-06-30T17:11:10.499392Z","end":"2026-06-30T17:11:10.668483Z","steps":["trace[65690732] 'agreement among raft nodes before linearized reading' (duration: 168.998621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.668438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.913503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T17:11:10.668569Z","caller":"traceutil/trace.go:171","msg":"trace[1117907575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"127.075487ms","start":"2026-06-30T17:11:10.541485Z","end":"2026-06-30T17:11:10.668561Z","steps":["trace[1117907575] 'agreement among raft nodes before linearized reading' (duration: 126.856582ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.82415Z","caller":"traceutil/trace.go:171","msg":"trace[511688428] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"152.551905ms","start":"2026-06-30T17:11:10.67157Z","end":"2026-06-30T17:11:10.824122Z","steps":["trace[511688428] 'process raft request' (duration: 150.307374ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.854781Z","caller":"traceutil/trace.go:171","msg":"trace[37118463] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"182.811349ms","start":"2026-06-30T17:11:10.671949Z","end":"2026-06-30T17:11:10.85476Z","steps":["trace[37118463] 'process raft request' (duration: 182.667086ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.855049Z","caller":"traceutil/trace.go:171","msg":"trace[963685044] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"183.030885ms","start":"2026-06-30T17:11:10.672004Z","end":"2026-06-30T17:11:10.855035Z","steps":["trace[963685044] 'process raft request' (duration: 182.726537ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.855579Z","caller":"traceutil/trace.go:171","msg":"trace[1230479351] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1352; }","duration":"141.309786ms","start":"2026-06-30T17:11:10.714251Z","end":"2026-06-30T17:11:10.85556Z","steps":["trace[1230479351] 'read index received' (duration: 107.553359ms)","trace[1230479351] 'applied index is now lower than readState.Index' (duration: 33.755427ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:10.855612Z","caller":"traceutil/trace.go:171","msg":"trace[265738977] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"183.554359ms","start":"2026-06-30T17:11:10.672044Z","end":"2026-06-30T17:11:10.855598Z","steps":["trace[265738977] 'process raft request' (duration: 182.942083ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:10.85572Z","caller":"traceutil/trace.go:171","msg":"trace[530849695] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"183.618871ms","start":"2026-06-30T17:11:10.672087Z","end":"2026-06-30T17:11:10.855706Z","steps":["trace[530849695] 'process raft request' (duration: 183.391915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:10.856599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.302402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:11:10.856654Z","caller":"traceutil/trace.go:171","msg":"trace[423362055] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1304; }","duration":"142.442626ms","start":"2026-06-30T17:11:10.7142Z","end":"2026-06-30T17:11:10.856642Z","steps":["trace[423362055] 'agreement among raft nodes before linearized reading' (duration: 141.431669ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:11.089073Z","caller":"traceutil/trace.go:171","msg":"trace[1450076428] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"224.396595ms","start":"2026-06-30T17:11:10.864656Z","end":"2026-06-30T17:11:11.089053Z","steps":["trace[1450076428] 'process raft request' (duration: 140.963618ms)","trace[1450076428] 'compare' (duration: 83.318105ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:11:11.755101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.904996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:11:11.755187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.196704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-06-30T17:11:11.755224Z","caller":"traceutil/trace.go:171","msg":"trace[1654003384] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1306; }","duration":"167.052009ms","start":"2026-06-30T17:11:11.588122Z","end":"2026-06-30T17:11:11.755174Z","steps":["trace[1654003384] 'count revisions from in-memory index tree' (duration: 166.848004ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:11.755285Z","caller":"traceutil/trace.go:171","msg":"trace[237044392] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1306; }","duration":"243.337329ms","start":"2026-06-30T17:11:11.511931Z","end":"2026-06-30T17:11:11.755269Z","steps":["trace[237044392] 'range keys from in-memory index tree' (duration: 243.014659ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:12.058219Z","caller":"traceutil/trace.go:171","msg":"trace[398249254] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"248.113135ms","start":"2026-06-30T17:11:11.810081Z","end":"2026-06-30T17:11:12.058194Z","steps":["trace[398249254] 'read index received' (duration: 247.92432ms)","trace[398249254] 'applied index is now lower than readState.Index' (duration: 187.855µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:12.058363Z","caller":"traceutil/trace.go:171","msg":"trace[427449023] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"285.979651ms","start":"2026-06-30T17:11:11.772344Z","end":"2026-06-30T17:11:12.058324Z","steps":["trace[427449023] 'process raft request' (duration: 285.723095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:12.058715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.624518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:12.058759Z","caller":"traceutil/trace.go:171","msg":"trace[1302419078] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1307; }","duration":"248.707501ms","start":"2026-06-30T17:11:11.81004Z","end":"2026-06-30T17:11:12.058748Z","steps":["trace[1302419078] 'agreement among raft nodes before linearized reading' (duration: 248.580858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:12.058999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.350145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:12.059038Z","caller":"traceutil/trace.go:171","msg":"trace[297134427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"168.389286ms","start":"2026-06-30T17:11:11.890639Z","end":"2026-06-30T17:11:12.059029Z","steps":["trace[297134427] 'agreement among raft nodes before linearized reading' (duration: 168.330054ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:19.268532Z","caller":"traceutil/trace.go:171","msg":"trace[218068084] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"108.423442ms","start":"2026-06-30T17:11:19.160088Z","end":"2026-06-30T17:11:19.268511Z","steps":["trace[218068084] 'process raft request' (duration: 108.31837ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:19.51566Z","caller":"traceutil/trace.go:171","msg":"trace[1809154776] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"171.104757ms","start":"2026-06-30T17:11:19.344474Z","end":"2026-06-30T17:11:19.515579Z","steps":["trace[1809154776] 'process raft request' (duration: 160.314871ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:19.700549Z","caller":"traceutil/trace.go:171","msg":"trace[600367188] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"125.550727ms","start":"2026-06-30T17:11:19.574972Z","end":"2026-06-30T17:11:19.700523Z","steps":["trace[600367188] 'read index received' (duration: 77.274674ms)","trace[600367188] 'applied index is now lower than readState.Index' (duration: 48.274883ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:19.70068Z","caller":"traceutil/trace.go:171","msg":"trace[1746486256] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"352.677714ms","start":"2026-06-30T17:11:19.347969Z","end":"2026-06-30T17:11:19.700647Z","steps":["trace[1746486256] 'process raft request' (duration: 304.277387ms)","trace[1746486256] 'compare' (duration: 48.14733ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:11:19.700702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.696492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:11:19.700749Z","caller":"traceutil/trace.go:171","msg":"trace[701877163] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1371; }","duration":"125.771894ms","start":"2026-06-30T17:11:19.574966Z","end":"2026-06-30T17:11:19.700738Z","steps":["trace[701877163] 'agreement among raft nodes before linearized reading' (duration: 125.665961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:19.700812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:19.347956Z","time spent":"352.782717ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:11:20.873973Z","caller":"traceutil/trace.go:171","msg":"trace[1314914180] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"214.780159ms","start":"2026-06-30T17:11:20.659168Z","end":"2026-06-30T17:11:20.873948Z","steps":["trace[1314914180] 'process raft request' (duration: 214.607924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.032143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.873784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:11:22.03223Z","caller":"traceutil/trace.go:171","msg":"trace[647581674] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"1.153274804s","start":"2026-06-30T17:11:20.878942Z","end":"2026-06-30T17:11:22.032217Z","steps":["trace[647581674] 'read index received' (duration: 202.205174ms)","trace[647581674] 'applied index is now lower than readState.Index' (duration: 951.06868ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:22.032288Z","caller":"traceutil/trace.go:171","msg":"trace[714841548] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.325928474s","start":"2026-06-30T17:11:20.706352Z","end":"2026-06-30T17:11:22.032281Z","steps":["trace[714841548] 'process raft request' (duration: 374.811913ms)","trace[714841548] 'compare' (duration: 950.73164ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:11:22.032334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.706328Z","time spent":"1.325982464s","remote":"127.0.0.1:33108","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-06-30T17:11:22.032547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153610512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-30T17:11:22.032584Z","caller":"traceutil/trace.go:171","msg":"trace[1690011425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1373; }","duration":"1.153684544s","start":"2026-06-30T17:11:20.87889Z","end":"2026-06-30T17:11:22.032575Z","steps":["trace[1690011425] 'agreement among raft nodes before linearized reading' (duration: 1.153579471s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.032606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.878873Z","time spent":"1.153728555s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T17:11:22.032743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151048615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-30T17:11:22.032767Z","caller":"traceutil/trace.go:171","msg":"trace[500815150] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1373; }","duration":"1.151093956s","start":"2026-06-30T17:11:20.881666Z","end":"2026-06-30T17:11:22.03276Z","steps":["trace[500815150] 'agreement among raft nodes before linearized reading' (duration: 1.151028944s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.032784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.881656Z","time spent":"1.151123817s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-30T17:11:22.032867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135199264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T17:11:22.032893Z","caller":"traceutil/trace.go:171","msg":"trace[30683327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"1.135244335s","start":"2026-06-30T17:11:20.897642Z","end":"2026-06-30T17:11:22.032886Z","steps":["trace[30683327] 'agreement among raft nodes before linearized reading' (duration: 1.135184713s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.032892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126548743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-30T17:11:22.032916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.897631Z","time spent":"1.135279197s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T17:11:22.032984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142364184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:11:22.033007Z","caller":"traceutil/trace.go:171","msg":"trace[837553968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"1.142398935s","start":"2026-06-30T17:11:20.890602Z","end":"2026-06-30T17:11:22.033001Z","steps":["trace[837553968] 'agreement among raft nodes before linearized reading' (duration: 1.142363144s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.033023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.890596Z","time spent":"1.142423766s","remote":"127.0.0.1:32838","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-06-30T17:11:22.032983Z","caller":"traceutil/trace.go:171","msg":"trace[1679215309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"1.126689667s","start":"2026-06-30T17:11:20.906239Z","end":"2026-06-30T17:11:22.032929Z","steps":["trace[1679215309] 'agreement among raft nodes before linearized reading' (duration: 1.126496252s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.033028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130837998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122725"} {"level":"warn","ts":"2026-06-30T17:11:22.033234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127924161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T17:11:22.033263Z","caller":"traceutil/trace.go:171","msg":"trace[2100474334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1373; }","duration":"1.127974561s","start":"2026-06-30T17:11:20.905282Z","end":"2026-06-30T17:11:22.033256Z","steps":["trace[2100474334] 'agreement among raft nodes before linearized reading' (duration: 1.1279094s)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.033229Z","caller":"traceutil/trace.go:171","msg":"trace[810266609] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1373; }","duration":"1.131059613s","start":"2026-06-30T17:11:20.902096Z","end":"2026-06-30T17:11:22.033156Z","steps":["trace[810266609] 'agreement among raft nodes before linearized reading' (duration: 1.130701504s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.033305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127008185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-30T17:11:22.033305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129179162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-30T17:11:22.033382Z","caller":"traceutil/trace.go:171","msg":"trace[1817534135] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1373; }","duration":"1.129280396s","start":"2026-06-30T17:11:20.904091Z","end":"2026-06-30T17:11:22.033371Z","steps":["trace[1817534135] 'agreement among raft nodes before linearized reading' (duration: 1.129147282s)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.033383Z","caller":"traceutil/trace.go:171","msg":"trace[1794928127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"1.127123669s","start":"2026-06-30T17:11:20.906241Z","end":"2026-06-30T17:11:22.033364Z","steps":["trace[1794928127] 'agreement among raft nodes before linearized reading' (duration: 1.126949254s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.033416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.904079Z","time spent":"1.129327257s","remote":"127.0.0.1:32902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-30T17:11:22.033434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.906222Z","time spent":"1.127198491s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T17:11:22.033324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.902082Z","time spent":"1.131229618s","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":122748,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-30T17:11:22.033074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.906227Z","time spent":"1.12683474s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T17:11:22.033281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:11:20.90527Z","time spent":"1.128006912s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-30T17:11:22.140144Z","caller":"traceutil/trace.go:171","msg":"trace[1986575328] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"102.760612ms","start":"2026-06-30T17:11:22.037358Z","end":"2026-06-30T17:11:22.140119Z","steps":["trace[1986575328] 'process raft request' (duration: 99.736792ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.141175Z","caller":"traceutil/trace.go:171","msg":"trace[864504559] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"102.243867ms","start":"2026-06-30T17:11:22.038915Z","end":"2026-06-30T17:11:22.141159Z","steps":["trace[864504559] 'process raft request' (duration: 102.187685ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.141275Z","caller":"traceutil/trace.go:171","msg":"trace[292119304] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1428; }","duration":"102.973107ms","start":"2026-06-30T17:11:22.03828Z","end":"2026-06-30T17:11:22.141253Z","steps":["trace[292119304] 'read index received' (duration: 99.222976ms)","trace[292119304] 'applied index is now lower than readState.Index' (duration: 3.749011ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:11:22.141456Z","caller":"traceutil/trace.go:171","msg":"trace[423119555] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"103.873141ms","start":"2026-06-30T17:11:22.037542Z","end":"2026-06-30T17:11:22.141415Z","steps":["trace[423119555] 'process raft request' (duration: 103.005948ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.141632Z","caller":"traceutil/trace.go:171","msg":"trace[456861683] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"102.658958ms","start":"2026-06-30T17:11:22.038958Z","end":"2026-06-30T17:11:22.141617Z","steps":["trace[456861683] 'process raft request' (duration: 102.187346ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.14176Z","caller":"traceutil/trace.go:171","msg":"trace[1228636052] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"103.370927ms","start":"2026-06-30T17:11:22.038375Z","end":"2026-06-30T17:11:22.141746Z","steps":["trace[1228636052] 'process raft request' (duration: 102.526745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:11:22.141985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.683835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122725"} {"level":"info","ts":"2026-06-30T17:11:22.142034Z","caller":"traceutil/trace.go:171","msg":"trace[1799236359] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1379; }","duration":"103.755308ms","start":"2026-06-30T17:11:22.038265Z","end":"2026-06-30T17:11:22.142021Z","steps":["trace[1799236359] 'agreement among raft nodes before linearized reading' (duration: 103.46925ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:22.142373Z","caller":"traceutil/trace.go:171","msg":"trace[1241816755] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"103.975723ms","start":"2026-06-30T17:11:22.03838Z","end":"2026-06-30T17:11:22.142356Z","steps":["trace[1241816755] 'process raft request' (duration: 102.651098ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:11:49.980523Z","caller":"traceutil/trace.go:171","msg":"trace[1859037494] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"222.557105ms","start":"2026-06-30T17:11:49.757927Z","end":"2026-06-30T17:11:49.980484Z","steps":["trace[1859037494] 'process raft request' (duration: 222.36511ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:12:44.865075Z","caller":"traceutil/trace.go:171","msg":"trace[2015540920] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"158.168363ms","start":"2026-06-30T17:12:44.706869Z","end":"2026-06-30T17:12:44.865037Z","steps":["trace[2015540920] 'process raft request' (duration: 131.019781ms)","trace[2015540920] 'compare' (duration: 26.919065ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:12:59.041057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.782434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-30T17:12:59.041137Z","caller":"traceutil/trace.go:171","msg":"trace[1142923526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1837; }","duration":"113.906337ms","start":"2026-06-30T17:12:58.927211Z","end":"2026-06-30T17:12:59.041117Z","steps":["trace[1142923526] 'range keys from in-memory index tree' (duration: 113.62408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:12:59.04128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.373028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:12:59.041356Z","caller":"traceutil/trace.go:171","msg":"trace[1098134870] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1837; }","duration":"120.474211ms","start":"2026-06-30T17:12:58.920858Z","end":"2026-06-30T17:12:59.041332Z","steps":["trace[1098134870] 'count revisions from in-memory index tree' (duration: 120.273765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:12:59.297669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.996515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:12:59.297853Z","caller":"traceutil/trace.go:171","msg":"trace[1250896324] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"216.90895ms","start":"2026-06-30T17:12:59.080927Z","end":"2026-06-30T17:12:59.297836Z","steps":["trace[1250896324] 'process raft request' (duration: 216.854628ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:12:59.297892Z","caller":"traceutil/trace.go:171","msg":"trace[2004303823] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"251.858366ms","start":"2026-06-30T17:12:59.04601Z","end":"2026-06-30T17:12:59.297868Z","steps":["trace[2004303823] 'process raft request' (duration: 125.574374ms)","trace[2004303823] 'compare' (duration: 125.873382ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:13:33.761076Z","caller":"traceutil/trace.go:171","msg":"trace[832612519] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"208.805278ms","start":"2026-06-30T17:13:33.552242Z","end":"2026-06-30T17:13:33.761047Z","steps":["trace[832612519] 'process raft request' (duration: 166.380995ms)","trace[832612519] 'compare' (duration: 42.141655ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:15:05.861086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.097754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-30T17:15:05.861248Z","caller":"traceutil/trace.go:171","msg":"trace[297544305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2439; }","duration":"177.30142ms","start":"2026-06-30T17:15:05.683922Z","end":"2026-06-30T17:15:05.861224Z","steps":["trace[297544305] 'range keys from in-memory index tree' (duration: 176.910459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:15:11.873285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.025587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:15:11.873376Z","caller":"traceutil/trace.go:171","msg":"trace[1475437447] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2471; }","duration":"170.15168ms","start":"2026-06-30T17:15:11.703199Z","end":"2026-06-30T17:15:11.873351Z","steps":["trace[1475437447] 'count revisions from in-memory index tree' (duration: 169.957825ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:15:12.216182Z","caller":"traceutil/trace.go:171","msg":"trace[1543479496] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2573; }","duration":"324.110782ms","start":"2026-06-30T17:15:11.892039Z","end":"2026-06-30T17:15:12.21615Z","steps":["trace[1543479496] 'read index received' (duration: 323.906846ms)","trace[1543479496] 'applied index is now lower than readState.Index' (duration: 202.716µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:15:12.21645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.372929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:15:12.216508Z","caller":"traceutil/trace.go:171","msg":"trace[1732861509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2472; }","duration":"324.474912ms","start":"2026-06-30T17:15:11.892021Z","end":"2026-06-30T17:15:12.216496Z","steps":["trace[1732861509] 'agreement among raft nodes before linearized reading' (duration: 324.356079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:15:12.216543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:15:11.892013Z","time spent":"324.522853ms","remote":"127.0.0.1:32828","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-06-30T17:15:12.216574Z","caller":"traceutil/trace.go:171","msg":"trace[1259933715] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"325.155279ms","start":"2026-06-30T17:15:11.891386Z","end":"2026-06-30T17:15:12.216542Z","steps":["trace[1259933715] 'process raft request' (duration: 324.567933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:15:12.216716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:15:11.891371Z","time spent":"325.270132ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:15:12.217526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.311343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-30T17:15:12.217587Z","caller":"traceutil/trace.go:171","msg":"trace[1560731011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2472; }","duration":"249.398766ms","start":"2026-06-30T17:15:11.968174Z","end":"2026-06-30T17:15:12.217573Z","steps":["trace[1560731011] 'agreement among raft nodes before linearized reading' (duration: 248.258755ms)"],"step_count":1}