{"level":"warn","ts":"2026-06-13T22:51:44.210896Z","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-13T22:51:44.210999Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-13T22:51:44.211074Z","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-13T22:51:44.211087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-13T22:51:44.211104Z","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-13T22:51:44.211785Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-06-13T22:51:44.211905Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-13T22:51:45.029254Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"817.075046ms"} {"level":"warn","ts":"2026-06-13T22:51:46.567725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163022139s","expected-duration":"1s"} {"level":"info","ts":"2026-06-13T22:51:47.232491Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-06-13T22:51:47.232705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T22:51:47.232772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-06-13T22:51:47.232802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T22:51:47.232853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-06-13T22:51:47.232954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-06-13T22:51:47.239122Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T22:51:47.243685Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T22:51:47.247662Z","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-13T22:51:47.252229Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T22:51:47.252677Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T22:51:47.252705Z","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-13T22:51:47.253343Z","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-13T22:51:47.253386Z","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-13T22:51:47.254909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-06-13T22:51:47.255458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-13T22:51:47.257512Z","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-13T22:51:47.257648Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-13T22:51:47.257729Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-13T22:51:47.257846Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T22:51:47.257896Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T22:51:48.03393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T22:51:48.033999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T22:51:48.034039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-06-13T22:51:48.034056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-06-13T22:51:48.03407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-13T22:51:48.034083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-06-13T22:51:48.034106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-13T22:51:48.035343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:51:48.036336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:51:48.036317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T22:51:48.03641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:51:48.036704Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T22:51:48.036783Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T22:51:48.036837Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:51:48.037027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:51:48.03708Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:51:48.039535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T22:51:48.041347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-06-13T22:51:54.194423Z","caller":"traceutil/trace.go:171","msg":"trace[874042297] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"113.229169ms","start":"2026-06-13T22:51:54.081153Z","end":"2026-06-13T22:51:54.194382Z","steps":["trace[874042297] 'process raft request' (duration: 64.542238ms)","trace[874042297] 'compare' (duration: 48.495187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:26.962975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.315042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:52:26.963104Z","caller":"traceutil/trace.go:171","msg":"trace[1092524845] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"501.674387ms","start":"2026-06-13T22:52:26.461414Z","end":"2026-06-13T22:52:26.963089Z","steps":["trace[1092524845] 'process raft request' (duration: 156.759883ms)","trace[1092524845] 'compare' (duration: 344.175349ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:26.963152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.461401Z","time spent":"501.730337ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:26.963751Z","caller":"traceutil/trace.go:171","msg":"trace[577075222] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"325.624981ms","start":"2026-06-13T22:52:26.638105Z","end":"2026-06-13T22:52:26.96373Z","steps":["trace[577075222] 'process raft request' (duration: 325.53515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:26.963873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.638084Z","time spent":"325.732364ms","remote":"127.0.0.1:44576","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-13T22:52:26.971598Z","caller":"traceutil/trace.go:171","msg":"trace[1627694042] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"332.669944ms","start":"2026-06-13T22:52:26.638873Z","end":"2026-06-13T22:52:26.971542Z","steps":["trace[1627694042] 'process raft request' (duration: 332.533861ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.971722Z","caller":"traceutil/trace.go:171","msg":"trace[1517280587] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"316.216957ms","start":"2026-06-13T22:52:26.655475Z","end":"2026-06-13T22:52:26.971692Z","steps":["trace[1517280587] 'process raft request' (duration: 316.047514ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.971853Z","caller":"traceutil/trace.go:171","msg":"trace[769188702] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"291.181048ms","start":"2026-06-13T22:52:26.680645Z","end":"2026-06-13T22:52:26.971826Z","steps":["trace[769188702] 'process raft request' (duration: 291.033735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:26.971889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.655457Z","time spent":"316.356519ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:52:26.971926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.638851Z","time spent":"332.900289ms","remote":"127.0.0.1:44576","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-13T22:52:26.971945Z","caller":"traceutil/trace.go:171","msg":"trace[1463964757] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"140.016509ms","start":"2026-06-13T22:52:26.83192Z","end":"2026-06-13T22:52:26.971936Z","steps":["trace[1463964757] 'process raft request' (duration: 139.973078ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.972038Z","caller":"traceutil/trace.go:171","msg":"trace[1205655576] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"271.50143ms","start":"2026-06-13T22:52:26.700526Z","end":"2026-06-13T22:52:26.972027Z","steps":["trace[1205655576] 'process raft request' (duration: 271.263475ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.972077Z","caller":"traceutil/trace.go:171","msg":"trace[1304219384] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"284.972271ms","start":"2026-06-13T22:52:26.687098Z","end":"2026-06-13T22:52:26.97207Z","steps":["trace[1304219384] 'process raft request' (duration: 284.620175ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.972113Z","caller":"traceutil/trace.go:171","msg":"trace[45403619] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"284.013754ms","start":"2026-06-13T22:52:26.688092Z","end":"2026-06-13T22:52:26.972106Z","steps":["trace[45403619] 'process raft request' (duration: 283.664008ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.972043Z","caller":"traceutil/trace.go:171","msg":"trace[760781953] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"265.950676ms","start":"2026-06-13T22:52:26.706085Z","end":"2026-06-13T22:52:26.972036Z","steps":["trace[760781953] 'process raft request' (duration: 265.746162ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:26.972114Z","caller":"traceutil/trace.go:171","msg":"trace[1154633200] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"307.637686ms","start":"2026-06-13T22:52:26.664386Z","end":"2026-06-13T22:52:26.972024Z","steps":["trace[1154633200] 'process raft request' (duration: 307.250529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:26.97236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.664369Z","time spent":"307.92171ms","remote":"127.0.0.1:44576","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-13T22:52:26.972218Z","caller":"traceutil/trace.go:171","msg":"trace[307437274] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"329.017546ms","start":"2026-06-13T22:52:26.642628Z","end":"2026-06-13T22:52:26.971645Z","steps":["trace[307437274] 'process raft request' (duration: 328.855073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:26.972542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.642607Z","time spent":"329.898951ms","remote":"127.0.0.1:44576","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-13T22:52:26.972352Z","caller":"traceutil/trace.go:171","msg":"trace[1026799852] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"316.599953ms","start":"2026-06-13T22:52:26.655528Z","end":"2026-06-13T22:52:26.972128Z","steps":["trace[1026799852] 'process raft request' (duration: 316.060034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:26.972945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:26.655523Z","time spent":"317.109084ms","remote":"127.0.0.1:44576","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-13T22:52:27.295485Z","caller":"traceutil/trace.go:171","msg":"trace[388486175] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"169.385229ms","start":"2026-06-13T22:52:27.12603Z","end":"2026-06-13T22:52:27.295415Z","steps":["trace[388486175] 'process raft request' (duration: 95.180011ms)","trace[388486175] 'compare' (duration: 73.893752ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:27.29578Z","caller":"traceutil/trace.go:171","msg":"trace[1615126575] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"169.378839ms","start":"2026-06-13T22:52:27.126372Z","end":"2026-06-13T22:52:27.295751Z","steps":["trace[1615126575] 'process raft request' (duration: 168.941561ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:27.345809Z","caller":"traceutil/trace.go:171","msg":"trace[1485269459] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:589; }","duration":"180.908084ms","start":"2026-06-13T22:52:27.164879Z","end":"2026-06-13T22:52:27.345787Z","steps":["trace[1485269459] 'read index received' (duration: 56.473206ms)","trace[1485269459] 'applied index is now lower than readState.Index' (duration: 124.434108ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:27.346098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.198719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:27.346137Z","caller":"traceutil/trace.go:171","msg":"trace[971295446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"181.291931ms","start":"2026-06-13T22:52:27.164835Z","end":"2026-06-13T22:52:27.346126Z","steps":["trace[971295446] 'agreement among raft nodes before linearized reading' (duration: 181.039637ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:27.346056Z","caller":"traceutil/trace.go:171","msg":"trace[394426033] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"189.81174ms","start":"2026-06-13T22:52:27.156217Z","end":"2026-06-13T22:52:27.346029Z","steps":["trace[394426033] 'process raft request' (duration: 189.412303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:27.559335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.269015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:52:27.560802Z","caller":"traceutil/trace.go:171","msg":"trace[724250176] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"248.278206ms","start":"2026-06-13T22:52:27.312426Z","end":"2026-06-13T22:52:27.560704Z","steps":["trace[724250176] 'process raft request' (duration: 114.514192ms)","trace[724250176] 'compare' (duration: 132.074971ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:27.562873Z","caller":"traceutil/trace.go:171","msg":"trace[132876443] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"215.602624ms","start":"2026-06-13T22:52:27.347205Z","end":"2026-06-13T22:52:27.562807Z","steps":["trace[132876443] 'read index received' (duration: 79.728961ms)","trace[132876443] 'applied index is now lower than readState.Index' (duration: 135.821762ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:27.562995Z","caller":"traceutil/trace.go:171","msg":"trace[1815541025] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"247.593712ms","start":"2026-06-13T22:52:27.31536Z","end":"2026-06-13T22:52:27.562954Z","steps":["trace[1815541025] 'process raft request' (duration: 244.969803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:27.565601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.374095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:27.565674Z","caller":"traceutil/trace.go:171","msg":"trace[712615559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"218.456208ms","start":"2026-06-13T22:52:27.347201Z","end":"2026-06-13T22:52:27.565657Z","steps":["trace[712615559] 'agreement among raft nodes before linearized reading' (duration: 215.721327ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:27.565849Z","caller":"traceutil/trace.go:171","msg":"trace[729270088] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"148.240554ms","start":"2026-06-13T22:52:27.417597Z","end":"2026-06-13T22:52:27.565838Z","steps":["trace[729270088] 'process raft request' (duration: 144.910042ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:28.287091Z","caller":"traceutil/trace.go:171","msg":"trace[1536987410] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"707.742761ms","start":"2026-06-13T22:52:27.579326Z","end":"2026-06-13T22:52:28.287068Z","steps":["trace[1536987410] 'process raft request' (duration: 653.928404ms)","trace[1536987410] 'compare' (duration: 53.691995ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:28.287223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:27.579301Z","time spent":"707.853313ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:28.287559Z","caller":"traceutil/trace.go:171","msg":"trace[1944429607] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"537.355284ms","start":"2026-06-13T22:52:27.750179Z","end":"2026-06-13T22:52:28.287534Z","steps":["trace[1944429607] 'read index received' (duration: 483.437264ms)","trace[1944429607] 'applied index is now lower than readState.Index' (duration: 53.917209ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:28.287595Z","caller":"traceutil/trace.go:171","msg":"trace[1822311092] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"700.193949ms","start":"2026-06-13T22:52:27.587391Z","end":"2026-06-13T22:52:28.287585Z","steps":["trace[1822311092] 'process raft request' (duration: 699.836202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:28.287656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:27.587363Z","time spent":"700.261811ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:28.287694Z","caller":"traceutil/trace.go:171","msg":"trace[613450407] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"641.42917ms","start":"2026-06-13T22:52:27.646226Z","end":"2026-06-13T22:52:28.287655Z","steps":["trace[613450407] 'process raft request' (duration: 641.258697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:28.287747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.551977ms","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-13T22:52:28.28777Z","caller":"traceutil/trace.go:171","msg":"trace[810503463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"537.628839ms","start":"2026-06-13T22:52:27.750134Z","end":"2026-06-13T22:52:28.287763Z","steps":["trace[810503463] 'agreement among raft nodes before linearized reading' (duration: 537.508766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:28.287764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.237835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-13T22:52:28.287769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.31142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:52:28.287792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:27.750112Z","time spent":"537.673959ms","remote":"127.0.0.1:44800","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":"info","ts":"2026-06-13T22:52:28.287804Z","caller":"traceutil/trace.go:171","msg":"trace[398345956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"122.38141ms","start":"2026-06-13T22:52:28.165413Z","end":"2026-06-13T22:52:28.287795Z","steps":["trace[398345956] 'agreement among raft nodes before linearized reading' (duration: 122.321969ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:28.287804Z","caller":"traceutil/trace.go:171","msg":"trace[1283409964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"534.288916ms","start":"2026-06-13T22:52:27.753502Z","end":"2026-06-13T22:52:28.287791Z","steps":["trace[1283409964] 'agreement among raft nodes before linearized reading' (duration: 534.182784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:28.287834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:27.753497Z","time spent":"534.331747ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-13T22:52:28.287827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:27.646198Z","time spent":"641.555822ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:52:31.810873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.53693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:52:31.811046Z","caller":"traceutil/trace.go:171","msg":"trace[582628690] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"146.211015ms","start":"2026-06-13T22:52:31.664804Z","end":"2026-06-13T22:52:31.811015Z","steps":["trace[582628690] 'process raft request' (duration: 13.377709ms)","trace[582628690] 'compare' (duration: 132.300376ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:32.647703Z","caller":"traceutil/trace.go:171","msg":"trace[880118767] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"197.031607ms","start":"2026-06-13T22:52:32.450638Z","end":"2026-06-13T22:52:32.647669Z","steps":["trace[880118767] 'read index received' (duration: 364.297µs)","trace[880118767] 'applied index is now lower than readState.Index' (duration: 196.66617ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:32.647861Z","caller":"traceutil/trace.go:171","msg":"trace[426971037] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"199.850889ms","start":"2026-06-13T22:52:32.447963Z","end":"2026-06-13T22:52:32.647814Z","steps":["trace[426971037] 'process raft request' (duration: 106.300129ms)","trace[426971037] 'compare' (duration: 93.244605ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:32.648137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.465044ms","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-13T22:52:32.648217Z","caller":"traceutil/trace.go:171","msg":"trace[702631591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"197.602797ms","start":"2026-06-13T22:52:32.450588Z","end":"2026-06-13T22:52:32.648191Z","steps":["trace[702631591] 'agreement among raft nodes before linearized reading' (duration: 197.255281ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:34.030465Z","caller":"traceutil/trace.go:171","msg":"trace[1815315926] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"345.020875ms","start":"2026-06-13T22:52:33.685415Z","end":"2026-06-13T22:52:34.030436Z","steps":["trace[1815315926] 'process raft request' (duration: 343.453236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:34.030713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:33.685362Z","time spent":"345.190598ms","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:34.686254Z","caller":"traceutil/trace.go:171","msg":"trace[210895567] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"447.291739ms","start":"2026-06-13T22:52:34.238928Z","end":"2026-06-13T22:52:34.68622Z","steps":["trace[210895567] 'read index received' (duration: 365.854256ms)","trace[210895567] 'applied index is now lower than readState.Index' (duration: 81.435913ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:34.686594Z","caller":"traceutil/trace.go:171","msg":"trace[304122499] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"593.581445ms","start":"2026-06-13T22:52:34.092962Z","end":"2026-06-13T22:52:34.686543Z","steps":["trace[304122499] 'process raft request' (duration: 511.861766ms)","trace[304122499] 'compare' (duration: 81.21665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:34.686958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:34.092935Z","time spent":"593.746768ms","remote":"127.0.0.1:44880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:52:34.686978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.185979ms","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-13T22:52:34.687085Z","caller":"traceutil/trace.go:171","msg":"trace[1381329253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"267.387912ms","start":"2026-06-13T22:52:34.419667Z","end":"2026-06-13T22:52:34.687055Z","steps":["trace[1381329253] 'agreement among raft nodes before linearized reading' (duration: 267.006885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:34.687081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.039422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fc28h\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-13T22:52:34.687228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.827466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-13T22:52:34.687341Z","caller":"traceutil/trace.go:171","msg":"trace[1576986688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"232.018181ms","start":"2026-06-13T22:52:34.455296Z","end":"2026-06-13T22:52:34.687314Z","steps":["trace[1576986688] 'agreement among raft nodes before linearized reading' (duration: 231.450119ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:34.687299Z","caller":"traceutil/trace.go:171","msg":"trace[253716061] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fc28h; range_end:; response_count:1; response_revision:637; }","duration":"448.401559ms","start":"2026-06-13T22:52:34.23887Z","end":"2026-06-13T22:52:34.687272Z","steps":["trace[253716061] 'agreement among raft nodes before linearized reading' (duration: 447.683826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:34.687882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:34.238843Z","time spent":"449.020801ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-fc28h\" "} {"level":"warn","ts":"2026-06-13T22:52:35.015131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.416012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:52:35.015399Z","caller":"traceutil/trace.go:171","msg":"trace[1963473320] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"319.756663ms","start":"2026-06-13T22:52:34.695573Z","end":"2026-06-13T22:52:35.01533Z","steps":["trace[1963473320] 'process raft request' (duration: 160.052395ms)","trace[1963473320] 'compare' (duration: 159.256569ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:35.015647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:34.695551Z","time spent":"319.956936ms","remote":"127.0.0.1:44800","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-13T22:52:35.015699Z","caller":"traceutil/trace.go:171","msg":"trace[13142663] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"318.722123ms","start":"2026-06-13T22:52:34.696956Z","end":"2026-06-13T22:52:35.015678Z","steps":["trace[13142663] 'process raft request' (duration: 318.284955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:35.0158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:34.696945Z","time spent":"318.806804ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:35.016051Z","caller":"traceutil/trace.go:171","msg":"trace[926335850] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:660; }","duration":"318.945547ms","start":"2026-06-13T22:52:34.697073Z","end":"2026-06-13T22:52:35.016018Z","steps":["trace[926335850] 'read index received' (duration: 67.119056ms)","trace[926335850] 'applied index is now lower than readState.Index' (duration: 251.823401ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:35.01617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.1017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:35.016214Z","caller":"traceutil/trace.go:171","msg":"trace[189460941] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:641; }","duration":"319.176201ms","start":"2026-06-13T22:52:34.697023Z","end":"2026-06-13T22:52:35.0162Z","steps":["trace[189460941] 'agreement among raft nodes before linearized reading' (duration: 319.07237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:35.016252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:34.697004Z","time spent":"319.241612ms","remote":"127.0.0.1:44892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-06-13T22:52:35.016208Z","caller":"traceutil/trace.go:171","msg":"trace[1496572750] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"313.76969ms","start":"2026-06-13T22:52:34.702398Z","end":"2026-06-13T22:52:35.016167Z","steps":["trace[1496572750] 'process raft request' (duration: 313.111328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:35.016474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:34.702386Z","time spent":"313.978024ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:52:35.667193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833961544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-13T22:52:36.168433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833961544,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:52:36.606576Z","caller":"traceutil/trace.go:171","msg":"trace[807871203] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.580124352s","start":"2026-06-13T22:52:35.02642Z","end":"2026-06-13T22:52:36.606544Z","steps":["trace[807871203] 'process raft request' (duration: 1.527173501s)","trace[807871203] 'compare' (duration: 52.476352ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:36.606898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:35.026385Z","time spent":"1.580283874s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:36.63715Z","caller":"traceutil/trace.go:171","msg":"trace[1800459507] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.204377902s","start":"2026-06-13T22:52:35.432738Z","end":"2026-06-13T22:52:36.637116Z","steps":["trace[1800459507] 'process raft request' (duration: 1.203540567s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:36.637599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:35.432718Z","time spent":"1.204533075s","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:52:36.651079Z","caller":"traceutil/trace.go:171","msg":"trace[535964985] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"1.484111256s","start":"2026-06-13T22:52:35.166939Z","end":"2026-06-13T22:52:36.65105Z","steps":["trace[535964985] 'read index received' (duration: 1.386882937s)","trace[535964985] 'applied index is now lower than readState.Index' (duration: 97.227369ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:36.651212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484249728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:36.651241Z","caller":"traceutil/trace.go:171","msg":"trace[1117618006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.4843444s","start":"2026-06-13T22:52:35.166889Z","end":"2026-06-13T22:52:36.651233Z","steps":["trace[1117618006] 'agreement among raft nodes before linearized reading' (duration: 1.484260209s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:36.651275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:52:35.166865Z","time spent":"1.484403131s","remote":"127.0.0.1:44496","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-13T22:52:38.618956Z","caller":"traceutil/trace.go:171","msg":"trace[1808579944] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"107.668275ms","start":"2026-06-13T22:52:38.511264Z","end":"2026-06-13T22:52:38.618933Z","steps":["trace[1808579944] 'process raft request' (duration: 107.484311ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:49.561712Z","caller":"traceutil/trace.go:171","msg":"trace[2067689913] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"201.973236ms","start":"2026-06-13T22:52:49.359712Z","end":"2026-06-13T22:52:49.561685Z","steps":["trace[2067689913] 'process raft request' (duration: 201.810403ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:50.564052Z","caller":"traceutil/trace.go:171","msg":"trace[52261907] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"198.898929ms","start":"2026-06-13T22:52:50.365123Z","end":"2026-06-13T22:52:50.564022Z","steps":["trace[52261907] 'process raft request' (duration: 198.717546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:52.45261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.647616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-13T22:52:52.452704Z","caller":"traceutil/trace.go:171","msg":"trace[1658882063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"269.818018ms","start":"2026-06-13T22:52:52.182866Z","end":"2026-06-13T22:52:52.452684Z","steps":["trace[1658882063] 'range keys from in-memory index tree' (duration: 269.436762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:52:52.452778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.795733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:52:52.452866Z","caller":"traceutil/trace.go:171","msg":"trace[1245552489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"285.923625ms","start":"2026-06-13T22:52:52.166924Z","end":"2026-06-13T22:52:52.452848Z","steps":["trace[1245552489] 'range keys from in-memory index tree' (duration: 285.711391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:19.44968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.094336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:53:19.449771Z","caller":"traceutil/trace.go:171","msg":"trace[863430250] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"205.394859ms","start":"2026-06-13T22:53:19.244364Z","end":"2026-06-13T22:53:19.449759Z","steps":["trace[863430250] 'process raft request' (duration: 104.11944ms)","trace[863430250] 'compare' (duration: 100.987803ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:26.354683Z","caller":"traceutil/trace.go:171","msg":"trace[615245634] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"235.674238ms","start":"2026-06-13T22:53:26.118979Z","end":"2026-06-13T22:53:26.354653Z","steps":["trace[615245634] 'process raft request' (duration: 201.392176ms)","trace[615245634] 'compare' (duration: 34.17757ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:26.357437Z","caller":"traceutil/trace.go:171","msg":"trace[872288962] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"140.873729ms","start":"2026-06-13T22:53:26.216517Z","end":"2026-06-13T22:53:26.357391Z","steps":["trace[872288962] 'process raft request' (duration: 140.771047ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:53:28.013702Z","caller":"traceutil/trace.go:171","msg":"trace[1610990913] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"396.121309ms","start":"2026-06-13T22:53:27.617519Z","end":"2026-06-13T22:53:28.01364Z","steps":["trace[1610990913] 'read index received' (duration: 395.913825ms)","trace[1610990913] 'applied index is now lower than readState.Index' (duration: 205.654µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:28.013738Z","caller":"traceutil/trace.go:171","msg":"trace[1857617009] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"600.853175ms","start":"2026-06-13T22:53:27.412843Z","end":"2026-06-13T22:53:28.013696Z","steps":["trace[1857617009] 'process raft request' (duration: 600.56294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:28.013924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:27.412819Z","time spent":"601.024488ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:53:28.013975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.424394ms","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-13T22:53:28.014017Z","caller":"traceutil/trace.go:171","msg":"trace[1126192660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"396.525755ms","start":"2026-06-13T22:53:27.617476Z","end":"2026-06-13T22:53:28.014002Z","steps":["trace[1126192660] 'agreement among raft nodes before linearized reading' (duration: 396.328212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:28.014067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:27.617455Z","time spent":"396.603157ms","remote":"127.0.0.1:44800","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-13T22:53:28.683496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.168004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:53:28.683587Z","caller":"traceutil/trace.go:171","msg":"trace[528580958] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1026; }","duration":"374.305287ms","start":"2026-06-13T22:53:28.309254Z","end":"2026-06-13T22:53:28.683559Z","steps":["trace[528580958] 'count revisions from in-memory index tree' (duration: 374.059772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:28.683633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:28.30923Z","time spent":"374.391228ms","remote":"127.0.0.1:44738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:53:29.184214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833962404,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:53:29.337185Z","caller":"traceutil/trace.go:171","msg":"trace[43249558] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"653.328173ms","start":"2026-06-13T22:53:28.683824Z","end":"2026-06-13T22:53:29.337152Z","steps":["trace[43249558] 'read index received' (duration: 653.047208ms)","trace[43249558] 'applied index is now lower than readState.Index' (duration: 279.875µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:29.337268Z","caller":"traceutil/trace.go:171","msg":"trace[1818312061] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"972.150865ms","start":"2026-06-13T22:53:28.36509Z","end":"2026-06-13T22:53:29.337241Z","steps":["trace[1818312061] 'process raft request' (duration: 971.89024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:29.33746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:28.365054Z","time spent":"972.303749ms","remote":"127.0.0.1:44800","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-13T22:53:29.337495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.66009ms","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-13T22:53:29.337546Z","caller":"traceutil/trace.go:171","msg":"trace[303504304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"653.750342ms","start":"2026-06-13T22:53:28.683777Z","end":"2026-06-13T22:53:29.337528Z","steps":["trace[303504304] 'agreement among raft nodes before linearized reading' (duration: 653.568999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:29.337558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.473996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:53:29.337587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:28.68375Z","time spent":"653.826363ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-13T22:53:29.337646Z","caller":"traceutil/trace.go:171","msg":"trace[1382717578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"170.599539ms","start":"2026-06-13T22:53:29.167014Z","end":"2026-06-13T22:53:29.337613Z","steps":["trace[1382717578] 'agreement among raft nodes before linearized reading' (duration: 170.449556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:29.337675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.385116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-13T22:53:29.337714Z","caller":"traceutil/trace.go:171","msg":"trace[342321018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"208.433046ms","start":"2026-06-13T22:53:29.129265Z","end":"2026-06-13T22:53:29.337698Z","steps":["trace[342321018] 'agreement among raft nodes before linearized reading' (duration: 208.336144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:29.33756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.914085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-13T22:53:29.337834Z","caller":"traceutil/trace.go:171","msg":"trace[647366376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"215.210671ms","start":"2026-06-13T22:53:29.12259Z","end":"2026-06-13T22:53:29.337801Z","steps":["trace[647366376] 'agreement among raft nodes before linearized reading' (duration: 214.841384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:30.518725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833962408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-13T22:53:30.991953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.630598633s","expected-duration":"1s"} {"level":"info","ts":"2026-06-13T22:53:30.992319Z","caller":"traceutil/trace.go:171","msg":"trace[1830264163] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.649160846s","start":"2026-06-13T22:53:29.343138Z","end":"2026-06-13T22:53:30.992299Z","steps":["trace[1830264163] 'process raft request' (duration: 1.649019232s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:30.992634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:29.343042Z","time spent":"1.649526853s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:53:30.996893Z","caller":"traceutil/trace.go:171","msg":"trace[1733923236] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1065; }","duration":"978.862419ms","start":"2026-06-13T22:53:30.018012Z","end":"2026-06-13T22:53:30.996875Z","steps":["trace[1733923236] 'read index received' (duration: 974.07775ms)","trace[1733923236] 'applied index is now lower than readState.Index' (duration: 4.783909ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:30.996961Z","caller":"traceutil/trace.go:171","msg":"trace[1010144298] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.653720579s","start":"2026-06-13T22:53:29.343232Z","end":"2026-06-13T22:53:30.996953Z","steps":["trace[1010144298] 'process raft request' (duration: 1.653496065s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:30.997049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:29.343208Z","time spent":"1.653774491s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:53:30.9971Z","caller":"traceutil/trace.go:171","msg":"trace[1872997942] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.65375297s","start":"2026-06-13T22:53:29.34333Z","end":"2026-06-13T22:53:30.997083Z","steps":["trace[1872997942] 'process raft request' (duration: 1.653502545s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:30.997167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:29.343316Z","time spent":"1.653816492s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:53:30.997146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.171877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:53:30.997172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.141724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-13T22:53:30.997232Z","caller":"traceutil/trace.go:171","msg":"trace[1613897823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"829.32147ms","start":"2026-06-13T22:53:30.167889Z","end":"2026-06-13T22:53:30.99721Z","steps":["trace[1613897823] 'agreement among raft nodes before linearized reading' (duration: 829.151547ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:53:30.99725Z","caller":"traceutil/trace.go:171","msg":"trace[412955032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"979.285656ms","start":"2026-06-13T22:53:30.017945Z","end":"2026-06-13T22:53:30.997231Z","steps":["trace[412955032] 'agreement among raft nodes before linearized reading' (duration: 979.048562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:30.997279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:30.167861Z","time spent":"829.410161ms","remote":"127.0.0.1:44496","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-13T22:53:30.997297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:30.017908Z","time spent":"979.380468ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-13T22:53:30.997412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.105862ms","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-13T22:53:30.997442Z","caller":"traceutil/trace.go:171","msg":"trace[382969147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"935.188934ms","start":"2026-06-13T22:53:30.062246Z","end":"2026-06-13T22:53:30.997435Z","steps":["trace[382969147] 'agreement among raft nodes before linearized reading' (duration: 934.754276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:30.997462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:30.062204Z","time spent":"935.254024ms","remote":"127.0.0.1:44800","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":"info","ts":"2026-06-13T22:53:42.730239Z","caller":"traceutil/trace.go:171","msg":"trace[1203350482] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"215.686899ms","start":"2026-06-13T22:53:42.514528Z","end":"2026-06-13T22:53:42.730215Z","steps":["trace[1203350482] 'read index received' (duration: 215.526356ms)","trace[1203350482] 'applied index is now lower than readState.Index' (duration: 159.553µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:42.730365Z","caller":"traceutil/trace.go:171","msg":"trace[713371722] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"406.395477ms","start":"2026-06-13T22:53:42.323919Z","end":"2026-06-13T22:53:42.730315Z","steps":["trace[713371722] 'process raft request' (duration: 406.149573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:42.730463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.910404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:53:42.730498Z","caller":"traceutil/trace.go:171","msg":"trace[603908651] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1068; }","duration":"216.043906ms","start":"2026-06-13T22:53:42.514444Z","end":"2026-06-13T22:53:42.730488Z","steps":["trace[603908651] 'agreement among raft nodes before linearized reading' (duration: 215.863433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:42.730554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:42.323865Z","time spent":"406.590281ms","remote":"127.0.0.1:44800","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-13T22:53:45.303201Z","caller":"traceutil/trace.go:171","msg":"trace[1283432261] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"103.591831ms","start":"2026-06-13T22:53:45.199571Z","end":"2026-06-13T22:53:45.303163Z","steps":["trace[1283432261] 'process raft request' (duration: 90.873277ms)","trace[1283432261] 'compare' (duration: 12.534871ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:55.427018Z","caller":"traceutil/trace.go:171","msg":"trace[1463157136] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"141.710754ms","start":"2026-06-13T22:53:55.285265Z","end":"2026-06-13T22:53:55.426976Z","steps":["trace[1463157136] 'process raft request' (duration: 141.5073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:55.935424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833962616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:53:56.146372Z","caller":"traceutil/trace.go:171","msg":"trace[740029866] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"713.383208ms","start":"2026-06-13T22:53:55.432931Z","end":"2026-06-13T22:53:56.146315Z","steps":["trace[740029866] 'process raft request' (duration: 713.205775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:56.146574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.43291Z","time spent":"713.557672ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:53:56.445513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:53:56.445913Z","caller":"traceutil/trace.go:171","msg":"trace[693912760] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"1.012144302s","start":"2026-06-13T22:53:55.433748Z","end":"2026-06-13T22:53:56.445892Z","steps":["trace[693912760] 'process raft request' (duration: 1.01200565s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:56.446054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.433711Z","time spent":"1.012300164s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:53:56.446097Z","caller":"traceutil/trace.go:171","msg":"trace[830964133] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"1.012886355s","start":"2026-06-13T22:53:55.432975Z","end":"2026-06-13T22:53:56.445861Z","steps":["trace[830964133] 'process raft request' (duration: 849.573917ms)","trace[830964133] 'compare' (duration: 162.516592ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:53:56.446295Z","caller":"traceutil/trace.go:171","msg":"trace[1327691703] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1151; }","duration":"1.011404087s","start":"2026-06-13T22:53:55.434866Z","end":"2026-06-13T22:53:56.44627Z","steps":["trace[1327691703] 'read index received' (duration: 711.279548ms)","trace[1327691703] 'applied index is now lower than readState.Index' (duration: 300.123479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:53:56.446355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.432955Z","time spent":"1.013325483s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:53:56.446419Z","caller":"traceutil/trace.go:171","msg":"trace[1058306855] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"868.225507ms","start":"2026-06-13T22:53:55.578146Z","end":"2026-06-13T22:53:56.446372Z","steps":["trace[1058306855] 'process raft request' (duration: 867.989803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:56.446523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011647712s","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-13T22:53:56.446485Z","caller":"traceutil/trace.go:171","msg":"trace[939446615] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"961.987003ms","start":"2026-06-13T22:53:55.484484Z","end":"2026-06-13T22:53:56.446471Z","steps":["trace[939446615] 'process raft request' (duration: 961.364001ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:53:56.446563Z","caller":"traceutil/trace.go:171","msg":"trace[799392630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"1.011747234s","start":"2026-06-13T22:53:55.434805Z","end":"2026-06-13T22:53:56.446552Z","steps":["trace[799392630] 'agreement among raft nodes before linearized reading' (duration: 1.011591581s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:53:56.446625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.434794Z","time spent":"1.011817665s","remote":"127.0.0.1:44800","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-13T22:53:56.446665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.578123Z","time spent":"868.42066ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:53:56.446703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.484444Z","time spent":"962.180146ms","remote":"127.0.0.1:44800","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-13T22:53:56.587382Z","caller":"traceutil/trace.go:171","msg":"trace[1009673228] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"135.531798ms","start":"2026-06-13T22:53:56.451826Z","end":"2026-06-13T22:53:56.587358Z","steps":["trace[1009673228] 'process raft request' (duration: 135.382205ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:53:56.587524Z","caller":"traceutil/trace.go:171","msg":"trace[1119059073] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"141.132593ms","start":"2026-06-13T22:53:56.446364Z","end":"2026-06-13T22:53:56.587497Z","steps":["trace[1119059073] 'read index received' (duration: 64.295364ms)","trace[1119059073] 'applied index is now lower than readState.Index' (duration: 76.835309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:53:56.623374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.643461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:53:56.623473Z","caller":"traceutil/trace.go:171","msg":"trace[1555206356] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1114; }","duration":"761.821114ms","start":"2026-06-13T22:53:55.861628Z","end":"2026-06-13T22:53:56.623449Z","steps":["trace[1555206356] 'agreement among raft nodes before linearized reading' (duration: 725.967512ms)","trace[1555206356] 'count revisions from in-memory index tree' (duration: 35.683338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:53:56.623532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:55.861604Z","time spent":"761.914066ms","remote":"127.0.0.1:59956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:53:57.560937Z","caller":"traceutil/trace.go:171","msg":"trace[753992003] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"124.751046ms","start":"2026-06-13T22:53:57.436154Z","end":"2026-06-13T22:53:57.560905Z","steps":["trace[753992003] 'process raft request' (duration: 124.311848ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:00.087446Z","caller":"traceutil/trace.go:171","msg":"trace[777924052] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"376.926898ms","start":"2026-06-13T22:53:59.710493Z","end":"2026-06-13T22:54:00.08742Z","steps":["trace[777924052] 'read index received' (duration: 376.741075ms)","trace[777924052] 'applied index is now lower than readState.Index' (duration: 184.963µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:00.087485Z","caller":"traceutil/trace.go:171","msg":"trace[2089853925] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"516.716185ms","start":"2026-06-13T22:53:59.570751Z","end":"2026-06-13T22:54:00.087467Z","steps":["trace[2089853925] 'process raft request' (duration: 516.533902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:00.087572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.07046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:00.087602Z","caller":"traceutil/trace.go:171","msg":"trace[329176940] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1121; }","duration":"377.163542ms","start":"2026-06-13T22:53:59.710428Z","end":"2026-06-13T22:54:00.087591Z","steps":["trace[329176940] 'agreement among raft nodes before linearized reading' (duration: 377.085201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:00.087622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:59.7104Z","time spent":"377.216693ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:54:00.087626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:53:59.570733Z","time spent":"516.813437ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:54:00.320946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.037475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-13T22:54:00.321072Z","caller":"traceutil/trace.go:171","msg":"trace[1670965886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1121; }","duration":"130.237919ms","start":"2026-06-13T22:54:00.1908Z","end":"2026-06-13T22:54:00.321038Z","steps":["trace[1670965886] 'range keys from in-memory index tree' (duration: 129.853631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:13.061967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.075365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:13.062087Z","caller":"traceutil/trace.go:171","msg":"trace[2085769707] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1166; }","duration":"107.426381ms","start":"2026-06-13T22:54:12.954638Z","end":"2026-06-13T22:54:13.062064Z","steps":["trace[2085769707] 'count revisions from in-memory index tree' (duration: 106.907741ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:13.586781Z","caller":"traceutil/trace.go:171","msg":"trace[950872432] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1166; }","duration":"112.229662ms","start":"2026-06-13T22:54:13.474499Z","end":"2026-06-13T22:54:13.586729Z","steps":["trace[950872432] 'process raft request' (duration: 112.059989ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.121663Z","caller":"traceutil/trace.go:171","msg":"trace[1217985466] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"103.411736ms","start":"2026-06-13T22:54:14.018221Z","end":"2026-06-13T22:54:14.121632Z","steps":["trace[1217985466] 'process raft request' (duration: 103.347135ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.121698Z","caller":"traceutil/trace.go:171","msg":"trace[987948795] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"103.60284ms","start":"2026-06-13T22:54:14.018063Z","end":"2026-06-13T22:54:14.121666Z","steps":["trace[987948795] 'process raft request' (duration: 86.361327ms)","trace[987948795] 'compare' (duration: 16.980998ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:14.161415Z","caller":"traceutil/trace.go:171","msg":"trace[691806204] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"130.31532ms","start":"2026-06-13T22:54:14.031045Z","end":"2026-06-13T22:54:14.16136Z","steps":["trace[691806204] 'process raft request' (duration: 130.075556ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.161465Z","caller":"traceutil/trace.go:171","msg":"trace[1910018876] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"130.225478ms","start":"2026-06-13T22:54:14.031208Z","end":"2026-06-13T22:54:14.161434Z","steps":["trace[1910018876] 'process raft request' (duration: 130.035575ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.161454Z","caller":"traceutil/trace.go:171","msg":"trace[1591320152] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"127.392336ms","start":"2026-06-13T22:54:14.034026Z","end":"2026-06-13T22:54:14.161418Z","steps":["trace[1591320152] 'process raft request' (duration: 127.291574ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.161641Z","caller":"traceutil/trace.go:171","msg":"trace[1081932416] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1249; }","duration":"129.046406ms","start":"2026-06-13T22:54:14.03257Z","end":"2026-06-13T22:54:14.161616Z","steps":["trace[1081932416] 'read index received' (duration: 71.871726ms)","trace[1081932416] 'applied index is now lower than readState.Index' (duration: 57.17236ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:14.1617Z","caller":"traceutil/trace.go:171","msg":"trace[62889083] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"114.657137ms","start":"2026-06-13T22:54:14.047018Z","end":"2026-06-13T22:54:14.161675Z","steps":["trace[62889083] 'process raft request' (duration: 114.338751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:14.161932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.336862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xmtt7\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-06-13T22:54:14.161985Z","caller":"traceutil/trace.go:171","msg":"trace[647638973] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xmtt7; range_end:; response_count:1; response_revision:1206; }","duration":"129.424153ms","start":"2026-06-13T22:54:14.032543Z","end":"2026-06-13T22:54:14.161968Z","steps":["trace[647638973] 'agreement among raft nodes before linearized reading' (duration: 129.186178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:14.162085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.900121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-06-13T22:54:14.16216Z","caller":"traceutil/trace.go:171","msg":"trace[1976010677] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1206; }","duration":"114.987353ms","start":"2026-06-13T22:54:14.047135Z","end":"2026-06-13T22:54:14.162122Z","steps":["trace[1976010677] 'agreement among raft nodes before linearized reading' (duration: 114.771419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:14.162361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.721021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9373"} {"level":"info","ts":"2026-06-13T22:54:14.162392Z","caller":"traceutil/trace.go:171","msg":"trace[458515389] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1206; }","duration":"111.776672ms","start":"2026-06-13T22:54:14.050608Z","end":"2026-06-13T22:54:14.162385Z","steps":["trace[458515389] 'agreement among raft nodes before linearized reading' (duration: 111.696271ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.283442Z","caller":"traceutil/trace.go:171","msg":"trace[1324156727] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"106.644297ms","start":"2026-06-13T22:54:14.176762Z","end":"2026-06-13T22:54:14.283406Z","steps":["trace[1324156727] 'process raft request' (duration: 103.778583ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.283939Z","caller":"traceutil/trace.go:171","msg":"trace[1960929560] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"106.973683ms","start":"2026-06-13T22:54:14.176938Z","end":"2026-06-13T22:54:14.283912Z","steps":["trace[1960929560] 'process raft request' (duration: 106.360112ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.283994Z","caller":"traceutil/trace.go:171","msg":"trace[1912077168] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1255; }","duration":"101.99039ms","start":"2026-06-13T22:54:14.181973Z","end":"2026-06-13T22:54:14.283963Z","steps":["trace[1912077168] 'read index received' (duration: 29.037244ms)","trace[1912077168] 'applied index is now lower than readState.Index' (duration: 72.950986ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:14.284357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.352566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9373"} {"level":"info","ts":"2026-06-13T22:54:14.284419Z","caller":"traceutil/trace.go:171","msg":"trace[833992749] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1210; }","duration":"102.4566ms","start":"2026-06-13T22:54:14.181949Z","end":"2026-06-13T22:54:14.284405Z","steps":["trace[833992749] 'agreement among raft nodes before linearized reading' (duration: 102.229575ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:14.28448Z","caller":"traceutil/trace.go:171","msg":"trace[866172044] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"107.379731ms","start":"2026-06-13T22:54:14.177087Z","end":"2026-06-13T22:54:14.284466Z","steps":["trace[866172044] 'process raft request' (duration: 106.538615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:17.667414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833962887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T22:54:17.776045Z","caller":"traceutil/trace.go:171","msg":"trace[989466633] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"931.942859ms","start":"2026-06-13T22:54:16.844069Z","end":"2026-06-13T22:54:17.776012Z","steps":["trace[989466633] 'process raft request' (duration: 931.743146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:17.776249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:16.844056Z","time spent":"932.082852ms","remote":"127.0.0.1:44800","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-13T22:54:17.885721Z","caller":"traceutil/trace.go:171","msg":"trace[1288473472] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1279; }","duration":"719.295858ms","start":"2026-06-13T22:54:17.166391Z","end":"2026-06-13T22:54:17.885686Z","steps":["trace[1288473472] 'read index received' (duration: 609.451351ms)","trace[1288473472] 'applied index is now lower than readState.Index' (duration: 109.842637ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:17.885775Z","caller":"traceutil/trace.go:171","msg":"trace[2041614750] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"842.138218ms","start":"2026-06-13T22:54:17.043602Z","end":"2026-06-13T22:54:17.88574Z","steps":["trace[2041614750] 'process raft request' (duration: 841.881274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:17.885891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.476452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:17.885992Z","caller":"traceutil/trace.go:171","msg":"trace[764587354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"719.637555ms","start":"2026-06-13T22:54:17.166333Z","end":"2026-06-13T22:54:17.88597Z","steps":["trace[764587354] 'agreement among raft nodes before linearized reading' (duration: 719.457561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:17.886054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:17.166307Z","time spent":"719.731567ms","remote":"127.0.0.1:44496","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-13T22:54:17.886292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:17.043567Z","time spent":"842.485465ms","remote":"127.0.0.1:44800","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-13T22:54:24.704014Z","caller":"traceutil/trace.go:171","msg":"trace[1059602124] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"140.344287ms","start":"2026-06-13T22:54:24.563639Z","end":"2026-06-13T22:54:24.703983Z","steps":["trace[1059602124] 'process raft request' (duration: 139.93696ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:31.980129Z","caller":"traceutil/trace.go:171","msg":"trace[1258472157] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"118.109952ms","start":"2026-06-13T22:54:31.862003Z","end":"2026-06-13T22:54:31.980113Z","steps":["trace[1258472157] 'process raft request' (duration: 118.02914ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:36.255979Z","caller":"traceutil/trace.go:171","msg":"trace[920464489] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"187.422499ms","start":"2026-06-13T22:54:36.068533Z","end":"2026-06-13T22:54:36.255956Z","steps":["trace[920464489] 'process raft request' (duration: 187.286997ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:36.966957Z","caller":"traceutil/trace.go:171","msg":"trace[1214033781] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"178.577494ms","start":"2026-06-13T22:54:36.788339Z","end":"2026-06-13T22:54:36.966917Z","steps":["trace[1214033781] 'process raft request' (duration: 178.39452ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:36.966968Z","caller":"traceutil/trace.go:171","msg":"trace[1711664659] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"135.472377ms","start":"2026-06-13T22:54:36.831399Z","end":"2026-06-13T22:54:36.966872Z","steps":["trace[1711664659] 'read index received' (duration: 135.251713ms)","trace[1711664659] 'applied index is now lower than readState.Index' (duration: 219.964µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:36.967174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.660556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-13T22:54:36.967162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.709771ms","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-13T22:54:36.967207Z","caller":"traceutil/trace.go:171","msg":"trace[703198301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"130.724898ms","start":"2026-06-13T22:54:36.836474Z","end":"2026-06-13T22:54:36.967199Z","steps":["trace[703198301] 'agreement among raft nodes before linearized reading' (duration: 130.594185ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:36.967232Z","caller":"traceutil/trace.go:171","msg":"trace[345235400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"135.855734ms","start":"2026-06-13T22:54:36.831357Z","end":"2026-06-13T22:54:36.967213Z","steps":["trace[345235400] 'agreement among raft nodes before linearized reading' (duration: 135.6179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:37.487978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.940027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:54:37.488091Z","caller":"traceutil/trace.go:171","msg":"trace[594265633] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"517.419969ms","start":"2026-06-13T22:54:36.97065Z","end":"2026-06-13T22:54:37.48807Z","steps":["trace[594265633] 'process raft request' (duration: 194.297188ms)","trace[594265633] 'compare' (duration: 322.829056ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:37.48815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:36.970629Z","time spent":"517.4934ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:54:37.85106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.6113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:54:37.851228Z","caller":"traceutil/trace.go:171","msg":"trace[1107877784] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"445.617794ms","start":"2026-06-13T22:54:37.405589Z","end":"2026-06-13T22:54:37.851206Z","steps":["trace[1107877784] 'read index received' (duration: 325.673449ms)","trace[1107877784] 'applied index is now lower than readState.Index' (duration: 119.942975ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:37.851327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.742386ms","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:7"} {"level":"info","ts":"2026-06-13T22:54:37.85136Z","caller":"traceutil/trace.go:171","msg":"trace[55365026] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1304; }","duration":"445.827228ms","start":"2026-06-13T22:54:37.405523Z","end":"2026-06-13T22:54:37.851351Z","steps":["trace[55365026] 'agreement among raft nodes before linearized reading' (duration: 445.740266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:37.851398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:37.405501Z","time spent":"445.887519ms","remote":"127.0.0.1:40548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:54:37.851721Z","caller":"traceutil/trace.go:171","msg":"trace[1793497073] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"880.195172ms","start":"2026-06-13T22:54:36.971513Z","end":"2026-06-13T22:54:37.851708Z","steps":["trace[1793497073] 'process raft request' (duration: 759.855409ms)","trace[1793497073] 'compare' (duration: 119.500387ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:37.851782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:36.971505Z","time spent":"880.250053ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:54:38.056728Z","caller":"traceutil/trace.go:171","msg":"trace[1796671549] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"202.867259ms","start":"2026-06-13T22:54:37.853835Z","end":"2026-06-13T22:54:38.056703Z","steps":["trace[1796671549] 'process raft request' (duration: 108.808738ms)","trace[1796671549] 'compare' (duration: 93.769206ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:38.312591Z","caller":"traceutil/trace.go:171","msg":"trace[1643608351] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"188.50247ms","start":"2026-06-13T22:54:38.124067Z","end":"2026-06-13T22:54:38.31257Z","steps":["trace[1643608351] 'read index received' (duration: 188.306817ms)","trace[1643608351] 'applied index is now lower than readState.Index' (duration: 194.823µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:38.312702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.726524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:54:38.312735Z","caller":"traceutil/trace.go:171","msg":"trace[1877745535] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1307; }","duration":"188.815256ms","start":"2026-06-13T22:54:38.12391Z","end":"2026-06-13T22:54:38.312725Z","steps":["trace[1877745535] 'agreement among raft nodes before linearized reading' (duration: 188.713084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:38.312743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.682947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:38.312774Z","caller":"traceutil/trace.go:171","msg":"trace[32641012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"146.752348ms","start":"2026-06-13T22:54:38.166011Z","end":"2026-06-13T22:54:38.312763Z","steps":["trace[32641012] 'agreement among raft nodes before linearized reading' (duration: 146.700267ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:38.312633Z","caller":"traceutil/trace.go:171","msg":"trace[1051845343] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"193.3022ms","start":"2026-06-13T22:54:38.119294Z","end":"2026-06-13T22:54:38.312597Z","steps":["trace[1051845343] 'process raft request' (duration: 193.146637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.162917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.443461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:54:39.163145Z","caller":"traceutil/trace.go:171","msg":"trace[96200522] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1362; }","duration":"619.832945ms","start":"2026-06-13T22:54:38.54329Z","end":"2026-06-13T22:54:39.163123Z","steps":["trace[96200522] 'read index received' (duration: 263.141797ms)","trace[96200522] 'applied index is now lower than readState.Index' (duration: 356.689948ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:39.163493Z","caller":"traceutil/trace.go:171","msg":"trace[374427302] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"843.21689ms","start":"2026-06-13T22:54:38.320234Z","end":"2026-06-13T22:54:39.163451Z","steps":["trace[374427302] 'process raft request' (duration: 68.104496ms)","trace[374427302] 'compare' (duration: 773.944122ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:39.163571Z","caller":"traceutil/trace.go:171","msg":"trace[279222084] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"816.144372ms","start":"2026-06-13T22:54:38.34739Z","end":"2026-06-13T22:54:39.163535Z","steps":["trace[279222084] 'process raft request' (duration: 815.652042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.163656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:38.320218Z","time spent":"843.360202ms","remote":"127.0.0.1:44710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:54:39.163706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:38.347375Z","time spent":"816.264904ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:54:39.163781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.483218ms","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-13T22:54:39.163842Z","caller":"traceutil/trace.go:171","msg":"trace[1235881404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"620.58604ms","start":"2026-06-13T22:54:38.543239Z","end":"2026-06-13T22:54:39.163825Z","steps":["trace[1235881404] 'agreement among raft nodes before linearized reading' (duration: 620.386436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.163886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:38.543217Z","time spent":"620.658801ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-13T22:54:39.663427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833963081,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-13T22:54:39.908175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.356266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:54:39.90832Z","caller":"traceutil/trace.go:171","msg":"trace[15791795] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"936.504745ms","start":"2026-06-13T22:54:38.971771Z","end":"2026-06-13T22:54:39.908275Z","steps":["trace[15791795] 'process raft request' (duration: 771.955224ms)","trace[15791795] 'compare' (duration: 164.195644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:54:39.908395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:38.97174Z","time spent":"936.622568ms","remote":"127.0.0.1:44800","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-13T22:54:39.909861Z","caller":"traceutil/trace.go:171","msg":"trace[2062092531] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1364; }","duration":"746.652101ms","start":"2026-06-13T22:54:39.163179Z","end":"2026-06-13T22:54:39.909832Z","steps":["trace[2062092531] 'read index received' (duration: 580.550741ms)","trace[2062092531] 'applied index is now lower than readState.Index' (duration: 166.09997ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:39.909937Z","caller":"traceutil/trace.go:171","msg":"trace[1120634878] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"741.848491ms","start":"2026-06-13T22:54:39.168028Z","end":"2026-06-13T22:54:39.909876Z","steps":["trace[1120634878] 'process raft request' (duration: 741.660827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.910113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:39.168018Z","time spent":"741.999963ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:54:39.910352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.911501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:39.9104Z","caller":"traceutil/trace.go:171","msg":"trace[1103959105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"742.996132ms","start":"2026-06-13T22:54:39.167392Z","end":"2026-06-13T22:54:39.910388Z","steps":["trace[1103959105] 'agreement among raft nodes before linearized reading' (duration: 742.87501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.91043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:39.167377Z","time spent":"743.046934ms","remote":"127.0.0.1:44496","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-13T22:54:39.910458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.953187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T22:54:39.910478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.343083ms","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-13T22:54:39.910524Z","caller":"traceutil/trace.go:171","msg":"trace[1233949977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1312; }","duration":"418.406784ms","start":"2026-06-13T22:54:39.492106Z","end":"2026-06-13T22:54:39.910512Z","steps":["trace[1233949977] 'agreement among raft nodes before linearized reading' (duration: 418.253532ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:39.910524Z","caller":"traceutil/trace.go:171","msg":"trace[1998228951] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1312; }","duration":"441.027078ms","start":"2026-06-13T22:54:39.46948Z","end":"2026-06-13T22:54:39.910508Z","steps":["trace[1998228951] 'agreement among raft nodes before linearized reading' (duration: 440.902456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.910433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291089794s","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":"warn","ts":"2026-06-13T22:54:39.910581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:39.469412Z","time spent":"441.15956ms","remote":"127.0.0.1:44738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-06-13T22:54:39.910617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.114913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-13T22:54:39.910647Z","caller":"traceutil/trace.go:171","msg":"trace[1263978215] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1312; }","duration":"743.150716ms","start":"2026-06-13T22:54:39.167488Z","end":"2026-06-13T22:54:39.910639Z","steps":["trace[1263978215] 'agreement among raft nodes before linearized reading' (duration: 743.094545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.910647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199262145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-13T22:54:39.91067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:39.167485Z","time spent":"743.180346ms","remote":"127.0.0.1:44928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-06-13T22:54:39.91057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:39.4921Z","time spent":"418.463135ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-13T22:54:39.910694Z","caller":"traceutil/trace.go:171","msg":"trace[1631234406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"1.199397777s","start":"2026-06-13T22:54:38.711283Z","end":"2026-06-13T22:54:39.91068Z","steps":["trace[1631234406] 'agreement among raft nodes before linearized reading' (duration: 1.199041521s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.910788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:38.71126Z","time spent":"1.19951667s","remote":"127.0.0.1:44800","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-13T22:54:39.910844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.799148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:54:39.911057Z","caller":"traceutil/trace.go:171","msg":"trace[684416542] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1312; }","duration":"876.39938ms","start":"2026-06-13T22:54:39.034641Z","end":"2026-06-13T22:54:39.91104Z","steps":["trace[684416542] 'agreement among raft nodes before linearized reading' (duration: 875.632155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.911371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:39.034609Z","time spent":"876.750717ms","remote":"127.0.0.1:59944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:54:39.910676Z","caller":"traceutil/trace.go:171","msg":"trace[1033407132] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1312; }","duration":"1.29138202s","start":"2026-06-13T22:54:38.619231Z","end":"2026-06-13T22:54:39.910613Z","steps":["trace[1033407132] 'agreement among raft nodes before linearized reading' (duration: 1.290991623s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:39.911554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:38.619209Z","time spent":"1.292298457s","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:54:48.788848Z","caller":"traceutil/trace.go:171","msg":"trace[1489289823] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1447; }","duration":"288.90006ms","start":"2026-06-13T22:54:48.499912Z","end":"2026-06-13T22:54:48.788812Z","steps":["trace[1489289823] 'read index received' (duration: 288.594404ms)","trace[1489289823] 'applied index is now lower than readState.Index' (duration: 304.356µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:48.788974Z","caller":"traceutil/trace.go:171","msg":"trace[111524137] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"298.833255ms","start":"2026-06-13T22:54:48.4901Z","end":"2026-06-13T22:54:48.788933Z","steps":["trace[111524137] 'process raft request' (duration: 298.493009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:48.789317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.332868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:54:48.789514Z","caller":"traceutil/trace.go:171","msg":"trace[46186138] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1392; }","duration":"289.572912ms","start":"2026-06-13T22:54:48.499869Z","end":"2026-06-13T22:54:48.789442Z","steps":["trace[46186138] 'agreement among raft nodes before linearized reading' (duration: 289.171644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:49.947636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394020833963238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-13T22:54:50.226157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389741023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-13T22:54:50.226276Z","caller":"traceutil/trace.go:171","msg":"trace[875032089] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1392; }","duration":"1.389911366s","start":"2026-06-13T22:54:48.83633Z","end":"2026-06-13T22:54:50.226242Z","steps":["trace[875032089] 'range keys from in-memory index tree' (duration: 1.389534529s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.226342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:48.836309Z","time spent":"1.390023328s","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-13T22:54:50.227139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059759723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:50.227235Z","caller":"traceutil/trace.go:171","msg":"trace[1085078840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"1.059895766s","start":"2026-06-13T22:54:49.167317Z","end":"2026-06-13T22:54:50.227213Z","steps":["trace[1085078840] 'range keys from in-memory index tree' (duration: 1.059659661s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.227591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.167291Z","time spent":"1.060284733s","remote":"127.0.0.1:44496","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-13T22:54:50.227732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.288128ms","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-13T22:54:50.227782Z","caller":"traceutil/trace.go:171","msg":"trace[1125807793] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1392; }","duration":"963.376909ms","start":"2026-06-13T22:54:49.264393Z","end":"2026-06-13T22:54:50.22777Z","steps":["trace[1125807793] 'count revisions from in-memory index tree' (duration: 963.180645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.227825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.264367Z","time spent":"963.4482ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-13T22:54:50.228255Z","caller":"traceutil/trace.go:171","msg":"trace[1656509299] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"781.040275ms","start":"2026-06-13T22:54:49.447199Z","end":"2026-06-13T22:54:50.22824Z","steps":["trace[1656509299] 'read index received' (duration: 775.982041ms)","trace[1656509299] 'applied index is now lower than readState.Index' (duration: 5.056784ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:50.228363Z","caller":"traceutil/trace.go:171","msg":"trace[79385078] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"851.172658ms","start":"2026-06-13T22:54:49.377174Z","end":"2026-06-13T22:54:50.228346Z","steps":["trace[79385078] 'process raft request' (duration: 845.976531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.228249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.685314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-13T22:54:50.228469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.377144Z","time spent":"851.267219ms","remote":"127.0.0.1:44800","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-13T22:54:50.228586Z","caller":"traceutil/trace.go:171","msg":"trace[1469624816] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1392; }","duration":"973.643611ms","start":"2026-06-13T22:54:49.254849Z","end":"2026-06-13T22:54:50.228493Z","steps":["trace[1469624816] 'range keys from in-memory index tree' (duration: 972.521531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.228754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.562065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:88441"} {"level":"info","ts":"2026-06-13T22:54:50.228789Z","caller":"traceutil/trace.go:171","msg":"trace[956371139] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1393; }","duration":"781.617266ms","start":"2026-06-13T22:54:49.447162Z","end":"2026-06-13T22:54:50.22878Z","steps":["trace[956371139] 'agreement among raft nodes before linearized reading' (duration: 781.513764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.22881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.447151Z","time spent":"781.654587ms","remote":"127.0.0.1:44582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":88464,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-13T22:54:50.22877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.254836Z","time spent":"973.913096ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-13T22:54:50.230095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.8666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-13T22:54:50.230175Z","caller":"traceutil/trace.go:171","msg":"trace[770714073] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1393; }","duration":"782.975221ms","start":"2026-06-13T22:54:49.44716Z","end":"2026-06-13T22:54:50.230135Z","steps":["trace[770714073] 'agreement among raft nodes before linearized reading' (duration: 781.187997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.230218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.44714Z","time spent":"783.068993ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-06-13T22:54:50.270599Z","caller":"traceutil/trace.go:171","msg":"trace[1048831286] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"313.464619ms","start":"2026-06-13T22:54:49.95711Z","end":"2026-06-13T22:54:50.270574Z","steps":["trace[1048831286] 'process raft request' (duration: 313.213054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.270958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.957078Z","time spent":"313.598871ms","remote":"127.0.0.1:44800","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-13T22:54:50.271039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.389624ms","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-13T22:54:50.271117Z","caller":"traceutil/trace.go:171","msg":"trace[659990181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1394; }","duration":"308.505856ms","start":"2026-06-13T22:54:49.962592Z","end":"2026-06-13T22:54:50.271098Z","steps":["trace[659990181] 'agreement among raft nodes before linearized reading' (duration: 308.360113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.271166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:49.962568Z","time spent":"308.589788ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-13T22:54:50.271153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.765744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-13T22:54:50.271479Z","caller":"traceutil/trace.go:171","msg":"trace[1153600510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1394; }","duration":"213.162661ms","start":"2026-06-13T22:54:50.058295Z","end":"2026-06-13T22:54:50.271458Z","steps":["trace[1153600510] 'agreement among raft nodes before linearized reading' (duration: 212.633342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.271276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.953702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:50.27155Z","caller":"traceutil/trace.go:171","msg":"trace[1552580777] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1394; }","duration":"116.284577ms","start":"2026-06-13T22:54:50.155242Z","end":"2026-06-13T22:54:50.271526Z","steps":["trace[1552580777] 'agreement among raft nodes before linearized reading' (duration: 115.964681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.271342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.812253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-06-13T22:54:50.271549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.005267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-13T22:54:50.271765Z","caller":"traceutil/trace.go:171","msg":"trace[1153512083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1394; }","duration":"206.360204ms","start":"2026-06-13T22:54:50.065383Z","end":"2026-06-13T22:54:50.271743Z","steps":["trace[1153512083] 'agreement among raft nodes before linearized reading' (duration: 205.916426ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:50.271658Z","caller":"traceutil/trace.go:171","msg":"trace[1433643308] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1394; }","duration":"136.17556ms","start":"2026-06-13T22:54:50.135468Z","end":"2026-06-13T22:54:50.271644Z","steps":["trace[1433643308] 'agreement among raft nodes before linearized reading' (duration: 135.810613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.271388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.193528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-13T22:54:50.271999Z","caller":"traceutil/trace.go:171","msg":"trace[932128139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1394; }","duration":"201.295519ms","start":"2026-06-13T22:54:50.070688Z","end":"2026-06-13T22:54:50.271984Z","steps":["trace[932128139] 'agreement among raft nodes before linearized reading' (duration: 200.081686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.271355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.873426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:54:50.272091Z","caller":"traceutil/trace.go:171","msg":"trace[548120017] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1394; }","duration":"110.635392ms","start":"2026-06-13T22:54:50.161445Z","end":"2026-06-13T22:54:50.27208Z","steps":["trace[548120017] 'agreement among raft nodes before linearized reading' (duration: 109.875947ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:50.553426Z","caller":"traceutil/trace.go:171","msg":"trace[3740123] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1450; }","duration":"282.878797ms","start":"2026-06-13T22:54:50.27052Z","end":"2026-06-13T22:54:50.553399Z","steps":["trace[3740123] 'read index received' (duration: 282.746154ms)","trace[3740123] 'applied index is now lower than readState.Index' (duration: 131.643µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:54:50.553571Z","caller":"traceutil/trace.go:171","msg":"trace[2113573218] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"320.032573ms","start":"2026-06-13T22:54:50.233519Z","end":"2026-06-13T22:54:50.553552Z","steps":["trace[2113573218] 'process raft request' (duration: 319.666836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.553587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.254333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:54:50.553623Z","caller":"traceutil/trace.go:171","msg":"trace[1036115690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1396; }","duration":"323.334034ms","start":"2026-06-13T22:54:50.230278Z","end":"2026-06-13T22:54:50.553612Z","steps":["trace[1036115690] 'agreement among raft nodes before linearized reading' (duration: 323.254063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.553666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:50.230258Z","time spent":"323.400096ms","remote":"127.0.0.1:44488","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-13T22:54:50.553625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.594173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6185"} {"level":"warn","ts":"2026-06-13T22:54:50.553704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:50.233505Z","time spent":"320.110964ms","remote":"127.0.0.1:44800","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-13T22:54:50.553733Z","caller":"traceutil/trace.go:171","msg":"trace[2051441661] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1396; }","duration":"320.737385ms","start":"2026-06-13T22:54:50.232989Z","end":"2026-06-13T22:54:50.553727Z","steps":["trace[2051441661] 'agreement among raft nodes before linearized reading' (duration: 320.44756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.553754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:50.232963Z","time spent":"320.784847ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":5,"response size":6208,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-06-13T22:54:50.553477Z","caller":"traceutil/trace.go:171","msg":"trace[1213403992] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"319.709467ms","start":"2026-06-13T22:54:50.233729Z","end":"2026-06-13T22:54:50.553439Z","steps":["trace[1213403992] 'process raft request' (duration: 319.620435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.554079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:50.233699Z","time spent":"320.322088ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:54:50.55465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.100531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6185"} {"level":"info","ts":"2026-06-13T22:54:50.554794Z","caller":"traceutil/trace.go:171","msg":"trace[2005190158] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1396; }","duration":"322.285825ms","start":"2026-06-13T22:54:50.232478Z","end":"2026-06-13T22:54:50.554764Z","steps":["trace[2005190158] 'agreement among raft nodes before linearized reading' (duration: 321.723275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.555013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:54:50.232468Z","time spent":"322.52888ms","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":5,"response size":6208,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-06-13T22:54:50.558763Z","caller":"traceutil/trace.go:171","msg":"trace[668366259] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"281.917809ms","start":"2026-06-13T22:54:50.276823Z","end":"2026-06-13T22:54:50.558741Z","steps":["trace[668366259] 'process raft request' (duration: 281.815287ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:50.559066Z","caller":"traceutil/trace.go:171","msg":"trace[134159957] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"282.278876ms","start":"2026-06-13T22:54:50.27677Z","end":"2026-06-13T22:54:50.559048Z","steps":["trace[134159957] 'process raft request' (duration: 281.741295ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:50.559206Z","caller":"traceutil/trace.go:171","msg":"trace[1813076407] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"282.325137ms","start":"2026-06-13T22:54:50.276857Z","end":"2026-06-13T22:54:50.559182Z","steps":["trace[1813076407] 'process raft request' (duration: 281.841057ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:50.559457Z","caller":"traceutil/trace.go:171","msg":"trace[1514442896] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"281.516902ms","start":"2026-06-13T22:54:50.277901Z","end":"2026-06-13T22:54:50.559418Z","steps":["trace[1514442896] 'process raft request' (duration: 281.086204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:54:50.559575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.125432ms","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-13T22:54:50.560296Z","caller":"traceutil/trace.go:171","msg":"trace[1750951538] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1401; }","duration":"259.870905ms","start":"2026-06-13T22:54:50.300401Z","end":"2026-06-13T22:54:50.560271Z","steps":["trace[1750951538] 'agreement among raft nodes before linearized reading' (duration: 259.02428ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:54:50.559711Z","caller":"traceutil/trace.go:171","msg":"trace[875411210] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"281.164975ms","start":"2026-06-13T22:54:50.278524Z","end":"2026-06-13T22:54:50.559689Z","steps":["trace[875411210] 'process raft request' (duration: 280.786618ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:55:10.831563Z","caller":"traceutil/trace.go:171","msg":"trace[1909527020] linearizableReadLoop","detail":"{readStateIndex:1549; appliedIndex:1548; }","duration":"155.008159ms","start":"2026-06-13T22:55:10.676522Z","end":"2026-06-13T22:55:10.83153Z","steps":["trace[1909527020] 'read index received' (duration: 154.652651ms)","trace[1909527020] 'applied index is now lower than readState.Index' (duration: 353.418µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:55:10.831598Z","caller":"traceutil/trace.go:171","msg":"trace[1102555452] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"194.110035ms","start":"2026-06-13T22:55:10.637456Z","end":"2026-06-13T22:55:10.831567Z","steps":["trace[1102555452] 'process raft request' (duration: 193.841019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:55:10.831883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.379626ms","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-13T22:55:10.831975Z","caller":"traceutil/trace.go:171","msg":"trace[2139748490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1489; }","duration":"155.507428ms","start":"2026-06-13T22:55:10.676445Z","end":"2026-06-13T22:55:10.831952Z","steps":["trace[2139748490] 'agreement among raft nodes before linearized reading' (duration: 155.224783ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:58:50.234697Z","caller":"traceutil/trace.go:171","msg":"trace[1471924530] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"101.312348ms","start":"2026-06-13T22:58:50.133351Z","end":"2026-06-13T22:58:50.234663Z","steps":["trace[1471924530] 'process raft request' (duration: 101.036973ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:58:50.351613Z","caller":"traceutil/trace.go:171","msg":"trace[1347706887] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"105.173785ms","start":"2026-06-13T22:58:50.246406Z","end":"2026-06-13T22:58:50.35158Z","steps":["trace[1347706887] 'process raft request' (duration: 105.062723ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:58:50.351651Z","caller":"traceutil/trace.go:171","msg":"trace[1616498711] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"106.446902ms","start":"2026-06-13T22:58:50.245139Z","end":"2026-06-13T22:58:50.351586Z","steps":["trace[1616498711] 'process raft request' (duration: 13.88618ms)","trace[1616498711] 'compare' (duration: 92.262326ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:58:50.351869Z","caller":"traceutil/trace.go:171","msg":"trace[459241440] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"105.281089ms","start":"2026-06-13T22:58:50.24657Z","end":"2026-06-13T22:58:50.351851Z","steps":["trace[459241440] 'process raft request' (duration: 105.148346ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:58:50.351973Z","caller":"traceutil/trace.go:171","msg":"trace[1832573850] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"105.435372ms","start":"2026-06-13T22:58:50.246526Z","end":"2026-06-13T22:58:50.351962Z","steps":["trace[1832573850] 'process raft request' (duration: 105.001992ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:58:51.512823Z","caller":"traceutil/trace.go:171","msg":"trace[1393383603] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"150.468567ms","start":"2026-06-13T22:58:51.362312Z","end":"2026-06-13T22:58:51.51278Z","steps":["trace[1393383603] 'process raft request' (duration: 150.230173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:58:51.981447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.369417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-06-13T22:58:51.981533Z","caller":"traceutil/trace.go:171","msg":"trace[2058292144] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2529; }","duration":"338.51736ms","start":"2026-06-13T22:58:51.642997Z","end":"2026-06-13T22:58:51.981514Z","steps":["trace[2058292144] 'range keys from in-memory index tree' (duration: 338.183252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:58:51.981569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:58:51.642968Z","time spent":"338.594171ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "}