blob: 1c4caf86b0164e05e31c137c5c4f8b2635ff775c [file] [log] [blame]
mpagenko80622a52021-02-09 16:53:23 +00001/*
2 * Copyright 2020-present Open Networking Foundation
3 *
4 * Licensed under the Apache License, Version 2.0 (the "License");
5 * you may not use this file except in compliance with the License.
6 * You may obtain a copy of the License at
7 *
8 * http://www.apache.org/licenses/LICENSE-2.0
9 *
10 * Unless required by applicable law or agreed to in writing, software
11 * distributed under the License is distributed on an "AS IS" BASIS,
12 * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
13 * See the License for the specific language governing permissions and
14 * limitations under the License.
15 */
16
17//Package adaptercoreonu provides the utility for onu devices, flows and statistics
18package adaptercoreonu
19
20import (
21 "context"
mpagenkoc26d4c02021-05-06 14:27:57 +000022 "encoding/binary"
mpagenko80622a52021-02-09 16:53:23 +000023 "fmt"
24 "strconv"
mpagenkoc26d4c02021-05-06 14:27:57 +000025 "sync"
mpagenko80622a52021-02-09 16:53:23 +000026 "time"
27
28 "github.com/boguslaw-wojcik/crc32a"
29 "github.com/looplab/fsm"
30 "github.com/opencord/omci-lib-go"
31 me "github.com/opencord/omci-lib-go/generated"
32 "github.com/opencord/voltha-lib-go/v4/pkg/log"
33 "github.com/opencord/voltha-protos/v4/go/voltha"
34)
35
36const cMaxUint32 = ^uint32(0)
37
38const (
39 // internal predefined values - some off them should later be configurable (perhaps with theses as defaults)
40 cOmciDownloadSectionSize = 31 //in bytes
41 cOmciDownloadWindowSizeLimit = 31 //in sections for window offset (windowSize(32)-1)
42 //cOmciDownloadWindowRetryMax = 2 // max attempts for a specific window
mpagenkoc26d4c02021-05-06 14:27:57 +000043 cOmciSectionInterleaveMilliseconds = 0 //DownloadSection interleave time in milliseconds (0 for no delay)
44 cOmciEndSwDlDelaySeconds = 1 //End Software Download delay after last section (may be also configurable?)
45 cWaitCountEndSwDl = 6 //maximum number of EndSwDl requests
46 cWaitDelayEndSwDlSeconds = 10 //duration, how long is waited before next request on EndSwDl
mpagenko80622a52021-02-09 16:53:23 +000047 //cOmciDownloadCompleteTimeout = 5400 //in s for the complete timeout (may be better scale to image size/ noOfWindows)
48)
49
50const (
51 // events of config PON ANI port FSM
52 upgradeEvStart = "upgradeEvStart"
mpagenkoc26d4c02021-05-06 14:27:57 +000053 upgradeEvAdapterDownload = "upgradeEvAdapterDownload"
mpagenko80622a52021-02-09 16:53:23 +000054 upgradeEvPrepareSwDownload = "upgradeEvPrepareSwDownload"
55 upgradeEvRxStartSwDownload = "upgradeEvRxStartSwDownload"
56 upgradeEvWaitWindowAck = "upgradeEvWaitWindowAck"
57 upgradeEvContinueNextWindow = "upgradeEvContinueNextWindow"
58 upgradeEvEndSwDownload = "upgradeEvEndSwDownload"
mpagenko59498c12021-03-18 14:15:15 +000059 upgradeEvWaitEndDownload = "upgradeEvWaitEndDownload"
60 upgradeEvContinueFinalize = "upgradeEvContinueFinalize"
mpagenkoc26d4c02021-05-06 14:27:57 +000061 upgradeEvWaitForActivate = "upgradeEvWaitForActivate"
mpagenko80622a52021-02-09 16:53:23 +000062 upgradeEvRequestActivate = "upgradeEvRequestActivate"
63 upgradeEvWaitForCommit = "upgradeEvWaitForCommit"
64 upgradeEvCommitSw = "upgradeEvCommitSw"
mpagenko15ff4a52021-03-02 10:09:20 +000065 upgradeEvCheckCommitted = "upgradeEvCheckCommitted"
mpagenko80622a52021-02-09 16:53:23 +000066
67 //upgradeEvTimeoutSimple = "upgradeEvTimeoutSimple"
68 //upgradeEvTimeoutMids = "upgradeEvTimeoutMids"
69 upgradeEvReset = "upgradeEvReset"
70 upgradeEvAbort = "upgradeEvAbort"
71 upgradeEvRestart = "upgradeEvRestart"
72)
73
74const (
75 // states of config PON ANI port FSM
76 upgradeStDisabled = "upgradeStDisabled"
77 upgradeStStarting = "upgradeStStarting"
mpagenkoc26d4c02021-05-06 14:27:57 +000078 upgradeStWaitingAdapterDL = "upgradeStWaitingAdapterDL"
mpagenko80622a52021-02-09 16:53:23 +000079 upgradeStPreparingDL = "upgradeStPreparingDL"
80 upgradeStDLSection = "upgradeStDLSection"
81 upgradeStVerifyWindow = "upgradeStVerifyWindow"
82 upgradeStFinalizeDL = "upgradeStFinalizeDL"
mpagenko59498c12021-03-18 14:15:15 +000083 upgradeStWaitEndDL = "upgradeStWaitEndDL"
mpagenkoc26d4c02021-05-06 14:27:57 +000084 upgradeStWaitForActivate = "upgradeStWaitForActivate"
mpagenko80622a52021-02-09 16:53:23 +000085 upgradeStRequestingActivate = "upgradeStRequestingActivate"
86 upgradeStWaitForCommit = "upgradeStWaitForCommit"
87 upgradeStCommitSw = "upgradeStCommitSw"
mpagenko15ff4a52021-03-02 10:09:20 +000088 upgradeStCheckCommitted = "upgradeStCheckCommitted"
mpagenko80622a52021-02-09 16:53:23 +000089 upgradeStResetting = "upgradeStResetting"
90)
91
92//required definition for IdleState detection for activities on OMCI
93const cOnuUpgradeFsmIdleState = upgradeStWaitForCommit
94
95//OnuUpgradeFsm defines the structure for the state machine to config the PON ANI ports of ONU UNI ports via OMCI
96type OnuUpgradeFsm struct {
97 pDeviceHandler *deviceHandler
98 pDownloadManager *adapterDownloadManager
mpagenkoc26d4c02021-05-06 14:27:57 +000099 pFileManager *fileDownloadManager //used from R2.8 with new API version
mpagenko80622a52021-02-09 16:53:23 +0000100 deviceID string
mpagenko15ff4a52021-03-02 10:09:20 +0000101 pOnuOmciDevice *OnuDeviceEntry
mpagenko80622a52021-02-09 16:53:23 +0000102 pOmciCC *omciCC
103 pOnuDB *onuDeviceDB
104 requestEvent OnuDeviceEvent
105 //omciMIdsResponseReceived chan bool //seperate channel needed for checking multiInstance OMCI message responses
mpagenkoc26d4c02021-05-06 14:27:57 +0000106 pAdaptFsm *AdapterFsm
107 pImageDsc *voltha.ImageDownload
108 imageBuffer []byte
109 origImageLength uint32 //as also limited by OMCI
110 imageCRC uint32 //as per OMCI - ITU I.363.5 crc
111 imageLength uint32 //including last bytes padding
112 omciDownloadWindowSizeLimit uint8 //windowSize-1 in sections
113 omciDownloadWindowSizeLast uint8 //number of sections in last window
114 noOfSections uint32 //uint32 range for sections should be sufficient for very long images
115 nextDownloadSectionsAbsolute uint32 //number of next section to download in overall image
116 nextDownloadSectionsWindow uint8 //number of next section to download within current window
117 noOfWindows uint32 //uint32 range for windows should be sufficient for very long images
118 nextDownloadWindow uint32 //number of next window to download
119 inactiveImageMeID uint16 //ME-ID of the inactive image
120 downloadToOnuTimeout4MB time.Duration //timeout for downloading the image to the ONU for a 4MB image slice
121 omciSectionInterleaveDelay time.Duration //DownloadSectionInterleave delay in milliseconds
122 delayEndSwDl bool //flag to provide a delay between last section and EndSwDl
123 pLastTxMeInstance *me.ManagedEntity
124 waitCountEndSwDl uint8 //number, how often is waited for EndSwDl at maximum
125 waitDelayEndSwDl time.Duration //duration, how long is waited before next request on EndSwDl
126 chReceiveExpectedResponse chan bool
mpagenkoaa3afe92021-05-21 16:20:58 +0000127 useAPIVersion43 bool //flag for indication on which API version is used (and accordingly which specific methods)
128 mutexUpgradeParams sync.RWMutex //mutex to protect members for parallel function requests and omci response processing
129 imageVersion string //name of the image as used within OMCI (and on extrenal API interface)
130 imageIdentifier string //name of the image as used in the adapter
mpagenkoc26d4c02021-05-06 14:27:57 +0000131 mutexIsAwaitingAdapterDlResponse sync.RWMutex
132 chAdapterDlReady chan bool
133 isWaitingForAdapterDlResponse bool
134 mutexIsAwaitingOnuDlResponse sync.RWMutex
135 chOnuDlReady chan bool
136 isWaitingForOnuDlResponse bool
137 activateImage bool
138 commitImage bool
mpagenkoaa3afe92021-05-21 16:20:58 +0000139 volthaDownloadState voltha.ImageState_ImageDownloadState
140 volthaDownloadReason voltha.ImageState_ImageFailureReason
141 volthaImageState voltha.ImageState_ImageActivationState
mpagenko80622a52021-02-09 16:53:23 +0000142}
143
144//NewOnuUpgradeFsm is the 'constructor' for the state machine to config the PON ANI ports
145// of ONU UNI ports via OMCI
146func NewOnuUpgradeFsm(ctx context.Context, apDeviceHandler *deviceHandler,
mpagenko15ff4a52021-03-02 10:09:20 +0000147 apDevEntry *OnuDeviceEntry, apOnuDB *onuDeviceDB,
mpagenko80622a52021-02-09 16:53:23 +0000148 aRequestEvent OnuDeviceEvent, aName string, aCommChannel chan Message) *OnuUpgradeFsm {
149 instFsm := &OnuUpgradeFsm{
mpagenko59498c12021-03-18 14:15:15 +0000150 pDeviceHandler: apDeviceHandler,
151 deviceID: apDeviceHandler.deviceID,
152 pOnuOmciDevice: apDevEntry,
153 pOmciCC: apDevEntry.PDevOmciCC,
154 pOnuDB: apOnuDB,
155 requestEvent: aRequestEvent,
156 omciDownloadWindowSizeLimit: cOmciDownloadWindowSizeLimit,
157 omciSectionInterleaveDelay: cOmciSectionInterleaveMilliseconds,
Holger Hildebrandtac010732021-06-02 13:35:39 +0000158 downloadToOnuTimeout4MB: apDeviceHandler.pOpenOnuAc.dlToOnuTimeout4M,
mpagenko59498c12021-03-18 14:15:15 +0000159 waitCountEndSwDl: cWaitCountEndSwDl,
160 waitDelayEndSwDl: cWaitDelayEndSwDlSeconds,
mpagenkoaa3afe92021-05-21 16:20:58 +0000161 volthaDownloadState: voltha.ImageState_DOWNLOAD_STARTED, //if FSM created we can assume that the download (to adapter) really started
162 volthaDownloadReason: voltha.ImageState_NO_ERROR,
163 volthaImageState: voltha.ImageState_IMAGE_UNKNOWN,
mpagenko80622a52021-02-09 16:53:23 +0000164 }
mpagenko59498c12021-03-18 14:15:15 +0000165 instFsm.chReceiveExpectedResponse = make(chan bool)
mpagenkoc26d4c02021-05-06 14:27:57 +0000166 instFsm.chAdapterDlReady = make(chan bool)
167 instFsm.chOnuDlReady = make(chan bool)
mpagenko80622a52021-02-09 16:53:23 +0000168
169 instFsm.pAdaptFsm = NewAdapterFsm(aName, instFsm.deviceID, aCommChannel)
170 if instFsm.pAdaptFsm == nil {
171 logger.Errorw(ctx, "OnuUpgradeFsm's AdapterFsm could not be instantiated!!", log.Fields{
172 "device-id": instFsm.deviceID})
173 return nil
174 }
175 instFsm.pAdaptFsm.pFsm = fsm.NewFSM(
176 upgradeStDisabled,
177 fsm.Events{
178 {Name: upgradeEvStart, Src: []string{upgradeStDisabled}, Dst: upgradeStStarting},
mpagenkoc26d4c02021-05-06 14:27:57 +0000179 {Name: upgradeEvAdapterDownload, Src: []string{upgradeStStarting}, Dst: upgradeStWaitingAdapterDL},
180 {Name: upgradeEvPrepareSwDownload, Src: []string{upgradeStStarting, upgradeStWaitingAdapterDL}, Dst: upgradeStPreparingDL},
mpagenko80622a52021-02-09 16:53:23 +0000181 {Name: upgradeEvRxStartSwDownload, Src: []string{upgradeStPreparingDL}, Dst: upgradeStDLSection},
182 {Name: upgradeEvWaitWindowAck, Src: []string{upgradeStDLSection}, Dst: upgradeStVerifyWindow},
183 {Name: upgradeEvContinueNextWindow, Src: []string{upgradeStVerifyWindow}, Dst: upgradeStDLSection},
184 {Name: upgradeEvEndSwDownload, Src: []string{upgradeStVerifyWindow}, Dst: upgradeStFinalizeDL},
mpagenko59498c12021-03-18 14:15:15 +0000185 {Name: upgradeEvWaitEndDownload, Src: []string{upgradeStFinalizeDL}, Dst: upgradeStWaitEndDL},
186 {Name: upgradeEvContinueFinalize, Src: []string{upgradeStWaitEndDL}, Dst: upgradeStFinalizeDL},
mpagenkoc26d4c02021-05-06 14:27:57 +0000187 {Name: upgradeEvWaitForActivate, Src: []string{upgradeStWaitEndDL}, Dst: upgradeStWaitForActivate},
188 {Name: upgradeEvRequestActivate, Src: []string{upgradeStStarting, upgradeStWaitEndDL, upgradeStWaitForActivate},
189 Dst: upgradeStRequestingActivate}, //allows also for direct activation (without download) [TODO!!!]
mpagenko80622a52021-02-09 16:53:23 +0000190 {Name: upgradeEvWaitForCommit, Src: []string{upgradeStRequestingActivate}, Dst: upgradeStWaitForCommit},
191 {Name: upgradeEvCommitSw, Src: []string{upgradeStStarting, upgradeStWaitForCommit},
mpagenkoc26d4c02021-05-06 14:27:57 +0000192 Dst: upgradeStCommitSw}, //allows also for direct commitment (without download) [TODO!!!]
mpagenko15ff4a52021-03-02 10:09:20 +0000193 {Name: upgradeEvCheckCommitted, Src: []string{upgradeStCommitSw}, Dst: upgradeStCheckCommitted},
mpagenko80622a52021-02-09 16:53:23 +0000194
195 /*
196 {Name: upgradeEvTimeoutSimple, Src: []string{
197 upgradeStCreatingDot1PMapper, upgradeStCreatingMBPCD, upgradeStSettingTconts, upgradeStSettingDot1PMapper}, Dst: upgradeStStarting},
198 {Name: upgradeEvTimeoutMids, Src: []string{
199 upgradeStCreatingGemNCTPs, upgradeStCreatingGemIWs, upgradeStSettingPQs}, Dst: upgradeStStarting},
200 */
201 // exceptional treatments
mpagenkoc26d4c02021-05-06 14:27:57 +0000202 {Name: upgradeEvReset, Src: []string{upgradeStStarting, upgradeStWaitingAdapterDL, upgradeStPreparingDL, upgradeStDLSection,
203 upgradeStVerifyWindow, upgradeStDLSection, upgradeStFinalizeDL, upgradeStWaitEndDL, upgradeStWaitForActivate,
204 upgradeStRequestingActivate, upgradeStCommitSw, upgradeStCheckCommitted}, //upgradeStWaitForCommit is not reset (later perhaps also not upgradeStWaitActivate)
mpagenko80622a52021-02-09 16:53:23 +0000205 Dst: upgradeStResetting},
mpagenkoc26d4c02021-05-06 14:27:57 +0000206 {Name: upgradeEvAbort, Src: []string{upgradeStStarting, upgradeStWaitingAdapterDL, upgradeStPreparingDL, upgradeStDLSection,
207 upgradeStVerifyWindow, upgradeStDLSection, upgradeStFinalizeDL, upgradeStWaitEndDL, upgradeStWaitForActivate,
208 upgradeStRequestingActivate, upgradeStWaitForCommit, upgradeStCommitSw, upgradeStCheckCommitted},
mpagenko80622a52021-02-09 16:53:23 +0000209 Dst: upgradeStResetting},
210 {Name: upgradeEvRestart, Src: []string{upgradeStResetting}, Dst: upgradeStDisabled},
211 },
212 fsm.Callbacks{
213 "enter_state": func(e *fsm.Event) { instFsm.pAdaptFsm.logFsmStateChange(ctx, e) },
214 "enter_" + upgradeStStarting: func(e *fsm.Event) { instFsm.enterStarting(ctx, e) },
mpagenkoc26d4c02021-05-06 14:27:57 +0000215 "enter_" + upgradeStWaitingAdapterDL: func(e *fsm.Event) { instFsm.enterWaitingAdapterDL(ctx, e) },
mpagenko80622a52021-02-09 16:53:23 +0000216 "enter_" + upgradeStPreparingDL: func(e *fsm.Event) { instFsm.enterPreparingDL(ctx, e) },
217 "enter_" + upgradeStDLSection: func(e *fsm.Event) { instFsm.enterDownloadSection(ctx, e) },
218 "enter_" + upgradeStVerifyWindow: func(e *fsm.Event) { instFsm.enterVerifyWindow(ctx, e) },
219 "enter_" + upgradeStFinalizeDL: func(e *fsm.Event) { instFsm.enterFinalizeDL(ctx, e) },
mpagenko59498c12021-03-18 14:15:15 +0000220 "enter_" + upgradeStWaitEndDL: func(e *fsm.Event) { instFsm.enterWaitEndDL(ctx, e) },
mpagenko80622a52021-02-09 16:53:23 +0000221 "enter_" + upgradeStRequestingActivate: func(e *fsm.Event) { instFsm.enterActivateSw(ctx, e) },
222 "enter_" + upgradeStCommitSw: func(e *fsm.Event) { instFsm.enterCommitSw(ctx, e) },
mpagenko15ff4a52021-03-02 10:09:20 +0000223 "enter_" + upgradeStCheckCommitted: func(e *fsm.Event) { instFsm.enterCheckCommitted(ctx, e) },
mpagenko80622a52021-02-09 16:53:23 +0000224 "enter_" + upgradeStResetting: func(e *fsm.Event) { instFsm.enterResetting(ctx, e) },
225 "enter_" + upgradeStDisabled: func(e *fsm.Event) { instFsm.enterDisabled(ctx, e) },
226 },
227 )
228 if instFsm.pAdaptFsm.pFsm == nil {
229 logger.Errorw(ctx, "OnuUpgradeFsm's Base FSM could not be instantiated!!", log.Fields{
230 "device-id": instFsm.deviceID})
231 return nil
232 }
233
234 logger.Debugw(ctx, "OnuUpgradeFsm created", log.Fields{"device-id": instFsm.deviceID})
235 return instFsm
236}
237
238//SetDownloadParams configures the needed parameters for a specific download to the ONU
mpagenkoc26d4c02021-05-06 14:27:57 +0000239// called from 'old' API Activate_image_update()
mpagenko15ff4a52021-03-02 10:09:20 +0000240func (oFsm *OnuUpgradeFsm) SetDownloadParams(ctx context.Context, aInactiveImageID uint16,
241 apImageDsc *voltha.ImageDownload, apDownloadManager *adapterDownloadManager) error {
mpagenko80622a52021-02-09 16:53:23 +0000242 pBaseFsm := oFsm.pAdaptFsm.pFsm
243 if pBaseFsm != nil && pBaseFsm.Is(upgradeStStarting) {
mpagenkoaa3afe92021-05-21 16:20:58 +0000244 oFsm.mutexUpgradeParams.Lock()
mpagenko80622a52021-02-09 16:53:23 +0000245 logger.Debugw(ctx, "OnuUpgradeFsm Parameter setting", log.Fields{
246 "device-id": oFsm.deviceID, "image-description": apImageDsc})
mpagenko15ff4a52021-03-02 10:09:20 +0000247 oFsm.inactiveImageMeID = aInactiveImageID //upgrade state machines run on configured inactive ImageId
mpagenko80622a52021-02-09 16:53:23 +0000248 oFsm.pImageDsc = apImageDsc
249 oFsm.pDownloadManager = apDownloadManager
mpagenkoaa3afe92021-05-21 16:20:58 +0000250 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000251
252 go func(aPBaseFsm *fsm.FSM) {
mpagenkoc26d4c02021-05-06 14:27:57 +0000253 // let the upgrade FSM proceed to PreparingDL
mpagenko80622a52021-02-09 16:53:23 +0000254 _ = aPBaseFsm.Event(upgradeEvPrepareSwDownload)
255 }(pBaseFsm)
256 return nil
257 }
258 logger.Errorw(ctx, "OnuUpgradeFsm abort: invalid FSM base pointer or state", log.Fields{
259 "device-id": oFsm.deviceID})
260 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm abort: invalid FSM base pointer or state for device-id: %s", oFsm.deviceID))
261}
262
mpagenkoc26d4c02021-05-06 14:27:57 +0000263//SetDownloadParamsAfterDownload configures the needed parameters for a specific download to the ONU according to
264// updated API interface with R2.8: start download to ONU if the image is downloaded to the adapter
265// called from 'new' API Download_onu_image
266func (oFsm *OnuUpgradeFsm) SetDownloadParamsAfterDownload(ctx context.Context, aInactiveImageID uint16,
267 apImageRequest *voltha.DeviceImageDownloadRequest, apDownloadManager *fileDownloadManager,
Holger Hildebrandtac010732021-06-02 13:35:39 +0000268 aImageIdentifier string) error {
mpagenkoc26d4c02021-05-06 14:27:57 +0000269 oFsm.mutexUpgradeParams.Lock()
270 var pBaseFsm *fsm.FSM = nil
271 if oFsm.pAdaptFsm != nil {
272 pBaseFsm = oFsm.pAdaptFsm.pFsm
273 }
274 if pBaseFsm != nil && pBaseFsm.Is(upgradeStStarting) {
275 logger.Debugw(ctx, "OnuUpgradeFsm Parameter setting", log.Fields{
276 "device-id": oFsm.deviceID, "image-description": apImageRequest})
277 oFsm.useAPIVersion43 = true
278 oFsm.inactiveImageMeID = aInactiveImageID //upgrade state machines run on configured inactive ImageId
279 oFsm.pFileManager = apDownloadManager
280 oFsm.imageIdentifier = aImageIdentifier
281 oFsm.imageVersion = apImageRequest.Image.Version
282 oFsm.activateImage = apImageRequest.ActivateOnSuccess
283 oFsm.commitImage = apImageRequest.CommitOnSuccess
mpagenkoc26d4c02021-05-06 14:27:57 +0000284 //TODO: currently straightforward options activate and commit are expected to be set and (unconditionally) done
285 // for separate handling of these options the FSM must accordingly branch from the concerned states - later
286 oFsm.mutexUpgradeParams.Unlock()
287 _ = pBaseFsm.Event(upgradeEvAdapterDownload) //no need to call the FSM event in background here
288 return nil
289 }
290 oFsm.mutexUpgradeParams.Unlock()
291 logger.Errorw(ctx, "OnuUpgradeFsm abort: invalid FSM base pointer or state", log.Fields{
292 "device-id": oFsm.deviceID})
293 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm abort: invalid FSM base pointer or state for device-id: %s", oFsm.deviceID))
294}
295
296//SetActivationParamsRunning sets the activate and commit flags for a running download to the ONU according to adapters rpc call
297// called from 'new' API Activate_onu_image
298func (oFsm *OnuUpgradeFsm) SetActivationParamsRunning(ctx context.Context,
299 aImageIdentifier string, aCommit bool) error {
300 oFsm.mutexUpgradeParams.Lock()
301 //set activate/commit independent from state, if FSM is already beyond concerned states, then it does not matter anyway
302 // (as long as the Imageidentifier is correct)
303 logger.Debugw(ctx, "OnuUpgradeFsm activate/commit parameter setting", log.Fields{
304 "device-id": oFsm.deviceID, "image-id": aImageIdentifier, "commit": aCommit})
305 if aImageIdentifier != oFsm.imageIdentifier {
306 logger.Errorw(ctx, "OnuUpgradeFsm abort: mismatching upgrade image", log.Fields{
307 "device-id": oFsm.deviceID, "request-image": aImageIdentifier, "fsm-image": oFsm.imageIdentifier})
308 oFsm.mutexUpgradeParams.Unlock()
309 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm params ignored: requested image-name not used in current upgrade for device-id: %s",
310 oFsm.deviceID))
311 }
312 oFsm.activateImage = true
313 oFsm.commitImage = aCommit
314 oFsm.mutexUpgradeParams.Unlock()
315 var pBaseFsm *fsm.FSM = nil
316 if oFsm.pAdaptFsm != nil {
317 pBaseFsm = oFsm.pAdaptFsm.pFsm
318 }
319 if pBaseFsm != nil {
320 if pBaseFsm.Is(upgradeStWaitForActivate) {
321 logger.Debugw(ctx, "OnuUpgradeFsm finish waiting for activate", log.Fields{"device-id": oFsm.deviceID})
322 _ = pBaseFsm.Event(upgradeEvRequestActivate) //no need to call the FSM event in background here
323 }
324 return nil
325 }
326 logger.Errorw(ctx, "OnuUpgradeFsm abort: invalid FSM base pointer", log.Fields{
327 "device-id": oFsm.deviceID})
328 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm abort: invalid FSM base pointer for device-id: %s", oFsm.deviceID))
329}
330
331//SetActivationParamsStart starts upgrade processing with immediate activation
332// called from 'new' API Activate_onu_image
333func (oFsm *OnuUpgradeFsm) SetActivationParamsStart(ctx context.Context, aImageVersion string, aInactiveImageID uint16, aCommit bool) error {
334 oFsm.mutexUpgradeParams.Lock()
335 var pBaseFsm *fsm.FSM = nil
336 if oFsm.pAdaptFsm != nil {
337 pBaseFsm = oFsm.pAdaptFsm.pFsm
338 }
339 if pBaseFsm != nil && pBaseFsm.Is(upgradeStStarting) {
340 logger.Debugw(ctx, "OnuUpgradeFsm Parameter setting to start with activation", log.Fields{
341 "device-id": oFsm.deviceID, "image-version": aImageVersion})
342 oFsm.useAPIVersion43 = true
343 oFsm.inactiveImageMeID = aInactiveImageID //upgrade state machines run on configured inactive ImageId
344 oFsm.imageVersion = aImageVersion
345 oFsm.activateImage = true
346 oFsm.commitImage = aCommit
347 oFsm.mutexUpgradeParams.Unlock()
348 //directly request the FSM to activate the image
349 _ = pBaseFsm.Event(upgradeEvRequestActivate) //no need to call the FSM event in background here
350 return nil
351 }
352 oFsm.mutexUpgradeParams.Unlock()
353 logger.Errorw(ctx, "OnuUpgradeFsm abort: invalid FSM base pointer or state", log.Fields{
354 "device-id": oFsm.deviceID})
355 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm abort: invalid FSM base pointer or state for device-id: %s", oFsm.deviceID))
356}
357
358//SetCommitmentParamsRunning sets the commit flag for a running download to the ONU according to adapters rpc call
359// called from 'new' API Commit_onu_image
360func (oFsm *OnuUpgradeFsm) SetCommitmentParamsRunning(ctx context.Context, aImageIdentifier string) error {
361 oFsm.mutexUpgradeParams.Lock()
362 //set commit independent from state, if FSM is already beyond commit state (just ready), then it does not matter anyway
363 // (as long as the Imageidentifier is correct)
364 logger.Debugw(ctx, "OnuUpgradeFsm commit parameter setting", log.Fields{
365 "device-id": oFsm.deviceID, "image-id": aImageIdentifier})
366 if aImageIdentifier != oFsm.imageIdentifier {
367 logger.Errorw(ctx, "OnuUpgradeFsm abort: mismatching upgrade image", log.Fields{
368 "device-id": oFsm.deviceID, "request-image": aImageIdentifier, "fsm-image": oFsm.imageIdentifier})
369 oFsm.mutexUpgradeParams.Unlock()
370 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm params ignored: requested image-name not used in current upgrade for device-id: %s",
371 oFsm.deviceID))
372 }
373 oFsm.commitImage = true
374 oFsm.mutexUpgradeParams.Unlock()
375 var pBaseFsm *fsm.FSM = nil
376 if oFsm.pAdaptFsm != nil {
377 pBaseFsm = oFsm.pAdaptFsm.pFsm
378 }
379 if pBaseFsm != nil {
380 if pBaseFsm.Is(upgradeStWaitForCommit) {
381 logger.Debugw(ctx, "OnuUpgradeFsm finish waiting for commit", log.Fields{"device-id": oFsm.deviceID})
382 _ = pBaseFsm.Event(upgradeEvCommitSw) //no need to call the FSM event in background here
383 }
384 return nil
385 }
386 logger.Errorw(ctx, "OnuUpgradeFsm abort: invalid FSM base pointer", log.Fields{
387 "device-id": oFsm.deviceID})
388 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm abort: invalid FSM base pointer for device-id: %s", oFsm.deviceID))
389}
390
391//SetCommitmentParamsStart starts upgrade processing with immediate commitment
392// called from 'new' API Commit_onu_image
393func (oFsm *OnuUpgradeFsm) SetCommitmentParamsStart(ctx context.Context, aImageVersion string, aActiveImageID uint16) error {
394 oFsm.mutexUpgradeParams.Lock()
395 var pBaseFsm *fsm.FSM = nil
396 if oFsm.pAdaptFsm != nil {
397 pBaseFsm = oFsm.pAdaptFsm.pFsm
398 }
399 if pBaseFsm != nil && pBaseFsm.Is(upgradeStStarting) {
400 logger.Debugw(ctx, "OnuUpgradeFsm Parameter setting to start with commitment", log.Fields{
401 "device-id": oFsm.deviceID, "image-version": aImageVersion})
402 oFsm.useAPIVersion43 = true
403 oFsm.inactiveImageMeID = aActiveImageID //upgrade state machines inactive ImageId is the new active ImageId
404 oFsm.imageVersion = aImageVersion
405 oFsm.commitImage = true
406 oFsm.mutexUpgradeParams.Unlock()
407 //directly request the FSM to activate the image
408 _ = pBaseFsm.Event(upgradeEvCommitSw) //no need to call the FSM event in background here
409 return nil
410 }
411 oFsm.mutexUpgradeParams.Unlock()
412 logger.Errorw(ctx, "OnuUpgradeFsm abort: invalid FSM base pointer or state", log.Fields{
413 "device-id": oFsm.deviceID})
414 return fmt.Errorf(fmt.Sprintf("OnuUpgradeFsm abort: invalid FSM base pointer or state for device-id: %s", oFsm.deviceID))
415}
416
mpagenkoaa3afe92021-05-21 16:20:58 +0000417//GetImageStates delivers the download states as per device proto buf or error indication
418func (oFsm *OnuUpgradeFsm) GetImageStates(ctx context.Context,
419 aImageIdentifier string, aVersion string) (*voltha.ImageState, error) {
420 pImageState := &voltha.ImageState{}
421 // check if the request refers to some active image/version of the processing
422 oFsm.mutexUpgradeParams.RLock()
423 if (aImageIdentifier == oFsm.imageIdentifier) || (aVersion == oFsm.imageVersion) {
424 pImageState.DownloadState = oFsm.volthaDownloadState
425 pImageState.Reason = oFsm.volthaDownloadReason
426 pImageState.ImageState = oFsm.volthaImageState
427 } else {
428 pImageState.DownloadState = voltha.ImageState_DOWNLOAD_UNKNOWN
429 pImageState.Reason = voltha.ImageState_NO_ERROR
430 pImageState.ImageState = voltha.ImageState_IMAGE_UNKNOWN
431 }
432 oFsm.mutexUpgradeParams.RUnlock()
433 return pImageState, nil
434}
435
mpagenkoc26d4c02021-05-06 14:27:57 +0000436//CancelProcessing ensures that suspended processing at waiting on some response is aborted and reset of FSM
437func (oFsm *OnuUpgradeFsm) CancelProcessing(ctx context.Context) {
438 //mutex protection is required for possible concurrent access to FSM members
439 //attention: for an unbuffered channel the sender is blocked until the value is received (processed)!
440 // accordingly the mutex must be released before sending to channel here (mutex acquired in receiver)
441 oFsm.mutexIsAwaitingAdapterDlResponse.RLock()
442 if oFsm.isWaitingForAdapterDlResponse {
443 oFsm.mutexIsAwaitingAdapterDlResponse.RUnlock()
444 //use channel to indicate that the download response waiting shall be aborted for this device (channel)
445 oFsm.chAdapterDlReady <- false
446 } else {
447 oFsm.mutexIsAwaitingAdapterDlResponse.RUnlock()
448 }
449 //chOnuDlReady is cleared as part of the FSM reset processing (from enterResetting())
450
451 // in any case (even if it might be automatically requested by above cancellation of waiting) ensure resetting the FSM
452 // specific here: If the FSM is in upgradeStWaitForCommit, it is left there for possibly later commit
453 // this possibly also refers later to (not yet existing) upgradeStWaitForActivate (with ctl API changes)
454 pAdaptFsm := oFsm.pAdaptFsm
455 if pAdaptFsm != nil {
456 // calling FSM events in background to avoid blocking of the caller
457 go func(aPAFsm *AdapterFsm) {
458 if aPAFsm.pFsm != nil {
459 if aPAFsm.pFsm.Is(upgradeStWaitEndDL) {
460 oFsm.chReceiveExpectedResponse <- false //which aborts the FSM (activate was not yet sent)
461 }
462 _ = aPAFsm.pFsm.Event(upgradeEvReset) //anyway and for all other states
463 } //else the FSM seems already to be in some released state
464 }(pAdaptFsm)
465 }
466}
467
mpagenko80622a52021-02-09 16:53:23 +0000468func (oFsm *OnuUpgradeFsm) enterStarting(ctx context.Context, e *fsm.Event) {
469 logger.Debugw(ctx, "OnuUpgradeFsm start", log.Fields{"in state": e.FSM.Current(),
470 "device-id": oFsm.deviceID})
471
472 // start go routine for processing of LockState messages
473 go oFsm.processOmciUpgradeMessages(ctx)
474}
475
mpagenkoc26d4c02021-05-06 14:27:57 +0000476//enterWaitingAdapterDL state can only be reached with useAPIVersion43
477func (oFsm *OnuUpgradeFsm) enterWaitingAdapterDL(ctx context.Context, e *fsm.Event) {
478 logger.Debugw(ctx, "OnuUpgradeFsm waiting for adapter download", log.Fields{"in state": e.FSM.Current(),
479 "device-id": oFsm.deviceID})
480 go oFsm.waitOnDownloadToAdapterReady(ctx, oFsm.chAdapterDlReady)
481 go oFsm.pFileManager.RequestDownloadReady(ctx, oFsm.imageIdentifier, oFsm.chAdapterDlReady)
482}
483
mpagenko80622a52021-02-09 16:53:23 +0000484func (oFsm *OnuUpgradeFsm) enterPreparingDL(ctx context.Context, e *fsm.Event) {
485 logger.Debugw(ctx, "OnuUpgradeFsm prepare Download to Onu", log.Fields{"in state": e.FSM.Current(),
486 "device-id": oFsm.deviceID})
487
mpagenkoc26d4c02021-05-06 14:27:57 +0000488 var fileLen int64
489 var err error
mpagenkoaa3afe92021-05-21 16:20:58 +0000490 oFsm.mutexUpgradeParams.Lock()
mpagenkoc26d4c02021-05-06 14:27:57 +0000491 if oFsm.useAPIVersion43 {
492 //with the new API structure download to adapter is implicit and we have to wait until the image is available
493 fileLen, err = oFsm.pFileManager.GetImageBufferLen(ctx, oFsm.imageIdentifier)
494 } else {
495 fileLen, err = oFsm.pDownloadManager.getImageBufferLen(ctx, oFsm.pImageDsc.Name, oFsm.pImageDsc.LocalDir)
496 }
mpagenko80622a52021-02-09 16:53:23 +0000497 if err != nil || fileLen > int64(cMaxUint32) {
mpagenkoaa3afe92021-05-21 16:20:58 +0000498 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000499 logger.Errorw(ctx, "OnuUpgradeFsm abort: problems getting image buffer length", log.Fields{
500 "device-id": oFsm.deviceID, "error": err, "length": fileLen})
501 pBaseFsm := oFsm.pAdaptFsm
502 // Can't call FSM Event directly, decoupling it
503 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000504 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000505 }(pBaseFsm)
506 return
507 }
508
mpagenkoc26d4c02021-05-06 14:27:57 +0000509 //copy file content to buffer
mpagenko80622a52021-02-09 16:53:23 +0000510 oFsm.imageBuffer = make([]byte, fileLen)
mpagenkoc26d4c02021-05-06 14:27:57 +0000511 if oFsm.useAPIVersion43 {
512 oFsm.imageBuffer, err = oFsm.pFileManager.GetDownloadImageBuffer(ctx, oFsm.imageIdentifier)
513 } else {
514 oFsm.imageBuffer, err = oFsm.pDownloadManager.getDownloadImageBuffer(ctx, oFsm.pImageDsc.Name, oFsm.pImageDsc.LocalDir)
515 }
mpagenko80622a52021-02-09 16:53:23 +0000516 if err != nil {
mpagenkoaa3afe92021-05-21 16:20:58 +0000517 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000518 logger.Errorw(ctx, "OnuUpgradeFsm abort: can't get image buffer", log.Fields{
519 "device-id": oFsm.deviceID, "error": err})
520 pBaseFsm := oFsm.pAdaptFsm
521 // Can't call FSM Event directly, decoupling it
522 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000523 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000524 }(pBaseFsm)
525 return
526 }
527
528 oFsm.noOfSections = uint32(fileLen / cOmciDownloadSectionSize)
529 if fileLen%cOmciDownloadSectionSize > 0 {
mpagenkoc26d4c02021-05-06 14:27:57 +0000530 bufferPadding := make([]byte, cOmciDownloadSectionSize-uint32((fileLen)%cOmciDownloadSectionSize))
mpagenko80622a52021-02-09 16:53:23 +0000531 //expand the imageBuffer to exactly fit multiples of cOmciDownloadSectionSize with padding
mpagenkoc26d4c02021-05-06 14:27:57 +0000532 oFsm.imageBuffer = append(oFsm.imageBuffer[:(fileLen)], bufferPadding...)
mpagenko80622a52021-02-09 16:53:23 +0000533 oFsm.noOfSections++
534 }
535 oFsm.origImageLength = uint32(fileLen)
536 oFsm.imageLength = uint32(len(oFsm.imageBuffer))
mpagenko80622a52021-02-09 16:53:23 +0000537 logger.Infow(ctx, "OnuUpgradeFsm starts with StartSwDl values", log.Fields{
538 "MeId": oFsm.inactiveImageMeID, "windowSizeLimit": oFsm.omciDownloadWindowSizeLimit,
539 "ImageSize": oFsm.imageLength, "original file size": fileLen})
540 //"NumberOfCircuitPacks": oFsm.numberCircuitPacks, "CircuitPacks MeId": 0}) //parallel circuit packs download not supported
mpagenkoaa3afe92021-05-21 16:20:58 +0000541
542 oFsm.mutexUpgradeParams.Unlock()
543 go oFsm.waitOnDownloadToOnuReady(ctx, oFsm.chOnuDlReady) // start supervision of the complete download-to-ONU procedure
544
Girish Gowdra0b235842021-03-09 13:06:46 -0800545 err = oFsm.pOmciCC.sendStartSoftwareDownload(log.WithSpanFromContext(context.TODO(), ctx), oFsm.pDeviceHandler.pOpenOnuAc.omciTimeout, false,
mpagenko80622a52021-02-09 16:53:23 +0000546 oFsm.pAdaptFsm.commChan, oFsm.inactiveImageMeID, oFsm.omciDownloadWindowSizeLimit, oFsm.origImageLength)
547 if err != nil {
548 logger.Errorw(ctx, "StartSwDl abort: can't send section", log.Fields{
549 "device-id": oFsm.deviceID, "error": err})
550 //TODO!!!: define some more sophisticated error treatment with some repetition, for now just reset the FSM
551 pBaseFsm := oFsm.pAdaptFsm
552 // Can't call FSM Event directly, decoupling it
553 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000554 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000555 }(pBaseFsm)
556 return
557 }
558}
559
560func (oFsm *OnuUpgradeFsm) enterDownloadSection(ctx context.Context, e *fsm.Event) {
561 logger.Debugw(ctx, "OnuUpgradeFsm start downloading sections", log.Fields{
562 "device-id": oFsm.deviceID, "absolute window": oFsm.nextDownloadWindow})
563
564 var windowAckRequest uint8 = 0
565 var bufferStartOffset uint32
566 var bufferEndOffset uint32
567 var downloadSection []byte
568 framePrint := false //default no printing of downloadSection frames
mpagenkoaa3afe92021-05-21 16:20:58 +0000569 oFsm.mutexUpgradeParams.Lock()
mpagenko80622a52021-02-09 16:53:23 +0000570 if oFsm.nextDownloadSectionsAbsolute == 0 {
571 //debug print of first section frame
572 framePrint = true
mpagenkoaa3afe92021-05-21 16:20:58 +0000573 oFsm.volthaImageState = voltha.ImageState_IMAGE_DOWNLOADING
mpagenko80622a52021-02-09 16:53:23 +0000574 }
575
576 for {
577 bufferStartOffset = oFsm.nextDownloadSectionsAbsolute * cOmciDownloadSectionSize
578 bufferEndOffset = bufferStartOffset + cOmciDownloadSectionSize - 1 //for representing cOmciDownloadSectionSizeLimit values
579 logger.Debugw(ctx, "DlSection values are", log.Fields{
580 "DlSectionNoAbsolute": oFsm.nextDownloadSectionsAbsolute,
581 "DlSectionWindow": oFsm.nextDownloadSectionsWindow,
582 "startOffset": bufferStartOffset, "endOffset": bufferEndOffset})
583 if bufferStartOffset+1 > oFsm.imageLength || bufferEndOffset+1 > oFsm.imageLength { //should never occur in this state
584 logger.Errorw(ctx, "OnuUpgradeFsm buffer error: exceeded length", log.Fields{
585 "device-id": oFsm.deviceID, "bufferStartOffset": bufferStartOffset,
586 "bufferEndOffset": bufferEndOffset, "imageLength": oFsm.imageLength})
mpagenkoaa3afe92021-05-21 16:20:58 +0000587 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000588 //logical error -- reset the FSM
589 pBaseFsm := oFsm.pAdaptFsm
590 // Can't call FSM Event directly, decoupling it
591 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000592 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000593 }(pBaseFsm)
594 return
595 }
596 downloadSection = oFsm.imageBuffer[bufferStartOffset : bufferEndOffset+1]
597 if oFsm.nextDownloadSectionsWindow == oFsm.omciDownloadWindowSizeLimit {
598 windowAckRequest = 1
599 logger.Debugw(ctx, "DlSection expect Response for complete window", log.Fields{
600 "device-id": oFsm.deviceID, "in window": oFsm.nextDownloadWindow})
601 }
602 if oFsm.nextDownloadSectionsAbsolute+1 >= oFsm.noOfSections {
603 windowAckRequest = 1
604 framePrint = true //debug print of last frame
mpagenko15ff4a52021-03-02 10:09:20 +0000605 oFsm.omciDownloadWindowSizeLast = oFsm.nextDownloadSectionsWindow
606 logger.Infow(ctx, "DlSection expect Response for last window (section)", log.Fields{
mpagenko80622a52021-02-09 16:53:23 +0000607 "device-id": oFsm.deviceID, "DlSectionNoAbsolute": oFsm.nextDownloadSectionsAbsolute})
608 }
mpagenkoaa3afe92021-05-21 16:20:58 +0000609 oFsm.mutexUpgradeParams.Unlock() //unlock here to give other functions some chance to process during/after the send request
Girish Gowdra0b235842021-03-09 13:06:46 -0800610 err := oFsm.pOmciCC.sendDownloadSection(log.WithSpanFromContext(context.TODO(), ctx), oFsm.pDeviceHandler.pOpenOnuAc.omciTimeout, false,
mpagenko80622a52021-02-09 16:53:23 +0000611 oFsm.pAdaptFsm.commChan, oFsm.inactiveImageMeID, windowAckRequest, oFsm.nextDownloadSectionsWindow, downloadSection, framePrint)
612 if err != nil {
613 logger.Errorw(ctx, "DlSection abort: can't send section", log.Fields{
mpagenko15ff4a52021-03-02 10:09:20 +0000614 "device-id": oFsm.deviceID, "section absolute": oFsm.nextDownloadSectionsAbsolute, "error": err})
mpagenko80622a52021-02-09 16:53:23 +0000615 //TODO!!!: define some more sophisticated error treatment with some repetition, for now just reset the FSM
616 pBaseFsm := oFsm.pAdaptFsm
617 // Can't call FSM Event directly, decoupling it
618 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000619 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000620 }(pBaseFsm)
621 return
622 }
mpagenkoaa3afe92021-05-21 16:20:58 +0000623 oFsm.mutexUpgradeParams.Lock()
mpagenko80622a52021-02-09 16:53:23 +0000624 oFsm.nextDownloadSectionsAbsolute++ //always increase the absolute section counter after having sent one
625 if windowAckRequest == 1 {
626 pBaseFsm := oFsm.pAdaptFsm
627 // Can't call FSM Event directly, decoupling it
mpagenkoaa3afe92021-05-21 16:20:58 +0000628 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000629 go func(a_pAFsm *AdapterFsm) {
630 _ = a_pAFsm.pFsm.Event(upgradeEvWaitWindowAck) //state transition to upgradeStVerifyWindow
631 }(pBaseFsm)
632 return
633 }
634 framePrint = false //for the next Section frame (if wanted, can be enabled in logic before sendXXX())
635 oFsm.nextDownloadSectionsWindow++ //increase the window related section counter only if not in the last section
mpagenko59498c12021-03-18 14:15:15 +0000636 if oFsm.omciSectionInterleaveDelay > 0 {
mpagenko80622a52021-02-09 16:53:23 +0000637 //ensure a defined intersection-time-gap to leave space for further processing, other ONU's ...
mpagenkoaa3afe92021-05-21 16:20:58 +0000638 oFsm.mutexUpgradeParams.Unlock() //unlock here to give other functions some chance to process during/after the send request
mpagenko59498c12021-03-18 14:15:15 +0000639 time.Sleep(oFsm.omciSectionInterleaveDelay * time.Millisecond)
mpagenkoaa3afe92021-05-21 16:20:58 +0000640 oFsm.mutexUpgradeParams.Lock()
mpagenko80622a52021-02-09 16:53:23 +0000641 }
642 }
643}
644
645func (oFsm *OnuUpgradeFsm) enterVerifyWindow(ctx context.Context, e *fsm.Event) {
646 logger.Debugw(ctx, "OnuUpgradeFsm verify DL window ack", log.Fields{
647 "for window": oFsm.nextDownloadWindow, "device-id": oFsm.deviceID})
648}
649
650func (oFsm *OnuUpgradeFsm) enterFinalizeDL(ctx context.Context, e *fsm.Event) {
mpagenko80622a52021-02-09 16:53:23 +0000651 logger.Infow(ctx, "OnuUpgradeFsm finalize DL", log.Fields{
mpagenko59498c12021-03-18 14:15:15 +0000652 "device-id": oFsm.deviceID, "crc": strconv.FormatInt(int64(oFsm.imageCRC), 16), "delay": oFsm.delayEndSwDl})
mpagenko80622a52021-02-09 16:53:23 +0000653
mpagenkoaa3afe92021-05-21 16:20:58 +0000654 oFsm.mutexUpgradeParams.RLock()
mpagenko80622a52021-02-09 16:53:23 +0000655 if oFsm.delayEndSwDl {
mpagenkoaa3afe92021-05-21 16:20:58 +0000656 oFsm.mutexUpgradeParams.RUnlock()
mpagenko80622a52021-02-09 16:53:23 +0000657 //give the ONU some time for image evaluation (hoping it does not base that on first EndSwDl itself)
658 // should not be set in case this state is used for real download abort (not yet implemented)
659 time.Sleep(cOmciEndSwDlDelaySeconds * time.Second)
mpagenkoaa3afe92021-05-21 16:20:58 +0000660 } else {
661 oFsm.mutexUpgradeParams.RUnlock()
mpagenko80622a52021-02-09 16:53:23 +0000662 }
663
mpagenko59498c12021-03-18 14:15:15 +0000664 pBaseFsm := oFsm.pAdaptFsm
665 if pBaseFsm == nil {
666 logger.Errorw(ctx, "EndSwDl abort: BaseFsm invalid", log.Fields{
667 "device-id": oFsm.deviceID})
668 //TODO!!!: define some more sophisticated error treatment with some repetition, for now just reset the FSM
669 // Can't call FSM Event directly, decoupling it
670 go func(a_pAFsm *AdapterFsm) {
671 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
672 }(pBaseFsm)
673 return
674 }
Girish Gowdra0b235842021-03-09 13:06:46 -0800675 err := oFsm.pOmciCC.sendEndSoftwareDownload(log.WithSpanFromContext(context.TODO(), ctx), oFsm.pDeviceHandler.pOpenOnuAc.omciTimeout, false,
mpagenko59498c12021-03-18 14:15:15 +0000676 oFsm.pAdaptFsm.commChan, oFsm.inactiveImageMeID, oFsm.origImageLength, oFsm.imageCRC)
mpagenko80622a52021-02-09 16:53:23 +0000677 if err != nil {
678 logger.Errorw(ctx, "EndSwDl abort: can't send section", log.Fields{
679 "device-id": oFsm.deviceID, "error": err})
680 //TODO!!!: define some more sophisticated error treatment with some repetition, for now just reset the FSM
mpagenko80622a52021-02-09 16:53:23 +0000681 // Can't call FSM Event directly, decoupling it
682 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000683 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000684 }(pBaseFsm)
685 return
686 }
mpagenko59498c12021-03-18 14:15:15 +0000687 // go waiting for the EndSwDLResponse and check, if the ONU is ready for activation
688 // Can't call FSM Event directly, decoupling it
689 go func(a_pAFsm *AdapterFsm) {
690 _ = a_pAFsm.pFsm.Event(upgradeEvWaitEndDownload)
691 }(pBaseFsm)
692}
693
694func (oFsm *OnuUpgradeFsm) enterWaitEndDL(ctx context.Context, e *fsm.Event) {
695 logger.Infow(ctx, "OnuUpgradeFsm WaitEndDl", log.Fields{
696 "device-id": oFsm.deviceID, "wait delay": oFsm.waitDelayEndSwDl * time.Second, "wait count": oFsm.waitCountEndSwDl})
697 if oFsm.waitCountEndSwDl == 0 {
698 logger.Errorw(ctx, "WaitEndDl abort: max limit of EndSwDL reached", log.Fields{
699 "device-id": oFsm.deviceID})
700 pBaseFsm := oFsm.pAdaptFsm
701 if pBaseFsm == nil {
702 logger.Errorw(ctx, "WaitEndDl abort: BaseFsm invalid", log.Fields{
703 "device-id": oFsm.deviceID})
704 return
705 }
706 go func(a_pAFsm *AdapterFsm) {
707 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
708 }(pBaseFsm)
709 return
710 }
711
712 oFsm.waitCountEndSwDl--
713 select {
714 case <-time.After(oFsm.waitDelayEndSwDl * time.Second):
715 pBaseFsm := oFsm.pAdaptFsm
716 if pBaseFsm == nil {
717 logger.Errorw(ctx, "WaitEndDl abort: BaseFsm invalid", log.Fields{
718 "device-id": oFsm.deviceID})
719 //FSM may be reset already from somewhere else, nothing we can do here anymore
720 return
721 }
722 //retry End SW DL
mpagenkoaa3afe92021-05-21 16:20:58 +0000723 oFsm.mutexUpgradeParams.Lock()
mpagenko59498c12021-03-18 14:15:15 +0000724 oFsm.delayEndSwDl = false //no more extra delay for the request
mpagenkoaa3afe92021-05-21 16:20:58 +0000725 oFsm.mutexUpgradeParams.Unlock()
mpagenko59498c12021-03-18 14:15:15 +0000726 go func(a_pAFsm *AdapterFsm) {
727 _ = a_pAFsm.pFsm.Event(upgradeEvContinueFinalize)
728 }(pBaseFsm)
729 return
730 case success := <-oFsm.chReceiveExpectedResponse:
731 logger.Debugw(ctx, "WaitEndDl stop wait timer", log.Fields{"device-id": oFsm.deviceID})
732 pBaseFsm := oFsm.pAdaptFsm
733 if pBaseFsm == nil {
734 logger.Errorw(ctx, "WaitEndDl abort: BaseFsm invalid", log.Fields{
735 "device-id": oFsm.deviceID})
736 //FSM may be reset already from somewhere else, nothing we can do here anymore
737 return
738 }
739 if success {
740 //answer received with ready indication
mpagenkoc26d4c02021-05-06 14:27:57 +0000741 if oFsm.activateImage {
742 //immediate activation requested
743 go func(a_pAFsm *AdapterFsm) {
744 _ = a_pAFsm.pFsm.Event(upgradeEvRequestActivate)
745 }(pBaseFsm)
746 } else {
747 //have to wait on explicit activation request
748 go func(a_pAFsm *AdapterFsm) {
749 _ = a_pAFsm.pFsm.Event(upgradeEvWaitForActivate)
750 }(pBaseFsm)
751 }
mpagenko59498c12021-03-18 14:15:15 +0000752 return
753 }
754 //timer was aborted
755 go func(a_pAFsm *AdapterFsm) {
756 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
757 }(pBaseFsm)
758 return
759 }
mpagenko80622a52021-02-09 16:53:23 +0000760}
761
762func (oFsm *OnuUpgradeFsm) enterActivateSw(ctx context.Context, e *fsm.Event) {
763 logger.Infow(ctx, "OnuUpgradeFsm activate SW", log.Fields{
764 "device-id": oFsm.deviceID, "me-id": oFsm.inactiveImageMeID})
765
mpagenkoaa3afe92021-05-21 16:20:58 +0000766 oFsm.mutexUpgradeParams.Lock()
767 oFsm.volthaImageState = voltha.ImageState_IMAGE_ACTIVATING
768 oFsm.mutexUpgradeParams.Unlock()
769
Girish Gowdra0b235842021-03-09 13:06:46 -0800770 err := oFsm.pOmciCC.sendActivateSoftware(log.WithSpanFromContext(context.TODO(), ctx), oFsm.pDeviceHandler.pOpenOnuAc.omciTimeout, false,
mpagenko80622a52021-02-09 16:53:23 +0000771 oFsm.pAdaptFsm.commChan, oFsm.inactiveImageMeID)
772 if err != nil {
773 logger.Errorw(ctx, "ActivateSw abort: can't send activate frame", log.Fields{
774 "device-id": oFsm.deviceID, "error": err})
775 //TODO!!!: define some more sophisticated error treatment with some repetition, for now just reset the FSM
776 pBaseFsm := oFsm.pAdaptFsm
777 // Can't call FSM Event directly, decoupling it
778 go func(a_pAFsm *AdapterFsm) {
mpagenko15ff4a52021-03-02 10:09:20 +0000779 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000780 }(pBaseFsm)
781 return
782 }
783}
784
785func (oFsm *OnuUpgradeFsm) enterCommitSw(ctx context.Context, e *fsm.Event) {
mpagenko15ff4a52021-03-02 10:09:20 +0000786 if activeImageID, err := oFsm.pOnuOmciDevice.GetActiveImageMeID(ctx); err == nil {
787 //TODO!!: as long as testing with BBSIM and BBSIM not support upgrade tests following check needs to be deactivated
788 imageFit := true //TODO!!: test workaround as long as BBSIM does not fully support upgrade
mpagenkoaa3afe92021-05-21 16:20:58 +0000789 oFsm.mutexUpgradeParams.Lock()
mpagenko15ff4a52021-03-02 10:09:20 +0000790 if imageFit || activeImageID == oFsm.inactiveImageMeID {
mpagenkoaa3afe92021-05-21 16:20:58 +0000791 inactiveImageID := oFsm.inactiveImageMeID
mpagenko15ff4a52021-03-02 10:09:20 +0000792 logger.Infow(ctx, "OnuUpgradeFsm commit SW", log.Fields{
mpagenkoaa3afe92021-05-21 16:20:58 +0000793 "device-id": oFsm.deviceID, "me-id": inactiveImageID}) //more efficient activeImageID with above check
794 oFsm.volthaImageState = voltha.ImageState_IMAGE_COMMITTING
795 oFsm.mutexUpgradeParams.Unlock()
Girish Gowdra0b235842021-03-09 13:06:46 -0800796 err := oFsm.pOmciCC.sendCommitSoftware(log.WithSpanFromContext(context.TODO(), ctx), oFsm.pDeviceHandler.pOpenOnuAc.omciTimeout, false,
mpagenkoaa3afe92021-05-21 16:20:58 +0000797 oFsm.pAdaptFsm.commChan, inactiveImageID) //more efficient activeImageID with above check
mpagenko15ff4a52021-03-02 10:09:20 +0000798 if err != nil {
799 logger.Errorw(ctx, "CommitSw abort: can't send commit sw frame", log.Fields{
800 "device-id": oFsm.deviceID, "error": err})
801 //TODO!!!: define some more sophisticated error treatment with some repetition, for now just reset the FSM
802 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
803 pBaseFsm := oFsm.pAdaptFsm
804 // Can't call FSM Event directly, decoupling it
805 go func(a_pAFsm *AdapterFsm) {
806 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
807 }(pBaseFsm)
808 return
809 }
810 return
811 }
812 logger.Errorw(ctx, "OnuUpgradeFsm active ImageId <> IdToCommit", log.Fields{
813 "device-id": oFsm.deviceID, "active ID": activeImageID, "to commit ID": oFsm.inactiveImageMeID})
mpagenkoaa3afe92021-05-21 16:20:58 +0000814 oFsm.mutexUpgradeParams.Unlock()
815
mpagenko15ff4a52021-03-02 10:09:20 +0000816 //TODO!!!: possibly send event information for aborted upgrade (not activated)??
817 pBaseFsm := oFsm.pAdaptFsm
818 // Can't call FSM Event directly, decoupling it
819 go func(a_pAFsm *AdapterFsm) {
820 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
821 }(pBaseFsm)
822 return
823 }
824 logger.Errorw(ctx, "OnuUpgradeFsm can't commit, no valid active image", log.Fields{
825 "device-id": oFsm.deviceID})
826 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
827 pBaseFsm := oFsm.pAdaptFsm
828 // Can't call FSM Event directly, decoupling it
829 go func(a_pAFsm *AdapterFsm) {
830 _ = a_pAFsm.pFsm.Event(upgradeEvAbort)
831 }(pBaseFsm)
832}
833
834func (oFsm *OnuUpgradeFsm) enterCheckCommitted(ctx context.Context, e *fsm.Event) {
835 logger.Infow(ctx, "OnuUpgradeFsm checking committed SW", log.Fields{
mpagenko80622a52021-02-09 16:53:23 +0000836 "device-id": oFsm.deviceID, "me-id": oFsm.inactiveImageMeID})
mpagenko15ff4a52021-03-02 10:09:20 +0000837 requestedAttributes := me.AttributeValueMap{"IsCommitted": 0, "IsActive": 0, "Version": ""}
ozgecanetsiab36ed572021-04-01 10:38:48 +0300838 meInstance, err := oFsm.pOmciCC.sendGetMe(log.WithSpanFromContext(context.TODO(), ctx),
Girish Gowdra0b235842021-03-09 13:06:46 -0800839 me.SoftwareImageClassID, oFsm.inactiveImageMeID, requestedAttributes, oFsm.pDeviceHandler.pOpenOnuAc.omciTimeout, false, oFsm.pAdaptFsm.commChan)
mpagenko15ff4a52021-03-02 10:09:20 +0000840 //accept also nil as (error) return value for writing to LastTx
841 // - this avoids misinterpretation of new received OMCI messages
ozgecanetsiab36ed572021-04-01 10:38:48 +0300842 if err != nil {
843 logger.Errorw(ctx, "OnuUpgradeFsm get Software Image ME result error",
844 log.Fields{"device-id": oFsm.deviceID, "Error": err})
845 pOnuUpgradeFsm := oFsm.pAdaptFsm
846 if pOnuUpgradeFsm != nil {
847 go func(a_pAFsm *AdapterFsm) {
848 _ = a_pAFsm.pFsm.Event(upgradeEvReset)
849 }(pOnuUpgradeFsm)
850 }
851 return
852 }
mpagenko15ff4a52021-03-02 10:09:20 +0000853 oFsm.pLastTxMeInstance = meInstance
mpagenko80622a52021-02-09 16:53:23 +0000854}
855
856func (oFsm *OnuUpgradeFsm) enterResetting(ctx context.Context, e *fsm.Event) {
857 logger.Debugw(ctx, "OnuUpgradeFsm resetting", log.Fields{"device-id": oFsm.deviceID})
858
mpagenkoc26d4c02021-05-06 14:27:57 +0000859 // in case the download-to-ONU timer is still running - cancel it
860 oFsm.mutexIsAwaitingOnuDlResponse.RLock()
861 if oFsm.isWaitingForOnuDlResponse {
862 oFsm.mutexIsAwaitingOnuDlResponse.RUnlock()
863 //use channel to indicate that the download response waiting shall be aborted for this device (channel)
864 oFsm.chOnuDlReady <- false
865 } else {
866 oFsm.mutexIsAwaitingOnuDlResponse.RUnlock()
867 }
868
mpagenko80622a52021-02-09 16:53:23 +0000869 pConfigupgradeStateAFsm := oFsm.pAdaptFsm
870 if pConfigupgradeStateAFsm != nil {
871 // abort running message processing
872 fsmAbortMsg := Message{
873 Type: TestMsg,
874 Data: TestMessage{
875 TestMessageVal: AbortMessageProcessing,
876 },
877 }
878 pConfigupgradeStateAFsm.commChan <- fsmAbortMsg
879
880 //try to restart the FSM to 'disabled'
881 // Can't call FSM Event directly, decoupling it
882 go func(a_pAFsm *AdapterFsm) {
883 if a_pAFsm != nil && a_pAFsm.pFsm != nil {
884 _ = a_pAFsm.pFsm.Event(upgradeEvRestart)
885 }
886 }(pConfigupgradeStateAFsm)
887 }
888}
889
890func (oFsm *OnuUpgradeFsm) enterDisabled(ctx context.Context, e *fsm.Event) {
891 logger.Debugw(ctx, "OnuUpgradeFsm enters disabled state", log.Fields{"device-id": oFsm.deviceID})
mpagenkoc26d4c02021-05-06 14:27:57 +0000892 // no need to flush possible channels here, Upgrade FSM will be completely removed, garbage collector should find its way
mpagenko80622a52021-02-09 16:53:23 +0000893 if oFsm.pDeviceHandler != nil {
894 //request removal of 'reference' in the Handler (completely clear the FSM and its data)
895 go oFsm.pDeviceHandler.removeOnuUpgradeFsm(ctx)
896 }
897}
898
899func (oFsm *OnuUpgradeFsm) processOmciUpgradeMessages(ctx context.Context) { //ctx context.Context?
900 logger.Debugw(ctx, "Start OnuUpgradeFsm Msg processing", log.Fields{"for device-id": oFsm.deviceID})
901loop:
902 for {
903 // case <-ctx.Done():
904 // logger.Info(ctx,"MibSync Msg", log.Fields{"Message handling canceled via context for device-id": oFsm.deviceID})
905 // break loop
906 message, ok := <-oFsm.pAdaptFsm.commChan
907 if !ok {
908 logger.Info(ctx, "OnuUpgradeFsm Rx Msg - could not read from channel", log.Fields{"device-id": oFsm.deviceID})
909 // but then we have to ensure a restart of the FSM as well - as exceptional procedure
mpagenko15ff4a52021-03-02 10:09:20 +0000910 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000911 break loop
912 }
913 logger.Debugw(ctx, "OnuUpgradeFsm Rx Msg", log.Fields{"device-id": oFsm.deviceID})
914
915 switch message.Type {
916 case TestMsg:
917 msg, _ := message.Data.(TestMessage)
918 if msg.TestMessageVal == AbortMessageProcessing {
919 logger.Infow(ctx, "OnuUpgradeFsm abort ProcessMsg", log.Fields{"for device-id": oFsm.deviceID})
920 break loop
921 }
922 logger.Warnw(ctx, "OnuUpgradeFsm unknown TestMessage", log.Fields{"device-id": oFsm.deviceID, "MessageVal": msg.TestMessageVal})
923 case OMCI:
924 msg, _ := message.Data.(OmciMessage)
925 oFsm.handleOmciOnuUpgradeMessage(ctx, msg)
926 default:
927 logger.Warn(ctx, "OnuUpgradeFsm Rx unknown message", log.Fields{"device-id": oFsm.deviceID,
928 "message.Type": message.Type})
929 }
930 }
931 logger.Infow(ctx, "End OnuUpgradeFsm Msg processing", log.Fields{"device-id": oFsm.deviceID})
932}
933
934//nolint: gocyclo
935func (oFsm *OnuUpgradeFsm) handleOmciOnuUpgradeMessage(ctx context.Context, msg OmciMessage) {
936 logger.Debugw(ctx, "Rx OMCI OnuUpgradeFsm Msg", log.Fields{"device-id": oFsm.deviceID,
937 "msgType": msg.OmciMsg.MessageType})
938
939 switch msg.OmciMsg.MessageType {
940 case omci.StartSoftwareDownloadResponseType:
941 {
942 msgLayer := (*msg.OmciPacket).Layer(omci.LayerTypeStartSoftwareDownloadResponse)
943 if msgLayer == nil {
944 logger.Errorw(ctx, "Omci Msg layer could not be detected for StartSwDlResponse",
945 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +0000946 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
947 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000948 return
949 }
950 msgObj, msgOk := msgLayer.(*omci.StartSoftwareDownloadResponse)
951 if !msgOk {
952 logger.Errorw(ctx, "Omci Msg layer could not be assigned for StartSwDlResponse",
953 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +0000954 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
955 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000956 return
957 }
958 logger.Debugw(ctx, "OnuUpgradeFsm StartSwDlResponse data", log.Fields{
959 "device-id": oFsm.deviceID, "data-fields": msgObj})
960 if msgObj.Result != me.Success {
961 logger.Errorw(ctx, "OnuUpgradeFsm StartSwDlResponse result error - later: drive FSM to abort state ?",
962 log.Fields{"device-id": oFsm.deviceID, "Error": msgObj.Result})
963 // possibly force FSM into abort or ignore some errors for some messages? store error for mgmt display?
mpagenko15ff4a52021-03-02 10:09:20 +0000964 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
965 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000966 return
967 }
mpagenkoaa3afe92021-05-21 16:20:58 +0000968
969 oFsm.mutexUpgradeParams.Lock()
mpagenko80622a52021-02-09 16:53:23 +0000970 if msgObj.EntityInstance == oFsm.inactiveImageMeID {
971 logger.Debugw(ctx, "Expected StartSwDlResponse received", log.Fields{"device-id": oFsm.deviceID})
972 if msgObj.WindowSize != oFsm.omciDownloadWindowSizeLimit {
973 // also response WindowSize = 0 is a valid number for used Window size 1
974 logger.Debugw(ctx, "different StartSwDlResponse window size requested by ONU", log.Fields{
975 "acceptedOnuWindowSizeLimit": msgObj.WindowSize, "device-id": oFsm.deviceID})
976 oFsm.omciDownloadWindowSizeLimit = msgObj.WindowSize
977 }
978 oFsm.noOfWindows = oFsm.noOfSections / uint32(oFsm.omciDownloadWindowSizeLimit+1)
979 if oFsm.noOfSections%uint32(oFsm.omciDownloadWindowSizeLimit+1) > 0 {
980 oFsm.noOfWindows++
981 }
982 logger.Debugw(ctx, "OnuUpgradeFsm will use", log.Fields{
983 "windows": oFsm.noOfWindows, "sections": oFsm.noOfSections,
984 "at WindowSizeLimit": oFsm.omciDownloadWindowSizeLimit})
985 oFsm.nextDownloadSectionsAbsolute = 0
986 oFsm.nextDownloadSectionsWindow = 0
987 oFsm.nextDownloadWindow = 0
988
mpagenkoaa3afe92021-05-21 16:20:58 +0000989 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000990 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvRxStartSwDownload)
991 return
992 }
mpagenkoaa3afe92021-05-21 16:20:58 +0000993 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +0000994 logger.Errorw(ctx, "OnuUpgradeFsm StartSwDlResponse wrong ME instance: try again (later)?",
995 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance})
996 // TODO!!!: possibly repeat the start request (once)?
mpagenko15ff4a52021-03-02 10:09:20 +0000997 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
998 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +0000999 return
1000 } //StartSoftwareDownloadResponseType
1001 case omci.DownloadSectionResponseType:
1002 {
mpagenko80622a52021-02-09 16:53:23 +00001003 msgLayer := (*msg.OmciPacket).Layer(omci.LayerTypeDownloadSectionResponse)
1004 if msgLayer == nil {
1005 logger.Errorw(ctx, "Omci Msg layer could not be detected for DlSectionResponse",
1006 log.Fields{"device-id": oFsm.deviceID, "omci-message": msg.OmciMsg})
mpagenko15ff4a52021-03-02 10:09:20 +00001007 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1008 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001009 return
1010 }
1011 msgObj, msgOk := msgLayer.(*omci.DownloadSectionResponse)
1012 if !msgOk {
1013 logger.Errorw(ctx, "Omci Msg layer could not be assigned for DlSectionResponse",
1014 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +00001015 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1016 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001017 return
1018 }
1019 logger.Debugw(ctx, "OnuUpgradeFsm DlSectionResponse Data", log.Fields{
1020 "device-id": oFsm.deviceID, "data-fields": msgObj})
1021 if msgObj.Result != me.Success {
1022 logger.Errorw(ctx, "OnuUpgradeFsm DlSectionResponse result error - later: repeat window once?", //TODO!!!
1023 log.Fields{"device-id": oFsm.deviceID, "Error": msgObj.Result})
mpagenko15ff4a52021-03-02 10:09:20 +00001024 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1025 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001026 return
1027 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001028 oFsm.mutexUpgradeParams.Lock()
mpagenko80622a52021-02-09 16:53:23 +00001029 if msgObj.EntityInstance == oFsm.inactiveImageMeID {
1030 sectionNumber := msgObj.SectionNumber
mpagenko15ff4a52021-03-02 10:09:20 +00001031 logger.Infow(ctx, "DlSectionResponse received", log.Fields{
1032 "window section-number": sectionNumber, "window": oFsm.nextDownloadWindow, "device-id": oFsm.deviceID})
mpagenko80622a52021-02-09 16:53:23 +00001033
mpagenko15ff4a52021-03-02 10:09:20 +00001034 oFsm.nextDownloadWindow++
1035 if oFsm.nextDownloadWindow >= oFsm.noOfWindows {
1036 if sectionNumber != oFsm.omciDownloadWindowSizeLast {
mpagenko02cf1b22021-03-12 17:30:30 +00001037 logger.Errorw(ctx, "OnuUpgradeFsm DlSectionResponse section error last window - later: repeat window once?", //TODO!!!
mpagenko15ff4a52021-03-02 10:09:20 +00001038 log.Fields{"device-id": oFsm.deviceID, "actual section": sectionNumber,
1039 "expected section": oFsm.omciDownloadWindowSizeLast})
1040 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
mpagenkoaa3afe92021-05-21 16:20:58 +00001041 oFsm.mutexUpgradeParams.Unlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001042 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1043 return
1044 }
mpagenkoc26d4c02021-05-06 14:27:57 +00001045 oFsm.delayEndSwDl = true //ensure a delay for the EndSwDl message
1046 //CRC computation for all data bytes of the file
1047 imageCRC := crc32a.Checksum(oFsm.imageBuffer[:int(oFsm.origImageLength)]) //store internal for multiple usage
1048 //revert the retrieved CRC Byte Order (seems not to deliver NetworkByteOrder)
1049 var byteSlice []byte = make([]byte, 4)
1050 binary.LittleEndian.PutUint32(byteSlice, uint32(imageCRC))
1051 oFsm.imageCRC = binary.BigEndian.Uint32(byteSlice)
mpagenkoaa3afe92021-05-21 16:20:58 +00001052 oFsm.mutexUpgradeParams.Unlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001053 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvEndSwDownload)
1054 return
1055 }
1056 if sectionNumber != oFsm.omciDownloadWindowSizeLimit {
1057 logger.Errorw(ctx, "OnuUpgradeFsm DlSectionResponse section error - later: repeat window once?", //TODO!!!
mpagenko02cf1b22021-03-12 17:30:30 +00001058 log.Fields{"device-id": oFsm.deviceID, "actual-section": sectionNumber,
1059 "expected section": oFsm.omciDownloadWindowSizeLimit})
mpagenkoaa3afe92021-05-21 16:20:58 +00001060 oFsm.mutexUpgradeParams.Unlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001061 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1062 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1063 return
1064 }
1065 oFsm.nextDownloadSectionsWindow = 0
mpagenkoaa3afe92021-05-21 16:20:58 +00001066 oFsm.mutexUpgradeParams.Unlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001067 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvContinueNextWindow)
mpagenko80622a52021-02-09 16:53:23 +00001068 return
1069 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001070 oFsm.mutexUpgradeParams.Unlock()
mpagenko80622a52021-02-09 16:53:23 +00001071 logger.Errorw(ctx, "OnuUpgradeFsm Omci StartSwDlResponse wrong ME instance: try again (later)?",
1072 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance})
mpagenko15ff4a52021-03-02 10:09:20 +00001073 // TODO!!!: possibly repeat the download (section) (once)?
1074 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1075 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001076 return
mpagenko80622a52021-02-09 16:53:23 +00001077 } //DownloadSectionResponseType
1078 case omci.EndSoftwareDownloadResponseType:
1079 {
1080 msgLayer := (*msg.OmciPacket).Layer(omci.LayerTypeEndSoftwareDownloadResponse)
1081 if msgLayer == nil {
1082 logger.Errorw(ctx, "Omci Msg layer could not be detected for EndSwDlResponse",
1083 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +00001084 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1085 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001086 return
1087 }
1088 msgObj, msgOk := msgLayer.(*omci.EndSoftwareDownloadResponse)
1089 if !msgOk {
1090 logger.Errorw(ctx, "Omci Msg layer could not be assigned for EndSwDlResponse",
1091 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +00001092 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1093 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001094 return
1095 }
1096 logger.Debugw(ctx, "OnuUpgradeFsm EndSwDlResponse data", log.Fields{
1097 "device-id": oFsm.deviceID, "data-fields": msgObj})
1098 if msgObj.Result != me.Success {
mpagenko59498c12021-03-18 14:15:15 +00001099 if msgObj.Result == me.DeviceBusy {
1100 //ONU indicates it is still processing the image - let the FSM just wait and then repeat the request
1101 logger.Debugw(ctx, "OnuUpgradeFsm EndSwDlResponse busy: waiting before sending new request", log.Fields{
1102 "device-id": oFsm.deviceID})
1103 return
1104 }
mpagenko80622a52021-02-09 16:53:23 +00001105 logger.Errorw(ctx, "OnuUpgradeFsm EndSwDlResponse result error - later: drive FSM to abort state ?",
1106 log.Fields{"device-id": oFsm.deviceID, "Error": msgObj.Result})
1107 // possibly force FSM into abort or ignore some errors for some messages? store error for mgmt display?
mpagenko15ff4a52021-03-02 10:09:20 +00001108 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1109 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001110 return
1111 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001112 oFsm.mutexUpgradeParams.RLock()
mpagenko80622a52021-02-09 16:53:23 +00001113 if msgObj.EntityInstance == oFsm.inactiveImageMeID {
mpagenkoaa3afe92021-05-21 16:20:58 +00001114 oFsm.mutexUpgradeParams.RUnlock()
mpagenko80622a52021-02-09 16:53:23 +00001115 logger.Debugw(ctx, "Expected EndSwDlResponse received", log.Fields{"device-id": oFsm.deviceID})
mpagenkoc26d4c02021-05-06 14:27:57 +00001116 oFsm.mutexIsAwaitingOnuDlResponse.RLock()
1117 if oFsm.isWaitingForOnuDlResponse {
1118 oFsm.mutexIsAwaitingOnuDlResponse.RUnlock()
1119 //use channel to indicate that the download to ONU was successful
1120 oFsm.chOnuDlReady <- true
1121 } else {
1122 oFsm.mutexIsAwaitingOnuDlResponse.RUnlock()
1123 }
mpagenko59498c12021-03-18 14:15:15 +00001124 oFsm.chReceiveExpectedResponse <- true //let the FSM proceed from the waitState
mpagenko80622a52021-02-09 16:53:23 +00001125 return
1126 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001127 oFsm.mutexUpgradeParams.RUnlock()
mpagenko80622a52021-02-09 16:53:23 +00001128 logger.Errorw(ctx, "OnuUpgradeFsm StartSwDlResponse wrong ME instance: try again (later)?",
1129 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance})
1130 // TODO!!!: possibly repeat the end request (once)? or verify ONU upgrade state?
mpagenko15ff4a52021-03-02 10:09:20 +00001131 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1132 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001133 return
1134 } //EndSoftwareDownloadResponseType
1135 case omci.ActivateSoftwareResponseType:
1136 {
1137 msgLayer := (*msg.OmciPacket).Layer(omci.LayerTypeActivateSoftwareResponse)
1138 if msgLayer == nil {
1139 logger.Errorw(ctx, "Omci Msg layer could not be detected for ActivateSw",
1140 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +00001141 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1142 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001143 return
1144 }
1145 msgObj, msgOk := msgLayer.(*omci.ActivateSoftwareResponse)
1146 if !msgOk {
1147 logger.Errorw(ctx, "Omci Msg layer could not be assigned for ActivateSw",
1148 log.Fields{"device-id": oFsm.deviceID})
mpagenko15ff4a52021-03-02 10:09:20 +00001149 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1150 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001151 return
1152 }
1153 logger.Debugw(ctx, "OnuUpgradeFsm ActivateSwResponse data", log.Fields{
1154 "device-id": oFsm.deviceID, "data-fields": msgObj})
1155 if msgObj.Result != me.Success {
1156 logger.Errorw(ctx, "OnuUpgradeFsm ActivateSwResponse result error - later: drive FSM to abort state ?",
1157 log.Fields{"device-id": oFsm.deviceID, "Error": msgObj.Result})
1158 // TODO!!!: error treatment?, perhaps in the end reset the FSM
mpagenko15ff4a52021-03-02 10:09:20 +00001159 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1160 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001161 return
1162 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001163 oFsm.mutexUpgradeParams.RLock()
mpagenko80622a52021-02-09 16:53:23 +00001164 if msgObj.EntityInstance == oFsm.inactiveImageMeID {
mpagenkoaa3afe92021-05-21 16:20:58 +00001165 oFsm.mutexUpgradeParams.RUnlock()
mpagenko02cf1b22021-03-12 17:30:30 +00001166 logger.Infow(ctx, "Expected ActivateSwResponse received", log.Fields{"device-id": oFsm.deviceID})
mpagenko80622a52021-02-09 16:53:23 +00001167 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvWaitForCommit)
1168 return
1169 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001170 oFsm.mutexUpgradeParams.RUnlock()
mpagenko80622a52021-02-09 16:53:23 +00001171 logger.Errorw(ctx, "OnuUpgradeFsm ActivateSwResponse wrong ME instance: abort",
1172 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance})
mpagenko15ff4a52021-03-02 10:09:20 +00001173 // TODO!!!: error treatment?
1174 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1175 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
mpagenko80622a52021-02-09 16:53:23 +00001176 return
1177 } //ActivateSoftwareResponseType
mpagenko15ff4a52021-03-02 10:09:20 +00001178 case omci.CommitSoftwareResponseType:
1179 {
1180 msgLayer := (*msg.OmciPacket).Layer(omci.LayerTypeCommitSoftwareResponse)
1181 if msgLayer == nil {
1182 logger.Errorw(ctx, "Omci Msg layer could not be detected for CommitResponse",
1183 log.Fields{"device-id": oFsm.deviceID})
1184 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1185 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1186 return
1187 }
1188 msgObj, msgOk := msgLayer.(*omci.CommitSoftwareResponse)
1189 if !msgOk {
1190 logger.Errorw(ctx, "Omci Msg layer could not be assigned for CommitResponse",
1191 log.Fields{"device-id": oFsm.deviceID})
1192 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1193 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1194 return
1195 }
mpagenkobf67a092021-03-17 09:52:28 +00001196 if msgObj.Result != me.Success {
mpagenko15ff4a52021-03-02 10:09:20 +00001197 logger.Errorw(ctx, "OnuUpgradeFsm SwImage CommitResponse result error - later: drive FSM to abort state ?",
1198 log.Fields{"device-id": oFsm.deviceID, "Error": msgObj.Result})
1199 // TODO!!!: error treatment?, perhaps in the end reset the FSM
1200 return
mpagenkobf67a092021-03-17 09:52:28 +00001201 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001202 oFsm.mutexUpgradeParams.RLock()
mpagenko15ff4a52021-03-02 10:09:20 +00001203 if msgObj.EntityInstance == oFsm.inactiveImageMeID {
mpagenkoaa3afe92021-05-21 16:20:58 +00001204 oFsm.mutexUpgradeParams.RUnlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001205 logger.Debugw(ctx, "OnuUpgradeFsm Expected SwImage CommitResponse received", log.Fields{"device-id": oFsm.deviceID})
1206 //verifying committed image
1207 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvCheckCommitted)
1208 return
1209 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001210 oFsm.mutexUpgradeParams.RUnlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001211 logger.Errorw(ctx, "OnuUpgradeFsm SwImage CommitResponse wrong ME instance: abort",
1212 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance})
1213 // TODO!!!: error treatment?
1214 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1215 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1216 return
1217 } //CommitSoftwareResponseType
1218 case omci.GetResponseType:
1219 {
1220 msgLayer := (*msg.OmciPacket).Layer(omci.LayerTypeGetResponse)
1221 if msgLayer == nil {
1222 logger.Errorw(ctx, "Omci Msg layer could not be detected for SwImage GetResponse",
1223 log.Fields{"device-id": oFsm.deviceID})
1224 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1225 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1226 return
1227 }
1228 msgObj, msgOk := msgLayer.(*omci.GetResponse)
1229 if !msgOk {
1230 logger.Errorw(ctx, "Omci Msg layer could not be assigned for SwImage GetResponse",
1231 log.Fields{"device-id": oFsm.deviceID})
1232 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1233 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1234 return
1235 }
1236 logger.Debugw(ctx, "OnuUpgradeFsm SwImage GetResponse data", log.Fields{
1237 "device-id": oFsm.deviceID, "data-fields": msgObj})
1238 if msgObj.EntityClass == oFsm.pLastTxMeInstance.GetClassID() &&
1239 msgObj.EntityInstance == oFsm.pLastTxMeInstance.GetEntityID() {
1240 if msgObj.Result != me.Success {
1241 logger.Errorw(ctx, "OnuUpgradeFsm SwImage GetResponse result error - later: drive FSM to abort state ?",
1242 log.Fields{"device-id": oFsm.deviceID, "Error": msgObj.Result})
1243 // TODO!!!: error treatment?, perhaps in the end reset the FSM
1244 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1245 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1246 return
1247 }
1248 } else {
1249 logger.Warnw(ctx, "OnuUpgradeFsm SwImage unexpected Entity GetResponse data - ignore",
1250 log.Fields{"device-id": oFsm.deviceID})
1251 return
1252 }
1253
1254 meAttributes := msgObj.Attributes
1255 imageIsCommitted := meAttributes["IsCommitted"].(uint8)
1256 imageIsActive := meAttributes["IsActive"].(uint8)
Holger Hildebrandtfb402a62021-05-26 14:40:49 +00001257 imageVersion := TrimStringFromMeOctet(meAttributes["Version"])
mpagenko02cf1b22021-03-12 17:30:30 +00001258 logger.Debugw(ctx, "OnuUpgradeFsm - GetResponse Data for SoftwareImage",
mpagenko15ff4a52021-03-02 10:09:20 +00001259 log.Fields{"device-id": oFsm.deviceID, "entityID": msgObj.EntityInstance,
1260 "version": imageVersion, "isActive": imageIsActive, "isCommitted": imageIsCommitted})
mpagenkoaa3afe92021-05-21 16:20:58 +00001261
1262 oFsm.mutexUpgradeParams.Lock()
1263 if msgObj.EntityInstance == oFsm.inactiveImageMeID && imageIsActive == swIsActive {
mpagenkoc26d4c02021-05-06 14:27:57 +00001264 //a check on the delivered image version is not done, the ONU delivered version might be different from what might have been
1265 // indicated in the download image version string (version must be part of the image content itself)
1266 // so checking that might be quite unreliable
1267 //but with new API this was changed, assumption is that omci image version is known at download request and exactly that is used
1268 // in all the API references, so it can and should be checked here now
1269 if oFsm.useAPIVersion43 {
1270 if imageVersion != oFsm.imageVersion {
1271 //new active version indicated on OMCI from ONU is not the expected version
1272 logger.Errorw(ctx, "OnuUpgradeFsm SwImage GetResponse indications not matching requested upgrade",
1273 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance,
1274 "onu-version": imageVersion, "expected-version": oFsm.imageVersion})
mpagenkoaa3afe92021-05-21 16:20:58 +00001275 oFsm.mutexUpgradeParams.Unlock()
mpagenkoc26d4c02021-05-06 14:27:57 +00001276 // TODO!!!: error treatment?
1277 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1278 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1279 return
1280 }
1281 logger.Debugw(ctx, "OnuUpgradeFsm - expected ONU image version indicated by the ONU",
1282 log.Fields{"device-id": oFsm.deviceID})
1283 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001284 oFsm.volthaImageState = voltha.ImageState_IMAGE_ACTIVE
1285 if imageIsCommitted == swIsCommitted {
1286 oFsm.volthaDownloadState = voltha.ImageState_DOWNLOAD_SUCCEEDED
1287 oFsm.volthaImageState = voltha.ImageState_IMAGE_COMMITTED
1288 logger.Infow(ctx, "requested SW image committed, releasing OnuUpgrade", log.Fields{"device-id": oFsm.deviceID})
1289 oFsm.pDeviceHandler.deviceProcStatusUpdate(ctx, OnuDeviceEvent(oFsm.requestEvent)) //to let the handler now about success
1290 oFsm.mutexUpgradeParams.Unlock()
1291 //releasing the upgrade FSM
1292 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvReset)
1293 return
1294 }
1295 oFsm.mutexUpgradeParams.Unlock()
1296 return //if the imageId is active but not committed let upgrade persist, maybe ONU reboot or manual commit may resolve the situation
mpagenko15ff4a52021-03-02 10:09:20 +00001297 }
mpagenkoaa3afe92021-05-21 16:20:58 +00001298 oFsm.mutexUpgradeParams.Unlock()
mpagenko15ff4a52021-03-02 10:09:20 +00001299 logger.Errorw(ctx, "OnuUpgradeFsm SwImage GetResponse indications not matching requested upgrade",
1300 log.Fields{"device-id": oFsm.deviceID, "ResponseMeId": msgObj.EntityInstance})
1301 // TODO!!!: error treatment?
1302 //TODO!!!: possibly send event information for aborted upgrade (aborted by omci processing)??
1303 _ = oFsm.pAdaptFsm.pFsm.Event(upgradeEvAbort)
1304 return
1305 } //GetResponseType
mpagenko80622a52021-02-09 16:53:23 +00001306 default:
1307 {
1308 logger.Errorw(ctx, "Rx OMCI unhandled MsgType",
1309 log.Fields{"omciMsgType": msg.OmciMsg.MessageType, "device-id": oFsm.deviceID})
1310 return
1311 }
1312 }
1313}
1314
mpagenkoc26d4c02021-05-06 14:27:57 +00001315//waitOnDownloadToAdapterReady state can only be reached with useAPIVersion43 (usage of pFileManager)
1316func (oFsm *OnuUpgradeFsm) waitOnDownloadToAdapterReady(ctx context.Context, aWaitChannel chan bool) {
1317 downloadToAdapterTimeout := oFsm.pFileManager.GetDownloadTimeout(ctx)
1318 oFsm.mutexIsAwaitingAdapterDlResponse.Lock()
1319 oFsm.isWaitingForAdapterDlResponse = true
1320 oFsm.mutexIsAwaitingAdapterDlResponse.Unlock()
mpagenko80622a52021-02-09 16:53:23 +00001321 select {
1322 // maybe be also some outside cancel (but no context modeled for the moment ...)
1323 // case <-ctx.Done():
mpagenkoc26d4c02021-05-06 14:27:57 +00001324 // logger.Infow("OnuUpgradeFsm-waitOnDownloadToAdapterReady canceled", log.Fields{"for device-id": oFsm.deviceID})
1325 case <-time.After(downloadToAdapterTimeout): //10s should be enough for downloading some image to the adapter
1326 logger.Warnw(ctx, "OnuUpgradeFsm Waiting-adapter-download timeout", log.Fields{
1327 "for device-id": oFsm.deviceID, "image-id": oFsm.imageIdentifier, "timeout": downloadToAdapterTimeout})
1328 oFsm.pFileManager.RemoveReadyRequest(ctx, oFsm.imageIdentifier, aWaitChannel)
1329 oFsm.mutexIsAwaitingAdapterDlResponse.Lock()
1330 oFsm.isWaitingForAdapterDlResponse = false
1331 oFsm.mutexIsAwaitingAdapterDlResponse.Unlock()
1332 //the upgrade process has to be aborted
1333 pUpgradeFsm := oFsm.pAdaptFsm
1334 if pUpgradeFsm != nil {
1335 _ = pUpgradeFsm.pFsm.Event(upgradeEvReset)
1336 } else {
1337 logger.Errorw(ctx, "pUpgradeFsm is nil", log.Fields{"device-id": oFsm.deviceID})
mpagenko80622a52021-02-09 16:53:23 +00001338 }
mpagenkoc26d4c02021-05-06 14:27:57 +00001339 return
1340
1341 case success := <-aWaitChannel:
1342 if success {
1343 logger.Debugw(ctx, "OnuUpgradeFsm image-downloaded received", log.Fields{"device-id": oFsm.deviceID})
1344 oFsm.mutexIsAwaitingAdapterDlResponse.Lock()
1345 oFsm.isWaitingForAdapterDlResponse = false
1346 oFsm.mutexIsAwaitingAdapterDlResponse.Unlock()
1347 //let the upgrade process proceed
1348 pUpgradeFsm := oFsm.pAdaptFsm
1349 if pUpgradeFsm != nil {
1350 _ = pUpgradeFsm.pFsm.Event(upgradeEvPrepareSwDownload)
1351 } else {
1352 logger.Errorw(ctx, "pUpgradeFsm is nil", log.Fields{"device-id": oFsm.deviceID})
1353 }
1354 return
1355 }
1356 // waiting was aborted (probably on external request)
1357 logger.Debugw(ctx, "OnuUpgradeFsm Waiting-adapter-download aborted", log.Fields{"device-id": oFsm.deviceID})
1358 oFsm.pFileManager.RemoveReadyRequest(ctx, oFsm.imageIdentifier, aWaitChannel)
1359 oFsm.mutexIsAwaitingAdapterDlResponse.Lock()
1360 oFsm.isWaitingForAdapterDlResponse = false
1361 oFsm.mutexIsAwaitingAdapterDlResponse.Unlock()
1362 //the upgrade process has to be aborted
1363 pUpgradeFsm := oFsm.pAdaptFsm
1364 if pUpgradeFsm != nil {
1365 _ = pUpgradeFsm.pFsm.Event(upgradeEvAbort)
1366 } else {
1367 logger.Errorw(ctx, "pUpgradeFsm is nil", log.Fields{"device-id": oFsm.deviceID})
1368 }
1369 return
mpagenko80622a52021-02-09 16:53:23 +00001370 }
1371}
mpagenkoc26d4c02021-05-06 14:27:57 +00001372
1373//waitOnDownloadToOnuReady state can only be reached with useAPIVersion43 (usage of pFileManager)
1374func (oFsm *OnuUpgradeFsm) waitOnDownloadToOnuReady(ctx context.Context, aWaitChannel chan bool) {
1375 downloadToOnuTimeout := time.Duration(1+(oFsm.imageLength/0x400000)) * oFsm.downloadToOnuTimeout4MB
1376 logger.Debugw(ctx, "OnuUpgradeFsm start download-to-ONU timer", log.Fields{"device-id": oFsm.deviceID,
1377 "duration": downloadToOnuTimeout})
1378 oFsm.mutexIsAwaitingOnuDlResponse.Lock()
1379 oFsm.isWaitingForOnuDlResponse = true
1380 oFsm.mutexIsAwaitingOnuDlResponse.Unlock()
1381 select {
1382 // maybe be also some outside cancel (but no context modeled for the moment ...)
1383 // case <-ctx.Done():
1384 // logger.Infow("OnuUpgradeFsm-waitOnDownloadToOnuReady canceled", log.Fields{"for device-id": oFsm.deviceID})
1385 case <-time.After(downloadToOnuTimeout): //using an image-size depending timout (in minutes)
1386 logger.Warnw(ctx, "OnuUpgradeFsm Waiting-ONU-download timeout", log.Fields{
1387 "for device-id": oFsm.deviceID, "image-id": oFsm.imageIdentifier, "timeout": downloadToOnuTimeout})
1388 oFsm.mutexIsAwaitingOnuDlResponse.Lock()
1389 oFsm.isWaitingForOnuDlResponse = false
1390 oFsm.mutexIsAwaitingOnuDlResponse.Unlock()
1391 //the upgrade process has to be aborted
1392 pUpgradeFsm := oFsm.pAdaptFsm
1393 if pUpgradeFsm != nil {
1394 _ = pUpgradeFsm.pFsm.Event(upgradeEvAbort)
1395 } else {
1396 logger.Errorw(ctx, "pUpgradeFsm is nil", log.Fields{"device-id": oFsm.deviceID})
1397 }
1398 return
1399
1400 case success := <-aWaitChannel:
1401 if success {
1402 logger.Debugw(ctx, "OnuUpgradeFsm image-downloaded on ONU received", log.Fields{"device-id": oFsm.deviceID})
1403 oFsm.mutexIsAwaitingOnuDlResponse.Lock()
1404 oFsm.isWaitingForOnuDlResponse = false
1405 oFsm.mutexIsAwaitingOnuDlResponse.Unlock()
1406 //all fine, let the FSM proceed like defined from the sender of this event
1407 return
1408 }
1409 // waiting was aborted (assumed here to be caused by
1410 // error detection or cancel at download after upgrade FSM reset/abort)
1411 logger.Debugw(ctx, "OnuUpgradeFsm Waiting-ONU-download aborted", log.Fields{"device-id": oFsm.deviceID})
1412 oFsm.mutexIsAwaitingOnuDlResponse.Lock()
1413 oFsm.isWaitingForOnuDlResponse = false
1414 oFsm.mutexIsAwaitingOnuDlResponse.Unlock()
1415 return
1416 }
1417}