{"level":"warn","ts":"2026-04-21T18:15:04.674985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T18:15:04.675252Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.31:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.31:2380","--initial-cluster=instance=https://199.19.213.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.31:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-21T18:15:04.675333Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T18:15:04.675343Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.31:2380"]} {"level":"info","ts":"2026-04-21T18:15:04.67536Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T18:15:04.676011Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"]} {"level":"info","ts":"2026-04-21T18:15:04.676082Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.31:2380"],"listen-peer-urls":["https://199.19.213.31:2380"],"advertise-client-urls":["https://199.19.213.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.31:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-21T18:15:04.6861Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.203605ms"} {"level":"info","ts":"2026-04-21T18:15:04.694743Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b3aa5b67741e7c5e","cluster-id":"9896be1db1fb000"} {"level":"info","ts":"2026-04-21T18:15:04.694822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T18:15:04.694929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became follower at term 0"} {"level":"info","ts":"2026-04-21T18:15:04.694945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b3aa5b67741e7c5e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T18:15:04.694956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became follower at term 1"} {"level":"info","ts":"2026-04-21T18:15:04.695008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=(12946260578717826142)"} {"level":"warn","ts":"2026-04-21T18:15:04.70171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T18:15:04.702854Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T18:15:04.703739Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-21T18:15:04.705575Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b3aa5b67741e7c5e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T18:15:04.706047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b3aa5b67741e7c5e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T18:15:04.706065Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T18:15:04.706121Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T18:15:04.706133Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T18:15:04.708011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=(12946260578717826142)"} {"level":"info","ts":"2026-04-21T18:15:04.708149Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9896be1db1fb000","local-member-id":"b3aa5b67741e7c5e","added-peer-id":"b3aa5b67741e7c5e","added-peer-peer-urls":["https://199.19.213.31:2380"]} {"level":"info","ts":"2026-04-21T18:15:04.711384Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T18:15:04.711567Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.31:2380"} {"level":"info","ts":"2026-04-21T18:15:04.711632Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.31:2380"} {"level":"info","ts":"2026-04-21T18:15:04.71187Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b3aa5b67741e7c5e","initial-advertise-peer-urls":["https://199.19.213.31:2380"],"listen-peer-urls":["https://199.19.213.31:2380"],"advertise-client-urls":["https://199.19.213.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T18:15:04.711939Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T18:15:05.595628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T18:15:05.595708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T18:15:05.595731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e received MsgPreVoteResp from b3aa5b67741e7c5e at term 1"} {"level":"info","ts":"2026-04-21T18:15:05.59575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became candidate at term 2"} {"level":"info","ts":"2026-04-21T18:15:05.595765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e received MsgVoteResp from b3aa5b67741e7c5e at term 2"} {"level":"info","ts":"2026-04-21T18:15:05.595787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became leader at term 2"} {"level":"info","ts":"2026-04-21T18:15:05.595803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b3aa5b67741e7c5e elected leader b3aa5b67741e7c5e at term 2"} {"level":"info","ts":"2026-04-21T18:15:05.596586Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:05.597545Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:15:05.597507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b3aa5b67741e7c5e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.31:2379]}","request-path":"/0/members/b3aa5b67741e7c5e/attributes","cluster-id":"9896be1db1fb000","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T18:15:05.597635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:15:05.597921Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T18:15:05.597957Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T18:15:05.597964Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9896be1db1fb000","local-member-id":"b3aa5b67741e7c5e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:05.598095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:05.598133Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:05.602431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T18:15:05.605003Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.31:2379"} {"level":"info","ts":"2026-04-21T18:15:35.278101Z","caller":"traceutil/trace.go:171","msg":"trace[1646956540] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"113.587396ms","start":"2026-04-21T18:15:35.164489Z","end":"2026-04-21T18:15:35.278076Z","steps":["trace[1646956540] 'process raft request' (duration: 109.132891ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.282429Z","caller":"traceutil/trace.go:171","msg":"trace[625828245] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"111.962511ms","start":"2026-04-21T18:15:35.170453Z","end":"2026-04-21T18:15:35.282415Z","steps":["trace[625828245] 'process raft request' (duration: 111.804818ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.282597Z","caller":"traceutil/trace.go:171","msg":"trace[593566672] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"112.363329ms","start":"2026-04-21T18:15:35.170226Z","end":"2026-04-21T18:15:35.282589Z","steps":["trace[593566672] 'process raft request' (duration: 111.94271ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.28273Z","caller":"traceutil/trace.go:171","msg":"trace[1748075674] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"107.381883ms","start":"2026-04-21T18:15:35.175328Z","end":"2026-04-21T18:15:35.28271Z","steps":["trace[1748075674] 'process raft request' (duration: 107.24308ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283Z","caller":"traceutil/trace.go:171","msg":"trace[352318102] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"112.506262ms","start":"2026-04-21T18:15:35.170486Z","end":"2026-04-21T18:15:35.282992Z","steps":["trace[352318102] 'process raft request' (duration: 111.852349ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283156Z","caller":"traceutil/trace.go:171","msg":"trace[2076720576] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"110.894067ms","start":"2026-04-21T18:15:35.172255Z","end":"2026-04-21T18:15:35.283149Z","steps":["trace[2076720576] 'process raft request' (duration: 110.105101ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283276Z","caller":"traceutil/trace.go:171","msg":"trace[2146434989] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"110.962279ms","start":"2026-04-21T18:15:35.172289Z","end":"2026-04-21T18:15:35.283251Z","steps":["trace[2146434989] 'process raft request' (duration: 110.08509ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283382Z","caller":"traceutil/trace.go:171","msg":"trace[1157769089] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"110.640302ms","start":"2026-04-21T18:15:35.172733Z","end":"2026-04-21T18:15:35.283374Z","steps":["trace[1157769089] 'process raft request' (duration: 109.678482ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283456Z","caller":"traceutil/trace.go:171","msg":"trace[508474116] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"110.648134ms","start":"2026-04-21T18:15:35.172803Z","end":"2026-04-21T18:15:35.283451Z","steps":["trace[508474116] 'process raft request' (duration: 109.633602ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283531Z","caller":"traceutil/trace.go:171","msg":"trace[414296337] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"109.718844ms","start":"2026-04-21T18:15:35.173806Z","end":"2026-04-21T18:15:35.283525Z","steps":["trace[414296337] 'process raft request' (duration: 108.650841ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283577Z","caller":"traceutil/trace.go:171","msg":"trace[224032587] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"108.63899ms","start":"2026-04-21T18:15:35.174924Z","end":"2026-04-21T18:15:35.283563Z","steps":["trace[224032587] 'process raft request' (duration: 107.627448ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:35.283168Z","caller":"traceutil/trace.go:171","msg":"trace[1920805812] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"105.063385ms","start":"2026-04-21T18:15:35.178095Z","end":"2026-04-21T18:15:35.283158Z","steps":["trace[1920805812] 'process raft request' (duration: 104.559074ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:41.689932Z","caller":"traceutil/trace.go:171","msg":"trace[655121914] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"163.559342ms","start":"2026-04-21T18:15:41.526307Z","end":"2026-04-21T18:15:41.689866Z","steps":["trace[655121914] 'process raft request' (duration: 163.085032ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:41.852892Z","caller":"traceutil/trace.go:171","msg":"trace[212609078] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"159.258912ms","start":"2026-04-21T18:15:41.693603Z","end":"2026-04-21T18:15:41.852862Z","steps":["trace[212609078] 'process raft request' (duration: 68.50108ms)","trace[212609078] 'compare' (duration: 90.63417ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:41.89031Z","caller":"traceutil/trace.go:171","msg":"trace[1337987406] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"169.929028ms","start":"2026-04-21T18:15:41.720358Z","end":"2026-04-21T18:15:41.890287Z","steps":["trace[1337987406] 'process raft request' (duration: 169.427108ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:42.379354Z","caller":"traceutil/trace.go:171","msg":"trace[1975456974] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"310.531016ms","start":"2026-04-21T18:15:42.068784Z","end":"2026-04-21T18:15:42.379315Z","steps":["trace[1975456974] 'read index received' (duration: 310.229988ms)","trace[1975456974] 'applied index is now lower than readState.Index' (duration: 299.848µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:42.379457Z","caller":"traceutil/trace.go:171","msg":"trace[792354791] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"414.744133ms","start":"2026-04-21T18:15:41.964657Z","end":"2026-04-21T18:15:42.379401Z","steps":["trace[792354791] 'process raft request' (duration: 414.429256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:42.379619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.158528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:15:42.379716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.901673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T18:15:42.381121Z","caller":"traceutil/trace.go:171","msg":"trace[781945004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"268.845373ms","start":"2026-04-21T18:15:42.112263Z","end":"2026-04-21T18:15:42.381109Z","steps":["trace[781945004] 'agreement among raft nodes before linearized reading' (duration: 267.28097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:42.38003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.518624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:15:42.381169Z","caller":"traceutil/trace.go:171","msg":"trace[949333432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"312.407265ms","start":"2026-04-21T18:15:42.068743Z","end":"2026-04-21T18:15:42.381151Z","steps":["trace[949333432] 'agreement among raft nodes before linearized reading' (duration: 310.713659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:42.381211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:42.068726Z","time spent":"312.473386ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:15:42.38124Z","caller":"traceutil/trace.go:171","msg":"trace[1578417546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"173.741038ms","start":"2026-04-21T18:15:42.207443Z","end":"2026-04-21T18:15:42.381184Z","steps":["trace[1578417546] 'agreement among raft nodes before linearized reading' (duration: 172.294958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:42.381725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:41.964629Z","time spent":"416.481719ms","remote":"127.0.0.1:35432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:30.767836Z","caller":"traceutil/trace.go:171","msg":"trace[823652646] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"135.402692ms","start":"2026-04-21T18:16:30.632394Z","end":"2026-04-21T18:16:30.767797Z","steps":["trace[823652646] 'process raft request' (duration: 135.164227ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:30.900845Z","caller":"traceutil/trace.go:171","msg":"trace[1237037646] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1023; }","duration":"143.32444ms","start":"2026-04-21T18:16:30.757496Z","end":"2026-04-21T18:16:30.90082Z","steps":["trace[1237037646] 'read index received' (duration: 10.003694ms)","trace[1237037646] 'applied index is now lower than readState.Index' (duration: 133.319886ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:30.900864Z","caller":"traceutil/trace.go:171","msg":"trace[1281389267] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"267.283387ms","start":"2026-04-21T18:16:30.633553Z","end":"2026-04-21T18:16:30.900836Z","steps":["trace[1281389267] 'process raft request' (duration: 267.009482ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:30.900875Z","caller":"traceutil/trace.go:171","msg":"trace[1532939683] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"146.494688ms","start":"2026-04-21T18:16:30.754358Z","end":"2026-04-21T18:16:30.900852Z","steps":["trace[1532939683] 'process raft request' (duration: 146.401126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:30.901016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.501674ms","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-04-21T18:16:30.901049Z","caller":"traceutil/trace.go:171","msg":"trace[1235167483] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:994; }","duration":"143.587796ms","start":"2026-04-21T18:16:30.757454Z","end":"2026-04-21T18:16:30.901042Z","steps":["trace[1235167483] 'agreement among raft nodes before linearized reading' (duration: 143.463793ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:06.392645Z","caller":"traceutil/trace.go:171","msg":"trace[1623636899] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"122.889974ms","start":"2026-04-21T18:17:06.269711Z","end":"2026-04-21T18:17:06.392601Z","steps":["trace[1623636899] 'process raft request' (duration: 87.051669ms)","trace[1623636899] 'compare' (duration: 35.368095ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:17:24.204429Z","caller":"traceutil/trace.go:171","msg":"trace[628968720] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"106.141465ms","start":"2026-04-21T18:17:24.098245Z","end":"2026-04-21T18:17:24.204387Z","steps":["trace[628968720] 'process raft request' (duration: 72.470294ms)","trace[628968720] 'compare' (duration: 33.519208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:17:24.474836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.643901ms","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-04-21T18:17:24.474882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.326172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:17:24.474902Z","caller":"traceutil/trace.go:171","msg":"trace[245677240] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1238; }","duration":"139.723152ms","start":"2026-04-21T18:17:24.335163Z","end":"2026-04-21T18:17:24.474887Z","steps":["trace[245677240] 'count revisions from in-memory index tree' (duration: 139.552399ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:24.474919Z","caller":"traceutil/trace.go:171","msg":"trace[533089578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"137.383053ms","start":"2026-04-21T18:17:24.337526Z","end":"2026-04-21T18:17:24.474909Z","steps":["trace[533089578] 'range keys from in-memory index tree' (duration: 137.192029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:25.330638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.50924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:17:25.330732Z","caller":"traceutil/trace.go:171","msg":"trace[1122065118] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"273.024718ms","start":"2026-04-21T18:17:25.057694Z","end":"2026-04-21T18:17:25.330719Z","steps":["trace[1122065118] 'read index received' (duration: 132.425346ms)","trace[1122065118] 'applied index is now lower than readState.Index' (duration: 140.598132ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:17:25.330769Z","caller":"traceutil/trace.go:171","msg":"trace[7033799] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"298.984862ms","start":"2026-04-21T18:17:25.031751Z","end":"2026-04-21T18:17:25.330736Z","steps":["trace[7033799] 'process raft request' (duration: 158.217356ms)","trace[7033799] 'compare' (duration: 140.265325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:17:25.330921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.223643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T18:17:25.330942Z","caller":"traceutil/trace.go:171","msg":"trace[298318757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"273.286094ms","start":"2026-04-21T18:17:25.057651Z","end":"2026-04-21T18:17:25.330937Z","steps":["trace[298318757] 'agreement among raft nodes before linearized reading' (duration: 273.120791ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:25.351257Z","caller":"traceutil/trace.go:171","msg":"trace[2133525596] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"280.712964ms","start":"2026-04-21T18:17:25.070521Z","end":"2026-04-21T18:17:25.351234Z","steps":["trace[2133525596] 'process raft request' (duration: 280.506259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:25.351751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.727554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:17:25.35185Z","caller":"traceutil/trace.go:171","msg":"trace[1873656725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"239.877487ms","start":"2026-04-21T18:17:25.111947Z","end":"2026-04-21T18:17:25.351825Z","steps":["trace[1873656725] 'agreement among raft nodes before linearized reading' (duration: 239.727554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:25.351857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.768232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T18:17:25.352132Z","caller":"traceutil/trace.go:171","msg":"trace[1690069183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"121.002147ms","start":"2026-04-21T18:17:25.231015Z","end":"2026-04-21T18:17:25.352017Z","steps":["trace[1690069183] 'agreement among raft nodes before linearized reading' (duration: 120.615288ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:25.352079Z","caller":"traceutil/trace.go:171","msg":"trace[1493494397] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"186.296005ms","start":"2026-04-21T18:17:25.165723Z","end":"2026-04-21T18:17:25.352019Z","steps":["trace[1493494397] 'process raft request' (duration: 185.430306ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:38.414763Z","caller":"traceutil/trace.go:171","msg":"trace[1896766361] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"142.209697ms","start":"2026-04-21T18:17:38.27253Z","end":"2026-04-21T18:17:38.41474Z","steps":["trace[1896766361] 'process raft request' (duration: 142.083884ms)"],"step_count":1}