{"level":"warn","ts":"2026-03-05T16:27:08.65719Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T16:27:08.657284Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-03-05T16:27:08.657359Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T16:27:08.65737Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-03-05T16:27:08.657389Z","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-03-05T16:27:08.658291Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-03-05T16:27:08.658396Z","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://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"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-03-05T16:27:08.664662Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.274173ms"} {"level":"info","ts":"2026-03-05T16:27:08.672652Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-03-05T16:27:08.672736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T16:27:08.672768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-03-05T16:27:08.67279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T16:27:08.672806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-03-05T16:27:08.672858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-03-05T16:27:08.683132Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T16:27:08.686106Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T16:27:08.687398Z","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-03-05T16:27:08.689278Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T16:27:08.691345Z","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-03-05T16:27:08.691511Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T16:27:08.691539Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T16:27:08.691714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T16:27:08.691927Z","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-03-05T16:27:08.692009Z","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-03-05T16:27:08.692018Z","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-03-05T16:27:08.692142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-03-05T16:27:08.692174Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-03-05T16:27:08.693248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-03-05T16:27:08.693345Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-03-05T16:27:09.573476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T16:27:09.573574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T16:27:09.573611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-03-05T16:27:09.573638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-03-05T16:27:09.573656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-03-05T16:27:09.573673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-03-05T16:27:09.573691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-03-05T16:27:09.574533Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T16:27:09.575355Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T16:27:09.57535Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T16:27:09.575437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T16:27:09.575805Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T16:27:09.575855Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T16:27:09.575943Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T16:27:09.576075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T16:27:09.576117Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T16:27:09.580636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T16:27:09.58062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-03-05T16:27:34.118302Z","caller":"traceutil/trace.go:171","msg":"trace[1720777022] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"331.753433ms","start":"2026-03-05T16:27:33.786523Z","end":"2026-03-05T16:27:34.118276Z","steps":["trace[1720777022] 'process raft request' (duration: 331.589271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:34.118985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:33.786506Z","time spent":"331.889284ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:40.200439Z","caller":"traceutil/trace.go:171","msg":"trace[156763920] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"108.799199ms","start":"2026-03-05T16:27:40.091615Z","end":"2026-03-05T16:27:40.200415Z","steps":["trace[156763920] 'process raft request' (duration: 108.226775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.416632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.64497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:41.416744Z","caller":"traceutil/trace.go:171","msg":"trace[1907193033] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:502; }","duration":"749.864181ms","start":"2026-03-05T16:27:40.666859Z","end":"2026-03-05T16:27:41.416723Z","steps":["trace[1907193033] 'range keys from in-memory index tree' (duration: 749.55097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.416801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:40.66684Z","time spent":"749.950343ms","remote":"127.0.0.1:45740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-05T16:27:41.416816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.585265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-dp8kj\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-03-05T16:27:41.41689Z","caller":"traceutil/trace.go:171","msg":"trace[1149866331] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-dp8kj; range_end:; response_count:1; response_revision:502; }","duration":"992.696175ms","start":"2026-03-05T16:27:40.424177Z","end":"2026-03-05T16:27:41.416873Z","steps":["trace[1149866331] 'range keys from in-memory index tree' (duration: 992.443983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.416938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:40.424161Z","time spent":"992.768285ms","remote":"127.0.0.1:45886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4910,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-dp8kj\" "} {"level":"warn","ts":"2026-03-05T16:27:41.416982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.459563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:41.417074Z","caller":"traceutil/trace.go:171","msg":"trace[689209522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"792.586073ms","start":"2026-03-05T16:27:40.624468Z","end":"2026-03-05T16:27:41.417054Z","steps":["trace[689209522] 'range keys from in-memory index tree' (duration: 792.401313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.41706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097994041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:41.41712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:40.624453Z","time spent":"792.658175ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T16:27:41.417199Z","caller":"traceutil/trace.go:171","msg":"trace[640188898] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:502; }","duration":"1.098562564s","start":"2026-03-05T16:27:40.318611Z","end":"2026-03-05T16:27:41.417174Z","steps":["trace[640188898] 'range keys from in-memory index tree' (duration: 1.09786384s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.417439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:40.318597Z","time spent":"1.098638555s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-03-05T16:27:41.41748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.382061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:41.417512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.794158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:41.417527Z","caller":"traceutil/trace.go:171","msg":"trace[1544917975] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:502; }","duration":"551.456111ms","start":"2026-03-05T16:27:40.866059Z","end":"2026-03-05T16:27:41.417515Z","steps":["trace[1544917975] 'range keys from in-memory index tree' (duration: 551.31047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.417558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:40.866044Z","time spent":"551.506971ms","remote":"127.0.0.1:45740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-05T16:27:41.41756Z","caller":"traceutil/trace.go:171","msg":"trace[658966450] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:502; }","duration":"950.860128ms","start":"2026-03-05T16:27:40.466685Z","end":"2026-03-05T16:27:41.417545Z","steps":["trace[658966450] 'range keys from in-memory index tree' (duration: 950.751968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.417598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:40.466676Z","time spent":"950.913889ms","remote":"127.0.0.1:45740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-03-05T16:27:41.430341Z","caller":"traceutil/trace.go:171","msg":"trace[1615344572] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"163.565837ms","start":"2026-03-05T16:27:41.266759Z","end":"2026-03-05T16:27:41.430325Z","steps":["trace[1615344572] 'read index received' (duration: 163.304305ms)","trace[1615344572] 'applied index is now lower than readState.Index' (duration: 260.892µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T16:27:41.430478Z","caller":"traceutil/trace.go:171","msg":"trace[877767975] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"363.078229ms","start":"2026-03-05T16:27:41.067393Z","end":"2026-03-05T16:27:41.430472Z","steps":["trace[877767975] 'process raft request' (duration: 362.816957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:41.430541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:41.067369Z","time spent":"363.132989ms","remote":"127.0.0.1:45740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T16:27:41.430716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.955059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-05T16:27:41.430744Z","caller":"traceutil/trace.go:171","msg":"trace[470812707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:503; }","duration":"164.015439ms","start":"2026-03-05T16:27:41.266722Z","end":"2026-03-05T16:27:41.430737Z","steps":["trace[470812707] 'agreement among raft nodes before linearized reading' (duration: 163.915869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:44.721927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088873915909558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T16:27:45.222767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088873915909558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T16:27:45.265938Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050260276s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T16:27:45.266342Z","caller":"traceutil/trace.go:171","msg":"trace[1340153597] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.050786209s","start":"2026-03-05T16:27:44.215531Z","end":"2026-03-05T16:27:45.266317Z","steps":["trace[1340153597] 'process raft request' (duration: 1.050611708s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.26651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.215506Z","time spent":"1.05091215s","remote":"127.0.0.1:45878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4680,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:45.841495Z","caller":"traceutil/trace.go:171","msg":"trace[133840016] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"1.620489305s","start":"2026-03-05T16:27:44.220983Z","end":"2026-03-05T16:27:45.841472Z","steps":["trace[133840016] 'read index received' (duration: 1.045194252s)","trace[133840016] 'applied index is now lower than readState.Index' (duration: 575.294213ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T16:27:45.841568Z","caller":"traceutil/trace.go:171","msg":"trace[1951856338] transaction","detail":"{read_only:false; number_of_response:1; response_revision:554; }","duration":"792.383726ms","start":"2026-03-05T16:27:45.049156Z","end":"2026-03-05T16:27:45.84154Z","steps":["trace[1951856338] 'process raft request' (duration: 792.132544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.841649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620641016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:45.841652Z","caller":"traceutil/trace.go:171","msg":"trace[916764362] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"676.731658ms","start":"2026-03-05T16:27:45.164876Z","end":"2026-03-05T16:27:45.841608Z","steps":["trace[916764362] 'process raft request' (duration: 676.519997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.841721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:45.049134Z","time spent":"792.507627ms","remote":"127.0.0.1:45878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":4727,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T16:27:45.841759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.454501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:45.84179Z","caller":"traceutil/trace.go:171","msg":"trace[2009448268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"216.517212ms","start":"2026-03-05T16:27:45.62526Z","end":"2026-03-05T16:27:45.841777Z","steps":["trace[2009448268] 'agreement among raft nodes before linearized reading' (duration: 216.471941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.841779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620716417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:45.841832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:45.164853Z","time spent":"676.88445ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:45.841847Z","caller":"traceutil/trace.go:171","msg":"trace[1755145484] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:555; }","duration":"1.620806028s","start":"2026-03-05T16:27:44.221024Z","end":"2026-03-05T16:27:45.84183Z","steps":["trace[1755145484] 'agreement among raft nodes before linearized reading' (duration: 1.620689147s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.841892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.221018Z","time spent":"1.620864208s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-03-05T16:27:45.841686Z","caller":"traceutil/trace.go:171","msg":"trace[2136797033] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:555; }","duration":"1.620699776s","start":"2026-03-05T16:27:44.220976Z","end":"2026-03-05T16:27:45.841675Z","steps":["trace[2136797033] 'agreement among raft nodes before linearized reading' (duration: 1.620618535s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.84208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.220919Z","time spent":"1.62114634s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-05T16:27:45.842084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549607655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:45.842108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.718054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-05T16:27:45.842125Z","caller":"traceutil/trace.go:171","msg":"trace[1569175489] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:555; }","duration":"1.549650177s","start":"2026-03-05T16:27:44.292462Z","end":"2026-03-05T16:27:45.842112Z","steps":["trace[1569175489] 'agreement among raft nodes before linearized reading' (duration: 1.549583516s)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:45.842137Z","caller":"traceutil/trace.go:171","msg":"trace[1167875489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"247.771004ms","start":"2026-03-05T16:27:45.594358Z","end":"2026-03-05T16:27:45.842129Z","steps":["trace[1167875489] 'agreement among raft nodes before linearized reading' (duration: 247.658073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.29243Z","time spent":"1.549718567s","remote":"127.0.0.1:50426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-05T16:27:45.842301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548898681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:45.842322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578700055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:45.842337Z","caller":"traceutil/trace.go:171","msg":"trace[702105664] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:555; }","duration":"1.548933531s","start":"2026-03-05T16:27:44.293392Z","end":"2026-03-05T16:27:45.842325Z","steps":["trace[702105664] 'agreement among raft nodes before linearized reading' (duration: 1.548885021s)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:45.842354Z","caller":"traceutil/trace.go:171","msg":"trace[2072193501] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:555; }","duration":"1.578736355s","start":"2026-03-05T16:27:44.263607Z","end":"2026-03-05T16:27:45.842343Z","steps":["trace[2072193501] 'agreement among raft nodes before linearized reading' (duration: 1.578690615s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.494212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-05T16:27:45.842368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.293343Z","time spent":"1.549016061s","remote":"127.0.0.1:50426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-03-05T16:27:45.842385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.263604Z","time spent":"1.578773315s","remote":"127.0.0.1:50422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-05T16:27:45.842401Z","caller":"traceutil/trace.go:171","msg":"trace[1210139551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"380.574943ms","start":"2026-03-05T16:27:45.461813Z","end":"2026-03-05T16:27:45.842388Z","steps":["trace[1210139551] 'agreement among raft nodes before linearized reading' (duration: 380.393122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:45.461794Z","time spent":"380.632114ms","remote":"127.0.0.1:45954","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-03-05T16:27:45.842511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549950628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:45.842543Z","caller":"traceutil/trace.go:171","msg":"trace[1163679296] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:555; }","duration":"1.550009478s","start":"2026-03-05T16:27:44.292524Z","end":"2026-03-05T16:27:45.842534Z","steps":["trace[1163679296] 'agreement among raft nodes before linearized reading' (duration: 1.549195313s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578952006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:45.842571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.292521Z","time spent":"1.550041798s","remote":"127.0.0.1:50426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"info","ts":"2026-03-05T16:27:45.842597Z","caller":"traceutil/trace.go:171","msg":"trace[1791440541] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:555; }","duration":"1.578984037s","start":"2026-03-05T16:27:44.263601Z","end":"2026-03-05T16:27:45.842585Z","steps":["trace[1791440541] 'agreement among raft nodes before linearized reading' (duration: 1.578930456s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.263587Z","time spent":"1.579032057s","remote":"127.0.0.1:50422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-03-05T16:27:45.842634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.844195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-05T16:27:45.842611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.160993ms","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-03-05T16:27:45.842668Z","caller":"traceutil/trace.go:171","msg":"trace[1817494865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"377.919046ms","start":"2026-03-05T16:27:45.46474Z","end":"2026-03-05T16:27:45.842659Z","steps":["trace[1817494865] 'agreement among raft nodes before linearized reading' (duration: 377.825855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:45.464709Z","time spent":"377.983096ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-05T16:27:45.84267Z","caller":"traceutil/trace.go:171","msg":"trace[347460840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:555; }","duration":"354.239253ms","start":"2026-03-05T16:27:45.488422Z","end":"2026-03-05T16:27:45.842661Z","steps":["trace[347460840] 'agreement among raft nodes before linearized reading' (duration: 354.129762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:45.48841Z","time spent":"354.354814ms","remote":"127.0.0.1:45954","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-03-05T16:27:45.842785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579199058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:45.842502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621316481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:45.842818Z","caller":"traceutil/trace.go:171","msg":"trace[740342158] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:555; }","duration":"1.579279648s","start":"2026-03-05T16:27:44.263526Z","end":"2026-03-05T16:27:45.842806Z","steps":["trace[740342158] 'agreement among raft nodes before linearized reading' (duration: 1.579232118s)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:45.842834Z","caller":"traceutil/trace.go:171","msg":"trace[1494916540] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:555; }","duration":"1.621665993s","start":"2026-03-05T16:27:44.221157Z","end":"2026-03-05T16:27:45.842823Z","steps":["trace[1494916540] 'agreement among raft nodes before linearized reading' (duration: 1.621303671s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.842847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.263512Z","time spent":"1.579327708s","remote":"127.0.0.1:50422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-05T16:27:45.842866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.22115Z","time spent":"1.621706463s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-03-05T16:27:45.843013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620864958s","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-03-05T16:27:45.843041Z","caller":"traceutil/trace.go:171","msg":"trace[61382524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"1.620905318s","start":"2026-03-05T16:27:44.222128Z","end":"2026-03-05T16:27:45.843033Z","steps":["trace[61382524] 'agreement among raft nodes before linearized reading' (duration: 1.620833338s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:45.843064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:44.22212Z","time spent":"1.620939068s","remote":"127.0.0.1:45954","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-03-05T16:27:50.371803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088873915909610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T16:27:50.724585Z","caller":"traceutil/trace.go:171","msg":"trace[467134463] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"857.461004ms","start":"2026-03-05T16:27:49.867097Z","end":"2026-03-05T16:27:50.724558Z","steps":["trace[467134463] 'process raft request' (duration: 857.309446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:50.724775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:49.867077Z","time spent":"857.59316ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:50.790216Z","caller":"traceutil/trace.go:171","msg":"trace[701135098] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"918.743824ms","start":"2026-03-05T16:27:49.871455Z","end":"2026-03-05T16:27:50.790199Z","steps":["trace[701135098] 'read index received' (duration: 853.07237ms)","trace[701135098] 'applied index is now lower than readState.Index' (duration: 65.670294ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T16:27:50.790285Z","caller":"traceutil/trace.go:171","msg":"trace[1786168478] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"922.570753ms","start":"2026-03-05T16:27:49.867673Z","end":"2026-03-05T16:27:50.790244Z","steps":["trace[1786168478] 'process raft request' (duration: 881.657571ms)","trace[1786168478] 'compare' (duration: 40.784326ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T16:27:50.790477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.015605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-05T16:27:50.790517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.357866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-05T16:27:50.790522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:49.867655Z","time spent":"922.707939ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:50.790542Z","caller":"traceutil/trace.go:171","msg":"trace[774997309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:572; }","duration":"919.083418ms","start":"2026-03-05T16:27:49.871421Z","end":"2026-03-05T16:27:50.790505Z","steps":["trace[774997309] 'agreement among raft nodes before linearized reading' (duration: 918.870319ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:50.790576Z","caller":"traceutil/trace.go:171","msg":"trace[2085394440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"918.493582ms","start":"2026-03-05T16:27:49.872067Z","end":"2026-03-05T16:27:50.79056Z","steps":["trace[2085394440] 'agreement among raft nodes before linearized reading' (duration: 918.299064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:50.79059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:49.8714Z","time spent":"919.179492ms","remote":"127.0.0.1:45954","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-03-05T16:27:50.79061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:49.872048Z","time spent":"918.556155ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T16:27:50.790653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.378897ms","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-03-05T16:27:50.790754Z","caller":"traceutil/trace.go:171","msg":"trace[1647913584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"918.495872ms","start":"2026-03-05T16:27:49.872218Z","end":"2026-03-05T16:27:50.790714Z","steps":["trace[1647913584] 'agreement among raft nodes before linearized reading' (duration: 918.245221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:50.790847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:49.872207Z","time spent":"918.629318ms","remote":"127.0.0.1:45954","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-03-05T16:27:50.791252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.95063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T16:27:50.791418Z","caller":"traceutil/trace.go:171","msg":"trace[1102035836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"165.148138ms","start":"2026-03-05T16:27:50.626251Z","end":"2026-03-05T16:27:50.791399Z","steps":["trace[1102035836] 'agreement among raft nodes before linearized reading' (duration: 164.132694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:51.275044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.438341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T16:27:51.275283Z","caller":"traceutil/trace.go:171","msg":"trace[1218725172] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"480.018956ms","start":"2026-03-05T16:27:50.795245Z","end":"2026-03-05T16:27:51.275264Z","steps":["trace[1218725172] 'process raft request' (duration: 479.905141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:51.275377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:50.795238Z","time spent":"480.09907ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:51.275398Z","caller":"traceutil/trace.go:171","msg":"trace[1528449443] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"480.195323ms","start":"2026-03-05T16:27:50.795174Z","end":"2026-03-05T16:27:51.27537Z","steps":["trace[1528449443] 'process raft request' (duration: 327.169967ms)","trace[1528449443] 'compare' (duration: 152.028354ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T16:27:51.275533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:50.79516Z","time spent":"480.295978ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T16:27:52.369496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.685204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:52.369563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.791434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T16:27:52.369633Z","caller":"traceutil/trace.go:171","msg":"trace[1814325740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"686.893053ms","start":"2026-03-05T16:27:51.682704Z","end":"2026-03-05T16:27:52.369597Z","steps":["trace[1814325740] 'range keys from in-memory index tree' (duration: 686.59092ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:52.369665Z","caller":"traceutil/trace.go:171","msg":"trace[410295150] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"423.295965ms","start":"2026-03-05T16:27:51.946351Z","end":"2026-03-05T16:27:52.369647Z","steps":["trace[410295150] 'process raft request' (duration: 290.349404ms)","trace[410295150] 'compare' (duration: 132.657458ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T16:27:52.369693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:51.682679Z","time spent":"687.001087ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T16:27:52.369736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:51.946322Z","time spent":"423.377468ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T16:27:52.796371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.756535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T16:27:52.796438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.847002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-05T16:27:52.796468Z","caller":"traceutil/trace.go:171","msg":"trace[101184368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"170.87486ms","start":"2026-03-05T16:27:52.625564Z","end":"2026-03-05T16:27:52.796439Z","steps":["trace[101184368] 'range keys from in-memory index tree' (duration: 170.654441ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:52.796503Z","caller":"traceutil/trace.go:171","msg":"trace[393633625] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:576; }","duration":"274.949556ms","start":"2026-03-05T16:27:52.521538Z","end":"2026-03-05T16:27:52.796487Z","steps":["trace[393633625] 'range keys from in-memory index tree' (duration: 274.710696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:53.285074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.311592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T16:27:53.285358Z","caller":"traceutil/trace.go:171","msg":"trace[335248686] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"484.781177ms","start":"2026-03-05T16:27:52.800564Z","end":"2026-03-05T16:27:53.285345Z","steps":["trace[335248686] 'process raft request' (duration: 213.12894ms)","trace[335248686] 'compare' (duration: 271.155246ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T16:27:53.285412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:52.80054Z","time spent":"484.85026ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:53.285428Z","caller":"traceutil/trace.go:171","msg":"trace[1065977366] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"484.755976ms","start":"2026-03-05T16:27:52.800651Z","end":"2026-03-05T16:27:53.285407Z","steps":["trace[1065977366] 'process raft request' (duration: 484.504845ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:53.285305Z","caller":"traceutil/trace.go:171","msg":"trace[1624279672] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"423.655657ms","start":"2026-03-05T16:27:52.861634Z","end":"2026-03-05T16:27:53.285289Z","steps":["trace[1624279672] 'process raft request' (duration: 423.616805ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:53.285463Z","caller":"traceutil/trace.go:171","msg":"trace[993566102] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"453.765482ms","start":"2026-03-05T16:27:52.831671Z","end":"2026-03-05T16:27:53.285437Z","steps":["trace[993566102] 'read index received' (duration: 28.982208ms)","trace[993566102] 'applied index is now lower than readState.Index' (duration: 424.780204ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T16:27:53.285613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.96155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T16:27:53.285654Z","caller":"traceutil/trace.go:171","msg":"trace[2107260601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"454.132008ms","start":"2026-03-05T16:27:52.831511Z","end":"2026-03-05T16:27:53.285643Z","steps":["trace[2107260601] 'agreement among raft nodes before linearized reading' (duration: 454.022933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:53.285714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:52.831496Z","time spent":"454.18162ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T16:27:53.285819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:52.861619Z","time spent":"423.876016ms","remote":"127.0.0.1:45724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T16:27:53.285895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T16:27:52.800632Z","time spent":"484.85823ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T16:27:53.506876Z","caller":"traceutil/trace.go:171","msg":"trace[2103527100] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"217.048403ms","start":"2026-03-05T16:27:53.289807Z","end":"2026-03-05T16:27:53.506855Z","steps":["trace[2103527100] 'process raft request' (duration: 208.622947ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:53.507201Z","caller":"traceutil/trace.go:171","msg":"trace[956733545] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"216.518309ms","start":"2026-03-05T16:27:53.290645Z","end":"2026-03-05T16:27:53.507164Z","steps":["trace[956733545] 'process raft request' (duration: 216.161114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T16:27:53.507466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.066553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-05T16:27:53.507536Z","caller":"traceutil/trace.go:171","msg":"trace[1935311771] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:582; }","duration":"217.164568ms","start":"2026-03-05T16:27:53.290353Z","end":"2026-03-05T16:27:53.507517Z","steps":["trace[1935311771] 'agreement among raft nodes before linearized reading' (duration: 216.964199ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T16:27:53.507259Z","caller":"traceutil/trace.go:171","msg":"trace[1410823150] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"216.825054ms","start":"2026-03-05T16:27:53.290381Z","end":"2026-03-05T16:27:53.507206Z","steps":["trace[1410823150] 'read index received' (duration: 36.68885ms)","trace[1410823150] 'applied index is now lower than readState.Index' (duration: 180.134273ms)"],"step_count":2}