{"level":"warn","ts":"2026-03-18T05:34:49.09743Z","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-18T05:34:49.097516Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-18T05:34:49.097609Z","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-18T05:34:49.097652Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-18T05:34:49.097689Z","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-18T05:34:49.098491Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-03-18T05:34:49.098627Z","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.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-18T05:34:49.105578Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.643485ms"} {"level":"info","ts":"2026-03-18T05:34:49.12092Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-03-18T05:34:49.121042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T05:34:49.121079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-03-18T05:34:49.121095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T05:34:49.121113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-03-18T05:34:49.121172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-03-18T05:34:49.128094Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T05:34:49.129488Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T05:34:49.130921Z","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-18T05:34:49.133269Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T05:34:49.134077Z","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-18T05:34:49.13442Z","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-18T05:34:49.134451Z","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-18T05:34:49.134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T05:34:49.146436Z","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-18T05:34:49.146651Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-18T05:34:49.147765Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-18T05:34:49.146912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-03-18T05:34:49.147133Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T05:34:49.1472Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T05:34:49.148725Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-18T05:34:49.822268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T05:34:49.822443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T05:34:49.822494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-03-18T05:34:49.822588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-03-18T05:34:49.822623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-18T05:34:49.82267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-03-18T05:34:49.822712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-18T05:34:49.82388Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T05:34:49.823927Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:49.824247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:34:49.824288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:34:49.827691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T05:34:49.828493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-03-18T05:34:49.838757Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T05:34:49.838764Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:49.838864Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T05:34:49.840011Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:49.840255Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-18T05:34:59.874381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.5482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-18T05:34:59.874568Z","caller":"traceutil/trace.go:171","msg":"trace[1977219069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"164.758074ms","start":"2026-03-18T05:34:59.709773Z","end":"2026-03-18T05:34:59.874531Z","steps":["trace[1977219069] 'range keys from in-memory index tree' (duration: 164.455078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:34:59.874377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.271846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-18T05:34:59.87469Z","caller":"traceutil/trace.go:171","msg":"trace[1176300118] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"114.621443ms","start":"2026-03-18T05:34:59.760047Z","end":"2026-03-18T05:34:59.874668Z","steps":["trace[1176300118] 'range keys from in-memory index tree' (duration: 114.168663ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:05.305662Z","caller":"traceutil/trace.go:171","msg":"trace[1200886218] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"174.490026ms","start":"2026-03-18T05:35:05.131143Z","end":"2026-03-18T05:35:05.305633Z","steps":["trace[1200886218] 'process raft request' (duration: 174.23442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:06.205115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.615849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:35:06.205245Z","caller":"traceutil/trace.go:171","msg":"trace[1348077416] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"895.520032ms","start":"2026-03-18T05:35:05.309693Z","end":"2026-03-18T05:35:06.205213Z","steps":["trace[1348077416] 'process raft request' (duration: 332.435932ms)","trace[1348077416] 'compare' (duration: 562.454145ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:06.20539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:05.309666Z","time spent":"895.648344ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:35:06.671372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.07543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T05:35:06.671406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.220246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-03-18T05:35:06.671468Z","caller":"traceutil/trace.go:171","msg":"trace[1552544060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"210.224404ms","start":"2026-03-18T05:35:06.461223Z","end":"2026-03-18T05:35:06.671447Z","steps":["trace[1552544060] 'range keys from in-memory index tree' (duration: 209.965028ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:06.671481Z","caller":"traceutil/trace.go:171","msg":"trace[757426] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:414; }","duration":"314.320498ms","start":"2026-03-18T05:35:06.35714Z","end":"2026-03-18T05:35:06.671461Z","steps":["trace[757426] 'range keys from in-memory index tree' (duration: 314.128404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:06.671524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:06.357082Z","time spent":"314.43336ms","remote":"127.0.0.1:37680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-03-18T05:35:06.949888Z","caller":"traceutil/trace.go:171","msg":"trace[1340371466] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"273.416757ms","start":"2026-03-18T05:35:06.676443Z","end":"2026-03-18T05:35:06.94986Z","steps":["trace[1340371466] 'process raft request' (duration: 273.205012ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:07.118736Z","caller":"traceutil/trace.go:171","msg":"trace[1584127414] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"156.179128ms","start":"2026-03-18T05:35:06.96252Z","end":"2026-03-18T05:35:07.118699Z","steps":["trace[1584127414] 'read index received' (duration: 156.053455ms)","trace[1584127414] 'applied index is now lower than readState.Index' (duration: 124.713µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:07.118905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.356891ms","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-18T05:35:07.11899Z","caller":"traceutil/trace.go:171","msg":"trace[1328580787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"156.482235ms","start":"2026-03-18T05:35:06.962489Z","end":"2026-03-18T05:35:07.118971Z","steps":["trace[1328580787] 'agreement among raft nodes before linearized reading' (duration: 156.321031ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:07.118806Z","caller":"traceutil/trace.go:171","msg":"trace[2035248813] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"432.51581ms","start":"2026-03-18T05:35:06.686265Z","end":"2026-03-18T05:35:07.118781Z","steps":["trace[2035248813] 'process raft request' (duration: 432.281434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:07.119224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:06.686239Z","time spent":"432.870408ms","remote":"127.0.0.1:37680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:35:26.670256Z","caller":"traceutil/trace.go:171","msg":"trace[1498345737] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"261.505128ms","start":"2026-03-18T05:35:26.408722Z","end":"2026-03-18T05:35:26.670228Z","steps":["trace[1498345737] 'process raft request' (duration: 261.14384ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:27.07514Z","caller":"traceutil/trace.go:171","msg":"trace[556080470] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"391.399855ms","start":"2026-03-18T05:35:26.683686Z","end":"2026-03-18T05:35:27.075086Z","steps":["trace[556080470] 'process raft request' (duration: 390.804491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:27.075367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:26.683637Z","time spent":"391.598799ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:35:27.9245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.456394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T05:35:27.924587Z","caller":"traceutil/trace.go:171","msg":"trace[909205065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"521.558436ms","start":"2026-03-18T05:35:27.403011Z","end":"2026-03-18T05:35:27.92457Z","steps":["trace[909205065] 'range keys from in-memory index tree' (duration: 521.24943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:27.924619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:27.40295Z","time spent":"521.661728ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T05:35:28.25286Z","caller":"traceutil/trace.go:171","msg":"trace[1039949168] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"144.571065ms","start":"2026-03-18T05:35:28.10826Z","end":"2026-03-18T05:35:28.252831Z","steps":["trace[1039949168] 'read index received' (duration: 144.486873ms)","trace[1039949168] 'applied index is now lower than readState.Index' (duration: 83.512µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:28.252891Z","caller":"traceutil/trace.go:171","msg":"trace[1217639700] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"324.117492ms","start":"2026-03-18T05:35:27.928745Z","end":"2026-03-18T05:35:28.252862Z","steps":["trace[1217639700] 'process raft request' (duration: 323.947638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:28.252969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.686408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:28.252996Z","caller":"traceutil/trace.go:171","msg":"trace[1991169959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"144.77493ms","start":"2026-03-18T05:35:28.108211Z","end":"2026-03-18T05:35:28.252986Z","steps":["trace[1991169959] 'agreement among raft nodes before linearized reading' (duration: 144.711668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:28.253016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:27.928717Z","time spent":"324.227754ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:35:29.99237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.346563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:29.992486Z","caller":"traceutil/trace.go:171","msg":"trace[669374949] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:566; }","duration":"117.518656ms","start":"2026-03-18T05:35:29.874944Z","end":"2026-03-18T05:35:29.992463Z","steps":["trace[669374949] 'range keys from in-memory index tree' (duration: 117.300062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.992787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.375325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:29.992853Z","caller":"traceutil/trace.go:171","msg":"trace[805560630] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:566; }","duration":"118.475787ms","start":"2026-03-18T05:35:29.874362Z","end":"2026-03-18T05:35:29.992837Z","steps":["trace[805560630] 'range keys from in-memory index tree' (duration: 118.284303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.993074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.481677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:29.993118Z","caller":"traceutil/trace.go:171","msg":"trace[1942320220] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:566; }","duration":"118.532288ms","start":"2026-03-18T05:35:29.874576Z","end":"2026-03-18T05:35:29.993108Z","steps":["trace[1942320220] 'count revisions from in-memory index tree' (duration: 118.328154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.993529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.473001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:29.993634Z","caller":"traceutil/trace.go:171","msg":"trace[1067271242] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:566; }","duration":"103.597873ms","start":"2026-03-18T05:35:29.88999Z","end":"2026-03-18T05:35:29.993588Z","steps":["trace[1067271242] 'range keys from in-memory index tree' (duration: 103.384749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.993584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.395899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:29.994632Z","caller":"traceutil/trace.go:171","msg":"trace[1221264510] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:566; }","duration":"104.469423ms","start":"2026-03-18T05:35:29.890144Z","end":"2026-03-18T05:35:29.994614Z","steps":["trace[1221264510] 'count revisions from in-memory index tree' (duration: 103.297547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:29.995063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.145205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:29.995137Z","caller":"traceutil/trace.go:171","msg":"trace[773794621] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:566; }","duration":"104.224387ms","start":"2026-03-18T05:35:29.890897Z","end":"2026-03-18T05:35:29.995122Z","steps":["trace[773794621] 'range keys from in-memory index tree' (duration: 101.308954ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:47.85185Z","caller":"traceutil/trace.go:171","msg":"trace[1954337401] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"494.802679ms","start":"2026-03-18T05:35:47.357023Z","end":"2026-03-18T05:35:47.851825Z","steps":["trace[1954337401] 'read index received' (duration: 494.552253ms)","trace[1954337401] 'applied index is now lower than readState.Index' (duration: 249.756µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:47.851918Z","caller":"traceutil/trace.go:171","msg":"trace[557576034] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"624.468209ms","start":"2026-03-18T05:35:47.227424Z","end":"2026-03-18T05:35:47.851892Z","steps":["trace[557576034] 'process raft request' (duration: 624.255355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:47.852087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.517661ms","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-18T05:35:47.852096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:47.2274Z","time spent":"624.584772ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:35:47.852131Z","caller":"traceutil/trace.go:171","msg":"trace[938182068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"166.611803ms","start":"2026-03-18T05:35:47.68551Z","end":"2026-03-18T05:35:47.852122Z","steps":["trace[938182068] 'agreement among raft nodes before linearized reading' (duration: 166.45882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:47.852124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.066014ms","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-18T05:35:47.852186Z","caller":"traceutil/trace.go:171","msg":"trace[532013122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"495.197207ms","start":"2026-03-18T05:35:47.356975Z","end":"2026-03-18T05:35:47.852172Z","steps":["trace[532013122] 'agreement among raft nodes before linearized reading' (duration: 494.981643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:47.852226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:47.356954Z","time spent":"495.268109ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T05:35:48.376019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.194048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:35:48.376203Z","caller":"traceutil/trace.go:171","msg":"trace[677730714] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"519.206438ms","start":"2026-03-18T05:35:47.856976Z","end":"2026-03-18T05:35:48.376183Z","steps":["trace[677730714] 'process raft request' (duration: 360.745334ms)","trace[677730714] 'compare' (duration: 158.072135ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:48.376306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:47.856951Z","time spent":"519.29715ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:35:48.376479Z","caller":"traceutil/trace.go:171","msg":"trace[1944769451] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"518.264828ms","start":"2026-03-18T05:35:47.858208Z","end":"2026-03-18T05:35:48.376473Z","steps":["trace[1944769451] 'process raft request' (duration: 517.91556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:48.376522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:47.858197Z","time spent":"518.308989ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:35:48.439792Z","caller":"traceutil/trace.go:171","msg":"trace[1339645096] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:753; }","duration":"331.248394ms","start":"2026-03-18T05:35:48.108516Z","end":"2026-03-18T05:35:48.439765Z","steps":["trace[1339645096] 'read index received' (duration: 109.133237ms)","trace[1339645096] 'applied index is now lower than readState.Index' (duration: 222.113847ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:48.439957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.428898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:48.439997Z","caller":"traceutil/trace.go:171","msg":"trace[363774321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"331.51599ms","start":"2026-03-18T05:35:48.108469Z","end":"2026-03-18T05:35:48.439985Z","steps":["trace[363774321] 'agreement among raft nodes before linearized reading' (duration: 331.443568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:48.440043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:48.108444Z","time spent":"331.591291ms","remote":"127.0.0.1:37228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T05:35:49.140641Z","caller":"traceutil/trace.go:171","msg":"trace[638782344] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"284.40377ms","start":"2026-03-18T05:35:48.856213Z","end":"2026-03-18T05:35:49.140617Z","steps":["trace[638782344] 'process raft request' (duration: 284.013092ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:15.845349Z","caller":"traceutil/trace.go:171","msg":"trace[1135618918] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"586.661185ms","start":"2026-03-18T05:36:15.258646Z","end":"2026-03-18T05:36:15.845307Z","steps":["trace[1135618918] 'process raft request' (duration: 586.238256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:15.845717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:15.258625Z","time spent":"586.87299ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:16.767721Z","caller":"traceutil/trace.go:171","msg":"trace[389650278] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"200.587843ms","start":"2026-03-18T05:36:16.567106Z","end":"2026-03-18T05:36:16.767693Z","steps":["trace[389650278] 'read index received' (duration: 200.359358ms)","trace[389650278] 'applied index is now lower than readState.Index' (duration: 227.555µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:16.767812Z","caller":"traceutil/trace.go:171","msg":"trace[1985141204] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"243.583032ms","start":"2026-03-18T05:36:16.524193Z","end":"2026-03-18T05:36:16.767776Z","steps":["trace[1985141204] 'process raft request' (duration: 243.351867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:16.767959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.824118ms","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-18T05:36:16.768001Z","caller":"traceutil/trace.go:171","msg":"trace[108604970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"200.93001ms","start":"2026-03-18T05:36:16.567056Z","end":"2026-03-18T05:36:16.767986Z","steps":["trace[108604970] 'agreement among raft nodes before linearized reading' (duration: 200.757646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:16.768063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.005633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T05:36:16.7683Z","caller":"traceutil/trace.go:171","msg":"trace[1385582245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"148.282739ms","start":"2026-03-18T05:36:16.619996Z","end":"2026-03-18T05:36:16.768279Z","steps":["trace[1385582245] 'agreement among raft nodes before linearized reading' (duration: 147.878861ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:28.234632Z","caller":"traceutil/trace.go:171","msg":"trace[5351475] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"128.78032ms","start":"2026-03-18T05:36:28.105837Z","end":"2026-03-18T05:36:28.234617Z","steps":["trace[5351475] 'read index received' (duration: 128.655927ms)","trace[5351475] 'applied index is now lower than readState.Index' (duration: 123.643µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:28.234808Z","caller":"traceutil/trace.go:171","msg":"trace[903271090] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"268.583933ms","start":"2026-03-18T05:36:27.966217Z","end":"2026-03-18T05:36:28.234801Z","steps":["trace[903271090] 'process raft request' (duration: 268.314677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:28.234942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.093666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:36:28.234969Z","caller":"traceutil/trace.go:171","msg":"trace[864771138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"129.169008ms","start":"2026-03-18T05:36:28.105793Z","end":"2026-03-18T05:36:28.234962Z","steps":["trace[864771138] 'agreement among raft nodes before linearized reading' (duration: 129.119877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:28.235087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.993254ms","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-18T05:36:28.235108Z","caller":"traceutil/trace.go:171","msg":"trace[2010608014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"114.035074ms","start":"2026-03-18T05:36:28.121068Z","end":"2026-03-18T05:36:28.235104Z","steps":["trace[2010608014] 'agreement among raft nodes before linearized reading' (duration: 113.969313ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:30.363867Z","caller":"traceutil/trace.go:171","msg":"trace[1803536068] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"120.053798ms","start":"2026-03-18T05:36:30.243766Z","end":"2026-03-18T05:36:30.36382Z","steps":["trace[1803536068] 'process raft request' (duration: 119.780302ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:43.024166Z","caller":"traceutil/trace.go:171","msg":"trace[247120579] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"133.34939ms","start":"2026-03-18T05:36:42.890798Z","end":"2026-03-18T05:36:43.024148Z","steps":["trace[247120579] 'read index received' (duration: 133.165816ms)","trace[247120579] 'applied index is now lower than readState.Index' (duration: 182.544µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:43.024345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.539355ms","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-18T05:36:43.024378Z","caller":"traceutil/trace.go:171","msg":"trace[854826088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"133.646667ms","start":"2026-03-18T05:36:42.890718Z","end":"2026-03-18T05:36:43.024365Z","steps":["trace[854826088] 'agreement among raft nodes before linearized reading' (duration: 133.520954ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:43.131632Z","caller":"traceutil/trace.go:171","msg":"trace[1755318901] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"100.791832ms","start":"2026-03-18T05:36:43.030816Z","end":"2026-03-18T05:36:43.131607Z","steps":["trace[1755318901] 'read index received' (duration: 66.410254ms)","trace[1755318901] 'applied index is now lower than readState.Index' (duration: 34.380598ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:43.131676Z","caller":"traceutil/trace.go:171","msg":"trace[1466114587] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"101.996368ms","start":"2026-03-18T05:36:43.029654Z","end":"2026-03-18T05:36:43.131651Z","steps":["trace[1466114587] 'process raft request' (duration: 101.809674ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:43.131756Z","caller":"traceutil/trace.go:171","msg":"trace[1584843471] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"101.343304ms","start":"2026-03-18T05:36:43.030384Z","end":"2026-03-18T05:36:43.131727Z","steps":["trace[1584843471] 'process raft request' (duration: 101.188361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:43.131915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.078688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-18T05:36:43.131962Z","caller":"traceutil/trace.go:171","msg":"trace[731016010] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:935; }","duration":"101.1405ms","start":"2026-03-18T05:36:43.030811Z","end":"2026-03-18T05:36:43.131951Z","steps":["trace[731016010] 'agreement among raft nodes before linearized reading' (duration: 100.923765ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:46.216852Z","caller":"traceutil/trace.go:171","msg":"trace[2043505559] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"249.901775ms","start":"2026-03-18T05:36:45.966886Z","end":"2026-03-18T05:36:46.216787Z","steps":["trace[2043505559] 'process raft request' (duration: 249.650419ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:46.349637Z","caller":"traceutil/trace.go:171","msg":"trace[1090116611] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"100.947987ms","start":"2026-03-18T05:36:46.248631Z","end":"2026-03-18T05:36:46.349579Z","steps":["trace[1090116611] 'process raft request' (duration: 58.309267ms)","trace[1090116611] 'compare' (duration: 42.425486ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:47.517542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.635245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:36:47.517716Z","caller":"traceutil/trace.go:171","msg":"trace[1901944207] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"408.098455ms","start":"2026-03-18T05:36:47.109599Z","end":"2026-03-18T05:36:47.517698Z","steps":["trace[1901944207] 'read index received' (duration: 58.032µs)","trace[1901944207] 'applied index is now lower than readState.Index' (duration: 408.038893ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:47.517799Z","caller":"traceutil/trace.go:171","msg":"trace[1315907945] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"475.002871ms","start":"2026-03-18T05:36:47.042781Z","end":"2026-03-18T05:36:47.517784Z","steps":["trace[1315907945] 'process raft request' (duration: 56.990628ms)","trace[1315907945] 'compare' (duration: 416.776526ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:47.517826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.229258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:36:47.518777Z","caller":"traceutil/trace.go:171","msg":"trace[2095728050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"409.200238ms","start":"2026-03-18T05:36:47.109561Z","end":"2026-03-18T05:36:47.518761Z","steps":["trace[2095728050] 'agreement among raft nodes before linearized reading' (duration: 408.231497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.51785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.042759Z","time spent":"475.067493ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:36:47.517977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.083902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:36:47.519027Z","caller":"traceutil/trace.go:171","msg":"trace[1591244595] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:965; }","duration":"137.147326ms","start":"2026-03-18T05:36:47.381844Z","end":"2026-03-18T05:36:47.518992Z","steps":["trace[1591244595] 'agreement among raft nodes before linearized reading' (duration: 136.105914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.518057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.377751ms","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-18T05:36:47.5193Z","caller":"traceutil/trace.go:171","msg":"trace[2142623644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"390.670521ms","start":"2026-03-18T05:36:47.128614Z","end":"2026-03-18T05:36:47.519285Z","steps":["trace[2142623644] 'agreement among raft nodes before linearized reading' (duration: 389.30087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.519359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.128591Z","time spent":"390.741513ms","remote":"127.0.0.1:37506","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-18T05:36:47.518193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.721745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-18T05:36:47.519567Z","caller":"traceutil/trace.go:171","msg":"trace[856930963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"370.170507ms","start":"2026-03-18T05:36:47.149381Z","end":"2026-03-18T05:36:47.519551Z","steps":["trace[856930963] 'agreement among raft nodes before linearized reading' (duration: 368.614283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.519615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.149352Z","time spent":"370.248669ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:36:47.518246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.349977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-18T05:36:47.519853Z","caller":"traceutil/trace.go:171","msg":"trace[1024146153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"370.007734ms","start":"2026-03-18T05:36:47.149833Z","end":"2026-03-18T05:36:47.519841Z","steps":["trace[1024146153] 'agreement among raft nodes before linearized reading' (duration: 368.155782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.519891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.149822Z","time spent":"370.058165ms","remote":"127.0.0.1:37506","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-18T05:36:47.521218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.109542Z","time spent":"409.297061ms","remote":"127.0.0.1:37228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T05:36:47.895662Z","caller":"traceutil/trace.go:171","msg":"trace[933670595] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"370.697759ms","start":"2026-03-18T05:36:47.524932Z","end":"2026-03-18T05:36:47.89563Z","steps":["trace[933670595] 'process raft request' (duration: 284.588309ms)","trace[933670595] 'compare' (duration: 85.932936ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:47.895844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.524907Z","time spent":"370.838553ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:47.896102Z","caller":"traceutil/trace.go:171","msg":"trace[316561821] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"370.939515ms","start":"2026-03-18T05:36:47.525129Z","end":"2026-03-18T05:36:47.896069Z","steps":["trace[316561821] 'process raft request' (duration: 370.454514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.896295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.5251Z","time spent":"371.098158ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:47.896348Z","caller":"traceutil/trace.go:171","msg":"trace[19011616] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1009; }","duration":"370.615267ms","start":"2026-03-18T05:36:47.525709Z","end":"2026-03-18T05:36:47.896324Z","steps":["trace[19011616] 'read index received' (duration: 283.829903ms)","trace[19011616] 'applied index is now lower than readState.Index' (duration: 86.779354ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:47.896418Z","caller":"traceutil/trace.go:171","msg":"trace[1212863636] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"370.992606ms","start":"2026-03-18T05:36:47.525406Z","end":"2026-03-18T05:36:47.896399Z","steps":["trace[1212863636] 'process raft request' (duration: 370.560637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.896466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.755171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:36:47.8965Z","caller":"traceutil/trace.go:171","msg":"trace[596558529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"370.905314ms","start":"2026-03-18T05:36:47.525583Z","end":"2026-03-18T05:36:47.896489Z","steps":["trace[596558529] 'agreement among raft nodes before linearized reading' (duration: 370.822172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.89651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.525377Z","time spent":"371.083807ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:36:47.896534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.52557Z","time spent":"370.956715ms","remote":"127.0.0.1:37236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T05:36:48.756927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.435397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:36:48.757045Z","caller":"traceutil/trace.go:171","msg":"trace[1826572782] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"211.349863ms","start":"2026-03-18T05:36:48.545675Z","end":"2026-03-18T05:36:48.757025Z","steps":["trace[1826572782] 'process raft request' (duration: 85.724262ms)","trace[1826572782] 'compare' (duration: 125.292604ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:50.934117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.471875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:36:50.934223Z","caller":"traceutil/trace.go:171","msg":"trace[2065877340] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:980; }","duration":"312.619608ms","start":"2026-03-18T05:36:50.621581Z","end":"2026-03-18T05:36:50.934201Z","steps":["trace[2065877340] 'count revisions from in-memory index tree' (duration: 312.332012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:50.93423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.966142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-18T05:36:50.93429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:50.621519Z","time spent":"312.757312ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-03-18T05:36:50.934305Z","caller":"traceutil/trace.go:171","msg":"trace[1765942246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"171.121065ms","start":"2026-03-18T05:36:50.763166Z","end":"2026-03-18T05:36:50.934287Z","steps":["trace[1765942246] 'range keys from in-memory index tree' (duration: 170.746607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:00.404541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.379795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:37:00.404647Z","caller":"traceutil/trace.go:171","msg":"trace[125649677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"294.545999ms","start":"2026-03-18T05:37:00.110079Z","end":"2026-03-18T05:37:00.404625Z","steps":["trace[125649677] 'range keys from in-memory index tree' (duration: 294.296124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:00.404637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.449587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:37:00.404721Z","caller":"traceutil/trace.go:171","msg":"trace[1912137977] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1048; }","duration":"188.610221ms","start":"2026-03-18T05:37:00.21609Z","end":"2026-03-18T05:37:00.4047Z","steps":["trace[1912137977] 'count revisions from in-memory index tree' (duration: 188.317875ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:00.857345Z","caller":"traceutil/trace.go:171","msg":"trace[2011953228] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"219.737399ms","start":"2026-03-18T05:37:00.637568Z","end":"2026-03-18T05:37:00.857306Z","steps":["trace[2011953228] 'process raft request' (duration: 219.500474ms)"],"step_count":1}