ec718/ec_20250305_fullsdk/PLAT/testscript/script/commtest_log.txt

[1682317361.247] test time:2023-04-24 14:22:41

[1682317361.253] run test0 -- test Flash LFS

[1682317364.682] 

[1682317364.682] Write(100)

[1682317364.698] Verify

[1682317364.714] End

[1682317371.258] 

[1682317371.258] Write(200)

[1682317371.29] Verify

[1682317371.306] End

[1682317377.866] 

[1682317377.866] Write(300)

[1682317377.898] Verify

[1682317377.914] End

[1682317385.034] 

[1682317385.034] Write(400)

[1682317385.05] Verify

[1682317385.05] End

[1682317391.561] 

[1682317391.561] Write(500)

[1682317391.577] Verify

[1682317391.593] End

[1682317391.593] Flash LFS stop.

[1682317395.17] test time:2023-04-24 14:23:15

[1682317395.185] run test0 -- test Flash LFS

[1682317398.176] 

[1682317398.176] Write(100)

[1682317398.207] Verify

[1682317398.223] End

[1682317404.805] 

[1682317404.805] Write(200)

[1682317404.839] Verify

[1682317404.855] End

[1682317411.905] 

[1682317411.905] Write(300)

[1682317411.921] Verify

[1682317411.921] End

[1682317418.454] 

[1682317418.454] Write(400)

[1682317418.47] Verify

[1682317418.485] End

[1682317425.041] 

[1682317425.041] Write(500)

[1682317425.072] Verify

[1682317425.088] End

[1682317425.088] Flash LFS stop.

[1682317428.751] test time:2023-04-24 14:23:48

[1682317428.751] run test0 -- test Flash LFS

[1682317432.227] 

[1682317432.227] Write(100)

[1682317432.243] Verify

[1682317432.243] End

[1682317438.763] 

[1682317438.763] Write(200)

[1682317438.779] Verify

[1682317438.795] End

[1682317445.33] 

[1682317445.33] Write(300)

[1682317445.348] Verify

[1682317445.379] End

[1682317451.93] 

[1682317451.93] Write(400)

[1682317451.963] Verify

[1682317451.979] End

[1682317459.066] 

[1682317459.067] Write(500)

[1682317459.082] Verify

[1682317459.084] End

[1682317459.086] Flash LFS stop.

[1682317462.765] test time:2023-04-24 14:24:22

[1682317462.766] run test0 -- test Flash LFS

[1682317465.637] 

[1682317465.637] Write(100)

[1682317465.666] Verify

[1682317465.685] End

[1682317466.597] ../../../../../../../PLAT/middleware/thirdparty/littlefs/lfs.c:1846:debug: Relocating {0x11f, 0x11e} -> {0x120, 0x11f}
[1682317472.332] 

[1682317472.332] Write(200)

[1682317472.347] Verify

[1682317472.389] End

[1682317478.965] 

[1682317478.965] Write(300)

[1682317478.997] Verify

[1682317479.004] End

[1682317486.036] 

[1682317486.036] Write(400)

[1682317486.052] Verify

[1682317486.067] End

[1682317492.596] 

[1682317492.597] Write(500)

[1682317492.612] Verify

[1682317492.628] End

[1682317492.644] Flash LFS stop.

[1682317496.241] test time:2023-04-24 14:24:56

[1682317496.242] run test0 -- test Flash LFS

[1682317499.431] 

[1682317499.431] Write(100)

[1682317499.465] Verify

[1682317499.497] End

[1682317506.791] 

[1682317506.791] Write(200)

[1682317506.807] Verify

[1682317506.807] End

[1682317513.293] 

[1682317513.293] Write(300)

[1682317513.309] Verify

[1682317513.324] End

[1682317519.862] 

[1682317519.862] Write(400)

[1682317519.884] Verify

[1682317519.899] End

[1682317520.762] Status report: {"speaker":{"cell_id":12823395,"rssi":-69,"timestamp":"1682317518","vbat":3928282}}

[1682317526.477] 

[1682317526.477] Write(500)

[1682317526.494] Verify

[1682317526.525] End

[1682317526.525] Flash LFS stop.

[1682317530.163] test time:2023-04-24 14:25:30

[1682317530.166] run test0 -- test Flash LFS

[1682317533.626] 

[1682317533.626] Write(100)

[1682317533.642] Verify

[1682317533.657] End

[1682317540.19] 

[1682317540.19] Write(200)

[1682317540.206] Verify

[1682317540.222] End

[1682317546.781] 

[1682317546.782] Write(300)

[1682317546.797] Verify

[1682317546.83] End

[1682317553.405] 

[1682317553.405] Write(400)

[1682317553.437] Verify

[1682317553.464] End

[1682317560.493] 

[1682317560.493] Write(500)

[1682317560.509] Verify

[1682317560.509] End

[1682317560.525] Flash LFS stop.

[1682317564.135] test time:2023-04-24 14:26:04

[1682317564.136] run test0 -- test Flash LFS

[1682317567.119] 

[1682317567.119] Write(100)

[1682317567.135] Verify

[1682317567.151] End

[1682317573.71] 

[1682317573.71] Write(200)

[1682317573.742] Verify

[1682317573.759] End

[1682317580.846] 

[1682317580.847] Write(300)

[1682317580.849] Verify

[1682317580.862] End

[1682317587.358] 

[1682317587.358] Write(400)

[1682317587.374] Verify

[1682317587.39] End

[1682317593.95] 

[1682317593.95] Write(500)

[1682317593.966] Verify

[1682317593.998] End

[1682317593.998] Flash LFS stop.

[1682317597.609] test time:2023-04-24 14:26:37

[1682317597.615] run test0 -- test Flash LFS

[1682317600.626] 

[1682317600.643] Write(100)

[1682317600.648] Verify

[1682317600.691] End

[1682317607.715] 

[1682317607.716] Write(200)

[1682317607.731] Verify

[1682317607.732] End

[1682317614.243] 

[1682317614.243] Write(300)

[1682317614.275] Verify

[1682317614.291] End

[1682317620.831] 

[1682317620.831] Write(400)

[1682317620.85] Verify

[1682317620.866] End

[1682317627.448] 

[1682317627.448] Write(500)

[1682317627.49] Verify

[1682317627.506] End

[1682317627.514] Flash LFS stop.

[1682317631.13] test time:2023-04-24 14:27:11

[1682317631.13] run test0 -- test Flash LFS

[1682317634.565] 

[1682317634.565] Write(100)

[1682317634.58] Verify

[1682317634.597] End

[1682317641.14] 

[1682317641.157] Write(200)

[1682317641.167] Verify

[1682317641.188] End

[1682317647.748] 

[1682317647.749] Write(300)

[1682317647.764] Verify

[1682317647.796] End

[1682317654.897] 

[1682317654.897] Write(400)

[1682317654.912] Verify

[1682317654.912] End

[1682317661.424] 

[1682317661.431] Write(500)

[1682317661.446] Verify

[1682317661.446] End

[1682317661.446] Flash LFS stop.

[1682317665.056] test time:2023-04-24 14:27:45

[1682317665.072] run test0 -- test Flash LFS

[1682317668.395] 

[1682317668.395] Write(100)

[1682317668.416] Verify

[1682317668.431] End

[1682317675.218] 

[1682317675.218] Write(200)

[1682317675.258] Verify

[1682317675.264] End

[1682317682.33] 

[1682317682.33] Write(300)

[1682317682.346] Verify

[1682317682.346] End

[1682317683.386] ../../../../../../../PLAT/middleware/thirdparty/littlefs/lfs.c:1846:debug: Relocating {0x120, 0x11f} -> {0x121, 0x120}
[1682317689.625] 

[1682317689.641] Write(400)

[1682317689.657] Verify

[1682317689.673] End

[1682317696.217] 

[1682317696.217] Write(500)

[1682317696.249] Verify

[1682317696.265] End

[1682317696.265] Flash LFS stop.

[1682317699.878] test time:2023-04-24 14:28:19

[1682317699.881] run test0 -- test Flash LFS

[1682317703.404] 

[1682317703.404] Write(100)

[1682317703.42] Verify

[1682317703.435] End

[1682317709.947] 

[1682317709.948] Write(200)

[1682317709.963] Verify

[1682317709.979] End

[1682317716.523] 

[1682317716.523] Write(300)

[1682317716.555] Verify

[1682317716.571] End

[1682317723.146] 

[1682317723.146] Write(400)

[1682317723.163] Verify

[1682317723.195] End

[1682317730.283] 

[1682317730.283] Write(500)

[1682317730.299] Verify

[1682317730.299] End

[1682317730.299] Flash LFS stop.

[1682317733.907] test time:2023-04-24 14:28:53

[1682317733.908] run test0 -- test Flash LFS

[1682317736.858] 

[1682317736.858] Write(100)

[1682317736.874] Verify

[1682317736.89] End

[1682317743.434] 

[1682317743.434] Write(200)

[1682317743.466] Verify

[1682317743.482] End

[1682317750.074] 

[1682317750.074] Write(300)

[1682317750.106] Verify

[1682317750.122] End

[1682317757.161] 

[1682317757.161] Write(400)

[1682317757.178] Verify

[1682317757.178] End

[1682317763.705] 

[1682317763.721] Write(500)

[1682317763.737] Verify

[1682317763.753] End

[1682317763.754] Flash LFS stop.

[1682317767.347] test time:2023-04-24 14:29:27

[1682317767.349] run test0 -- test Flash LFS

[1682317770.378] 

[1682317770.378] Write(100)

[1682317770.41] Verify

[1682317770.425] End

[1682317777.546] 

[1682317777.546] Write(200)

[1682317777.548] Verify

[1682317777.562] End

[1682317784.057] 

[1682317784.057] Write(300)

[1682317784.073] Verify

[1682317784.089] End

[1682317790.633] 

[1682317790.633] Write(400)

[1682317790.649] Verify

[1682317790.665] End

[1682317797.353] 

[1682317797.353] Write(500)

[1682317797.384] Verify

[1682317797.401] End

[1682317797.417] Flash LFS stop.

[1682317801.059] test time:2023-04-24 14:30:01

[1682317801.059] run test0 -- test Flash LFS

[1682317804.746] 

[1682317804.746] Write(100)

[1682317804.762] Verify

[1682317804.763] End

[1682317811.322] 

[1682317811.324] Write(200)

[1682317811.338] Verify

[1682317811.354] End

[1682317817.913] 

[1682317817.914] Write(300)

[1682317817.946] Verify

[1682317817.961] End

[1682317820.89] Status report: {"speaker":{"cell_id":12823395,"rssi":-68,"timestamp":"1682317819","vbat":3932592}}

[1682317824.553] 

[1682317824.553] Write(400)

[1682317824.585] Verify

[1682317824.602] End

[1682317831.625] 

[1682317831.641] Write(500)

[1682317831.641] Verify

[1682317831.657] End

[1682317831.657] Flash LFS stop.

[1682318500.526] test time:2023-04-24 14:41:40
[1682318500.527] run test0 -- test Flash LFS
[1682318560.938] Receive timeout.
[1682318564.684] test time:2023-04-24 14:42:44
[1682318564.685] run test0 -- test Flash LFS
[1682318567.619] 
[1682318567.619] Write(100)
[1682318567.651] Verify
[1682318567.683] End
[1682318574.707] 
[1682318574.709] Write(200)
[1682318574.722] Verify
[1682318574.724] End
[1682318581.251] 
[1682318581.267] Write(300)
[1682318581.283] Verify
[1682318581.298] End
[1682318587.842] 
[1682318587.843] Write(400)
[1682318587.858] Verify
[1682318587.89] End
[1682318594.466] 
[1682318594.466] Write(500)
[1682318594.482] Verify
[1682318594.514] End
[1682318594.514] Flash LFS stop.
[1682318598.142] test time:2023-04-24 14:43:18
[1682318598.143] run test0 -- test Flash LFS
[1682318601.672] 
[1682318601.672] Write(100)
[1682318601.688] Verify
[1682318601.704] End
[1682318608.247] 
[1682318608.248] Write(200)
[1682318608.279] Verify
[1682318608.295] End
[1682318614.855] 
[1682318614.855] Write(300)
[1682318614.871] Verify
[1682318614.903] End
[1682318621.991] 
[1682318621.991] Write(400)
[1682318621.992] Verify
[1682318622.007] End
[1682318628.519] 
[1682318628.519] Write(500)
[1682318628.534] Verify
[1682318628.551] End
[1682318628.551] Flash LFS stop.
[1682318632.176] test time:2023-04-24 14:43:52
[1682318632.177] run test0 -- test Flash LFS
[1682318635.159] 
[1682318635.159] Write(100)
[1682318635.175] Verify
[1682318635.207] End
[1682318641.799] 
[1682318641.799] Write(200)
[1682318641.831] Verify
[1682318641.863] End
[1682318648.887] 
[1682318648.887] Write(300)
[1682318648.903] Verify
[1682318648.903] End
[1682318655.638] 
[1682318655.638] Write(400)
[1682318655.654] Verify
[1682318655.67] End
[1682318662.374] 
[1682318662.374] Write(500)
[1682318662.406] Verify
[1682318662.422] End
[1682318662.422] Flash LFS stop.
[1682318666.081] test time:2023-04-24 14:44:26
[1682318666.082] run test0 -- test Flash LFS
[1682318669.575] 
[1682318669.575] Write(100)
[1682318669.59] Verify
[1682318669.59] End
[1682318676.102] 
[1682318676.118] Write(200)
[1682318676.134] Verify
[1682318676.134] End
[1682318682.678] 
[1682318682.678] Write(300)
[1682318682.694] Verify
[1682318682.71] End
[1682318689.286] 
[1682318689.286] Write(400)
[1682318689.317] Verify
[1682318689.334] End
[1682318696.438] 
[1682318696.439] Write(500)
[1682318696.453] Verify
[1682318696.453] End
[1682318696.453] Flash LFS stop.
[1682318700.058] test time:2023-04-24 14:45:00
[1682318700.059] run test0 -- test Flash LFS
[1682318703.008] 
[1682318703.008] Write(100)
[1682318703.04] Verify
[1682318703.057] End
[1682318709.6] 
[1682318709.601] Write(200)
[1682318709.616] Verify
[1682318709.632] End
[1682318716.208] 
[1682318716.208] Write(300)
[1682318716.24] Verify
[1682318716.272] End
[1682318721.152] Status report: {"speaker":{"cell_id":12823395,"rssi":-73,"timestamp":"1682318719","vbat":3934741}}
[1682318723.295] 
[1682318723.295] Write(400)
[1682318723.311] Verify
[1682318723.327] End
[1682318729.871] 
[1682318729.872] Write(500)
[1682318729.887] Verify
[1682318729.903] End
[1682318729.919] Flash LFS stop.
[1682318802.982] test time:2023-04-24 14:46:42
[1682318802.985] run test0 -- test Flash LFS
[1682318863.401] Receive timeout.
[1682318867.127] test time:2023-04-24 14:47:47
[1682318867.13] run test0 -- test Flash LFS
[1682318870.543] 
[1682318870.559] Write(100)
[1682318870.559] Verify
[1682318870.575] End
[1682318877.134] 
[1682318877.137] Write(200)
[1682318877.151] Verify
[1682318877.166] End
[1682318883.854] 
[1682318883.854] Write(300)
[1682318883.886] Verify
[1682318883.902] End
[1682318890.494] 
[1682318890.494] Write(400)
[1682318890.526] Verify
[1682318890.559] End
[1682318897.582] 
[1682318897.582] Write(500)
[1682318897.583] Verify
[1682318897.598] End
[1682318897.598] Flash LFS stop.
[1682318901.219] test time:2023-04-24 14:48:21
[1682318901.22] run test0 -- test Flash LFS
[1682318904.171] 
[1682318904.171] Write(100)
[1682318904.187] Verify
[1682318904.202] End
[1682318910.778] 
[1682318910.778] Write(200)
[1682318910.81] Verify
[1682318910.826] End
[1682318912.57] ../../../../../../../PLAT/middleware/thirdparty/littlefs/lfs.c:1846:debug: Relocating {0x122, 0x121} -> {0x123, 0x122}
[1682318918.026] 
[1682318918.026] Write(300)
[1682318918.042] Verify
[1682318918.042] End
[1682318924.554] 
[1682318924.554] Write(400)
[1682318924.569] Verify
[1682318924.585] End
[1682318931.129] 
[1682318931.129] Write(500)
[1682318931.145] Verify
[1682318931.161] End
[1682318931.163] Flash LFS stop.
[1682318934.755] Test time: 2023-04-24 14:48:54
[1682318934.756] run test0 -- test Flash LFS
[1682318937.819] 
[1682318937.821] Write(100)
[1682318937.851] Verify
[1682318937.867] End
[1682318944.907] 
[1682318944.907] Write(200)
[1682318944.923] Verify
[1682318944.939] End
[1682318951.467] 
[1682318951.467] Write(300)
[1682318951.482] Verify
[1682318951.499] End
[1682318958.074] 
[1682318958.074] Write(400)
[1682318958.09] Verify
[1682318958.123] End
[1682318964.697] 
[1682318964.698] Write(500)
[1682318964.73] Verify
[1682318964.761] End
[1682318964.762] Flash LFS stop.
[1682318968.444] Test time: 2023-04-24 14:49:28
[1682318968.446] Run test0 -- Flash LFS
[1682318971.829] 
[1682318971.829] Write(100)
[1682318971.844] Verify
[1682318971.86] End
[1682318978.388] 
[1682318978.388] Write(200)
[1682318978.404] Verify
[1682318978.42] End
[1682318984.996] 
[1682318984.996] Write(300)
[1682318985.012] Verify
[1682318985.043] End
[1682318992.132] 
[1682318992.132] Write(400)
[1682318992.134] Verify
[1682318992.148] End
[1682318998.675] 
[1682318998.676] Write(500)
[1682318998.691] Verify
[1682318998.707] End
[1682318998.708] Flash LFS stop.
[1682319002.36] Test time: 2023-04-24 14:50:02
[1682319002.367] Run test0 -- External Flash LFS
[1682319005.299] 
[1682319005.301] Write(100)
[1682319005.331] Verify
[1682319005.347] End
[1682319011.939] 
[1682319011.939] Write(200)
[1682319011.955] Verify
[1682319011.987] End
[1682319019.01] 
[1682319019.01] Write(300)
[1682319019.026] Verify
[1682319019.028] End
[1682319021.155] Status report: {"speaker":{"cell_id":12823395,"rssi":-71,"timestamp":"1682319019","vbat":3932592}}
[1682319025.57] 
[1682319025.57] Write(400)
[1682319025.586] Verify
[1682319025.602] End
[1682319032.178] 
[1682319032.179] Write(500)
[1682319032.194] Verify
[1682319032.21] End
[1682319032.21] Flash LFS stop.
[1682319471.448] Test time: 2023-04-24 14:57:51
[1682319471.448] Run test0 -- External Flash LFS
[1682319630.097] Test time: 2023-04-24 15:00:30
[1682319630.098] Run test0 -- External Flash LFS
[1682319792.738] Test time: 2023-04-24 15:03:12
[1682319792.739] Run test0 -- External Flash LFS
[1682320222.783] Test time: 2023-04-24 15:10:22
[1682320222.784] Run test0 -- External Flash LFS
[1682320283.159] Receive timeout.
[1682320433.221] Test time: 2023-04-24 15:13:53
[1682320433.226] Run test0 -- External Flash LFS
[1682320437.503] 
[1682320437.503] Write(2300)
[1682320437.535] Verify
[1682320437.551] End
[1682320444.59] 
[1682320444.59] Write(2400)
[1682320444.606] Verify
[1682320444.606] End
[1682320451.15] 
[1682320451.15] Write(2500)
[1682320451.182] Verify
[1682320451.198] End
[1682320457.758] 
[1682320457.758] Write(2600)
[1682320457.79] Verify
[1682320457.806] End
[1682320464.381] 
[1682320464.397] Write(2700)
[1682320464.413] Verify
[1682320464.446] End
[1682320471.469] 
[1682320471.47] Write(2800)
[1682320471.485] Verify
[1682320471.501] End
[1682320478.157] 
[1682320478.157] Write(2900)
[1682320478.173] Verify
[1682320478.189] End
[1682320484.781] 
[1682320484.781] Write(3000)
[1682320484.796] Verify
[1682320484.812] End
[1682320508.427] Work  mode: 1
[1682320508.428] Debug mode: 0
[1682320508.431] Debug type: 3
[1682320508.435] STEP: 1
[1682320508.443] SIM is ready.
[1682320508.539] Unverified external Flash: C816
[1682320508.923] NW is ready.
[1682320509.371] OneNET MQTT is ready.
[1682320509.467] Status report: {"speaker":{"cell_id":12823395,"rssi":-70,"timestamp":"1682320508","vbat":3934741}}
[1682320569.869] Receive timeout.
[1682321794.808] Test time: 2023-04-24 15:36:34
[1682321794.809] Run test0 -- External Flash LFS
[1682321855.148] Receive timeout.
[1682321867.861] Test time: 2023-04-24 15:37:47
[1682321867.862] Run test0 -- External Flash LFS
[1682321868.386] 
[1682321868.403] Write(200)
[1682321868.418] Verify
[1682321868.435] End
[1682321875.026] 
[1682321875.026] Write(300)
[1682321875.058] Verify
[1682321875.074] End
[1682321882.097] 
[1682321882.097] Write(400)
[1682321882.114] Verify
[1682321882.114] End
[1682321888.626] 
[1682321888.626] Write(500)
[1682321888.657] Verify
[1682321888.658] End
[1682321895.217] 
[1682321895.217] Write(600)
[1682321895.233] Verify
[1682321895.249] End
[1682321901.857] 
[1682321901.858] Write(700)
[1682321901.872] Verify
[1682321901.905] End
[1682321903.553] ../../../../../../../PLAT/middleware/thirdparty/littlefs/lfs.c:1846:debug: Relocating {0x9a, 0x99} -> {0x9b, 0x9a}
[1682321909.008] 
[1682321909.008] Write(800)
[1682321909.025] Verify
[1682321909.04] End
[1682321915.553] 
[1682321915.555] Write(900)
[1682321915.568] Verify
[1682321915.584] End
[1682321922.143] 
[1682321922.144] Write(1000)
[1682321922.16] Verify
[1682321922.176] End
[1682321928.768] 
[1682321928.784] Write(1100)
[1682321928.8] Verify
[1682321928.832] End
[1682321935.855] 
[1682321935.856] Write(1200)
[1682321935.871] Verify
[1682321935.871] End
[1682321942.559] 
[1682321942.559] Write(1300)
[1682321942.575] Verify
[1682321942.591] End
[1682321949.151] 
[1682321949.151] Write(1400)
[1682321949.183] Verify
[1682321949.199] End
[1682321955.79] 
[1682321955.79] Write(1500)
[1682321955.822] Verify
[1682321955.839] End
[1682321980.733] Work  mode: 1
[1682321980.735] Debug mode: 0
[1682321980.737] Debug type: 3
[1682321980.738] STEP: 1
[1682321980.738] SIM is ready.
[1682321980.829] Unverified external Flash: C816
[1682321981.293] NW is ready.
[1682321981.517] OneNET MQTT is ready.
[1682321981.757] Status report: {"speaker":{"cell_id":12823395,"rssi":-72,"timestamp":"1682321981","vbat":3936896}}
[1682322042.126] Receive timeout.
[1682322623.7] Test time: 2023-04-24 15:50:23
[1682322623.701] Run test0 -- External Flash LFS
[1682322623.701] {"name": "systest_flash_lfs", "param1": 50, "param2": 10000, "param3": "0"}
[1682322630.236] 
[1682322630.236] Write(8700)
[1682322630.251] Verify
[1682322630.267] End
[1682322635.435] ../../../../../../../PLAT/middleware/thirdparty/littlefs/lfs.c:1846:debug: Relocating {0x3a, 0x39} -> {0x3b, 0x3a}
[1682322636.891] 
[1682322636.891] Write(8800)
[1682322636.923] Verify
[1682322636.939] End
[1682322643.515] 
[1682322643.53] Write(8900)
[1682322644.058] Verify
[1682322644.074] End
[1682322650.586] 
[1682322650.586] Write(9000)
[1682322650.602] Verify
[1682322650.618] End
[1682322657.146] 
[1682322657.146] Write(9100)
[1682322657.162] Verify
[1682322657.178] End
[1682322677.993] Work  mode: 1
[1682322677.993] Debug mode: 0
[1682322677.995] Debug type: 3
[1682322677.998] STEP: 1
[1682322677.998] SIM is ready.
[1682322678.105] Unverified external Flash: C816
[1682322678.521] NW is ready.
[1682322678.761] OneNET MQTT is ready.
[1682322679.049] Status report: {"speaker":{"cell_id":26236191,"rssi":-85,"timestamp":"1682322678","vbat":3939045}}
[1682322739.39] Receive timeout.
[1682322739.461] Test time: 2023-04-24 15:52:19
[1682322739.461] Run test1 -- External Flash LFS
[1682322739.461] {"name": "systest_flash_lfs", "param1": 1024, "param2": 10000, "param3": "0"}
[1682322752.131] Test time: 2023-04-24 15:52:32
[1682322752.132] Run test0 -- External Flash LFS
[1682322752.132] {"name": "systest_flash_lfs", "param1": 50, "param2": 10000, "param3": "0"}
[1682322754.186] 
[1682322754.186] Write(100)
[1682322754.73] Verify
[1682322754.731] End
[1682322761.226] 
[1682322761.226] Write(200)
[1682322761.242] Verify
[1682322761.258] End
[1682322767.786] 
[1682322767.787] Write(300)
[1682322767.818] Verify
[1682322767.834] End
[1682322774.377] 
[1682322774.378] Write(400)
[1682322774.41] Verify
[1682322774.425] End
[1682322781.002] 
[1682322781.017] Write(500)
[1682322781.546] Verify
[1682322781.562] End
[1682322802.697] Work  mode: 1
[1682322802.697] Debug mode: 0
[1682322802.699] Debug type: 3
[1682322802.699] STEP: 1
[1682322802.712] SIM is ready.
[1682322802.808] Unverified external Flash: C816
[1682322803.448] NW is ready.
[1682322803.72] OneNET MQTT is ready.
[1682322803.736] Status report: {"speaker":{"cell_id":12823395,"rssi":-71,"timestamp":"1682322803","vbat":3943349}}
[1682322864.216] Receive timeout.
[1682322864.291] Test time: 2023-04-24 15:54:24
[1682322864.291] Run test1 -- External Flash LFS
[1682322864.292] {"name": "systest_flash_lfs", "param1": 1024, "param2": 10000, "param3": "0"}
[1682322924.767] Receive timeout.
[1682322924.778] Test time: 2023-04-24 15:55:24
[1682322924.779] Run test2 -- External Flash LFS
[1682322924.779] {"name": "systest_flash_lfs", "param1": 10240, "param2": 10000, "param3": "0"}
[1682322985.214] Receive timeout.
[1682322985.227] Test time: 2023-04-24 15:56:25
[1682322985.228] Run test3 -- External Flash LFS
[1682322985.228] {"name": "systest_flash_lfs", "param1": 51200, "param2": 10000, "param3": "0"}
[1682323039.012] Test time: 2023-04-24 15:57:19
[1682323039.016] Run test0 -- External Flash LFS
[1682323039.016] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323043.902] 
[1682323043.902] Write(100)
[1682323043.918] Verify
[1682323043.918] End
[1682323050.67] 
[1682323159.937] Write(200)
[1682323159.95] Verify
[1682323159.95] End
[1682323159.95] 
[1682323159.95] Write(300)
[1682323159.95] Verify
[1682323159.95] End
[1682323159.95] 
[1682323159.95] Write(400)
[1682323159.95] Verify
[1682323159.951] End
[1682323159.951] 
[1682323159.951] Write(500)
[1682323159.951] Verify
[1682323159.955] End
[1682323159.955] 
[1682323159.955] Write(600)
[1682323159.958] Verify
[1682323159.958] End
[1682323159.96] 
[1682323159.962] Write(700)
[1682323159.967] Verify
[1682323159.967] End
[1682323159.968] 
[1682323159.968] Write(800)
[1682323159.968] Verify
[1682323159.968] End
[1682323159.969] 
[1682323159.969] Write(900)
[1682323159.97] Verify
[1682323159.97] End
[1682323159.97] Status report: {"speaker":{"cell_id":12823395,"rssi":0,"timestamp":"1682323102","vbat":3934741}}
[1682323159.97] 
[1682323159.971] Write(1000)
[1682323159.972] Verify
[1682323159.972] End
[1682323159.972] 
[1682323159.973] Write(1100)
[1682323159.973] Verify
[1682323159.973] End
[1682323159.974] 
[1682323159.974] Write(1200)
[1682323159.974] Verify
[1682323159.974] End
[1682323159.974] 
[1682323159.975] Write(1300)
[1682323159.976] Verify
[1682323159.976] End
[1682323159.976] 
[1682323159.977] Write(1400)
[1682323159.977] Verify
[1682323159.977] End
[1682323159.978] 
[1682323159.982] Write(1500)
[1682323159.983] Verify
[1682323159.983] End
[1682323159.983] Publish failed: res_val = -1
[1682323159.984] 
[1682323159.984] Write(1600)
[1682323159.984] Verify
[1682323159.984] End
[1682323159.984] 
[1682323159.985] Write(1700)
[1682323159.985] Verify
[1682323159.985] End
[1682323159.985] 
[1682323159.986] Write(1800)
[1682323159.986] Verify
[1682323159.986] End
[1682323164.937] 
[1682323164.937] Write(1900)
[1682323164.969] Verify
[1682323164.984] End
[1682323171.528] 
[1682323171.53] Write(2000)
[1682323171.56] Verify
[1682323171.576] End
[1682323178.665] 
[1682323178.667] Write(2100)
[1682323178.671] Verify
[1682323178.68] End
[1682323185.176] 
[1682323185.176] Write(2200)
[1682323185.191] Verify
[1682323185.207] End
[1682323188.234] ^boot.rom
'v'!\n^boot.rom
'v'!\n^boot.rom
'v'!\n[1682323192.731] Test time: 2023-04-24 15:59:52
[1682323192.735] Run test0 -- External Flash LFS
[1682323192.735] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323214.82] Test time: 2023-04-24 16:00:14
[1682323214.886] Run test1 -- External Flash LFS
[1682323214.888] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682323214.915] Test time: 2023-04-24 16:00:14
[1682323214.916] Run test2 -- External Flash LFS
[1682323214.917] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682323214.961] Test time: 2023-04-24 16:00:14
[1682323214.962] Run test3 -- External Flash LFS
[1682323214.962] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682323215.002] Test time: 2023-04-24 16:00:15
[1682323215.003] Run test4 -- External Flash LFS
[1682323215.003] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682323215.102] Test time: 2023-04-24 16:00:15
[1682323215.103] Run test5 -- External Flash LFS
[1682323215.103] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682323215.13] Test time: 2023-04-24 16:00:15
[1682323215.131] Run test6 -- External Flash LFS
[1682323215.131] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323215.18] Test time: 2023-04-24 16:00:15
[1682323215.181] Run test7 -- External Flash LFS
[1682323215.182] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682323215.222] Test time: 2023-04-24 16:00:15
[1682323215.227] Run test8 -- External Flash LFS
[1682323215.227] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682323215.258] Test time: 2023-04-24 16:00:15
[1682323215.259] Run test9 -- External Flash LFS
[1682323215.259] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682323215.286] Test time: 2023-04-24 16:00:15
[1682323215.288] Run test10 -- External Flash LFS
[1682323215.288] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682323215.328] Test time: 2023-04-24 16:00:15
[1682323215.329] Run test11 -- External Flash LFS
[1682323215.331] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682323215.36] Test time: 2023-04-24 16:00:15
[1682323215.36] Run test12 -- External Flash LFS
[1682323215.361] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323215.391] Test time: 2023-04-24 16:00:15
[1682323215.394] Run test13 -- External Flash LFS
[1682323215.394] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682323215.44] Test time: 2023-04-24 16:00:15
[1682323215.441] Run test14 -- External Flash LFS
[1682323215.441] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682323215.482] Test time: 2023-04-24 16:00:15
[1682323215.483] Run test15 -- External Flash LFS
[1682323215.487] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682323215.549] Test time: 2023-04-24 16:00:15
[1682323215.55] Run test16 -- External Flash LFS
[1682323215.551] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682323215.597] Test time: 2023-04-24 16:00:15
[1682323215.598] Run test17 -- External Flash LFS
[1682323215.599] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682323295.584] Test time: 2023-04-24 16:01:35
[1682323295.585] Run test0 -- External Flash LFS
[1682323295.585] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323376.893] Test time: 2023-04-24 16:02:56
[1682323376.893] Run test0 -- External Flash LFS
[1682323376.893] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323427.956] ^boot.rom
'v'!\n^boot.rom
'v'!\n[1682323429.092] Work  mode: 1
[1682323429.108] Debug mode: 0
[1682323429.108] Debug type: 3
[1682323429.108] STEP: 1
[1682323429.108] SIM is ready.
[1682323429.204] Unverified external Flash: C816
[1682323429.764] NW is ready.
[1682323430.133] Status report: {"speaker":{"cell_id":12823395,"rssi":-73,"timestamp":"1682323429","vbat":3913221}}
[1682323430.165] OneNET MQTT is ready.
[1682323453.567] Test time: 2023-04-24 16:04:13
[1682323453.567] Run test0 -- External Flash LFS
[1682323453.567] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323455.96] 
[1682323455.96] Write(100)
[1682323455.976] Verify
[1682323455.992] End
[1682323462.584] 
[1682323462.584] Write(200)
[1682323462.615] Verify
[1682323462.648] End
[1682323469.687] 
[1682323469.688] Write(300)
[1682323469.704] Verify
[1682323469.719] End
[1682323476.215] 
[1682323476.215] Write(400)
[1682323476.231] Verify
[1682323476.247] End
[1682323482.775] 
[1682323482.775] Write(500)
[1682323482.807] Verify
[1682323482.823] End
[1682323482.824] Flash LFS stop.
[1682323482.83] Test time: 2023-04-24 16:04:42
[1682323482.831] Run test1 -- External Flash LFS
[1682323482.831] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682323543.198] Receive timeout.
[1682323543.274] Test time: 2023-04-24 16:05:43
[1682323543.274] Run test2 -- External Flash LFS
[1682323543.274] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682323603.66] Receive timeout.
[1682323603.67] Test time: 2023-04-24 16:06:43
[1682323603.67] Run test3 -- External Flash LFS
[1682323603.671] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682323664.145] Receive timeout.
[1682323664.154] Test time: 2023-04-24 16:07:44
[1682323664.155] Run test4 -- External Flash LFS
[1682323664.155] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682323710.951] Test time: 2023-04-24 16:08:30
[1682323710.952] Run test0 -- External Flash LFS
[1682323710.952] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323713.793] ^boot.rom
'v'!\n[1682323714.912] Work  mode: 1
[1682323714.912] Debug mode: 0
[1682323714.912] Debug type: 3
[1682323714.912] STEP: 1
[1682323714.912] SIM is ready.
[1682323715.009] Unverified external Flash: C816
[1682323715.456] NW is ready.
[1682323715.807] OneNET MQTT is ready.
[1682323715.936] Status report: {"speaker":{"cell_id":12823395,"rssi":-73,"timestamp":"1682323715","vbat":3926133}}
[1682323730.818] Test time: 2023-04-24 16:08:50
[1682323730.819] Run test0 -- External Flash LFS
[1682323730.819] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323736.17] 
[1682323736.17] Write(100)
[1682323736.185] Verify
[1682323736.202] End
[1682323742.73] 
[1682323742.73] Write(200)
[1682323742.746] Verify
[1682323742.762] End
[1682323749.337] 
[1682323749.337] Write(300)
[1682323749.369] Verify
[1682323749.385] End
[1682323756.473] 
[1682323756.473] Write(400)
[1682323756.475] Verify
[1682323756.488] End
[1682323762.985] 
[1682323762.985] Write(500)
[1682323763.001] Verify
[1682323763.017] End
[1682323763.017] Flash LFS stop.
[1682323763.02] Test time: 2023-04-24 16:09:23
[1682323763.02] Run test1 -- External Flash LFS
[1682323763.02] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682323823.428] Receive timeout.
[1682323823.506] Test time: 2023-04-24 16:10:23
[1682323823.506] Run test2 -- External Flash LFS
[1682323823.506] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682323828.22] ^boot.rom
'v'!\n[1682323829.318] Work  mode: 1
[1682323829.334] Debug mode: 0
[1682323829.334] Debug type: 3
[1682323829.334] STEP: 1
[1682323829.334] SIM is ready.
[1682323829.43] Unverified external Flash: C816
[1682323829.942] NW is ready.
[1682323830.293] OneNET MQTT is ready.
[1682323830.358] Status report: {"speaker":{"cell_id":12823395,"rssi":-75,"timestamp":"1682323829","vbat":3923978}}
[1682323858.652] Test time: 2023-04-24 16:10:58
[1682323858.652] Run test0 -- External Flash LFS
[1682323858.652] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682323863.269] 
[1682323863.269] Write(100)
[1682323863.301] Verify
[1682323863.317] End
[1682323869.908] 
[1682323869.909] Write(200)
[1682323869.94] Verify
[1682323869.956] End
[1682323876.981] 
[1682323876.981] Write(300)
[1682323876.996] Verify
[1682323876.996] End
[1682323883.508] 
[1682323883.508] Write(400)
[1682323883.54] Verify
[1682323883.54] End
[1682323890.116] 
[1682323890.116] Write(500)
[1682323890.148] Verify
[1682323890.163] End
[1682323890.164] Flash LFS stop.
[1682323890.167] Test time: 2023-04-24 16:11:30
[1682323890.169] Run test1 -- External Flash LFS
[1682323890.169] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682323928.786] systest_play_tts: 0, 0, ֧<><D6A7><EFBFBD><EFBFBD><EFBFBD>տ<EFBFBD>5609.13Ԫ
[1682323928.802] Play ֧<><D6A7><EFBFBD><EFBFBD><EFBFBD>տ<EFBFBD>5609.13Ԫ
[1682323991.725] Test time: 2023-04-24 16:13:11
[1682323991.726] Run test0 -- External Flash LFS
[1682323991.726] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324009.716] 111
[1682324016.257] 111
111
111
111
[1682324024.028] 111
111
111
111
111
111
111
111
111
111
111
111
111
111
111
111
111
111
111
111
[1682324025.721] 111
111
111
111
[1682324034.414] Test time: 2023-04-24 16:13:54
[1682324034.417] Run test0 -- External Flash LFS
[1682324034.417] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324047.68] 111\r\n
[1682324061.42] 111\r\n
[1682324071.36] 111
111
111
111
111
111
[1682324097.25] 111
[1682324098.514] 111
[1682324099.426] 111
[1682324100.306] 111
[1682324102.418] 111
[1682324105.953] 111
[1682324121.345] Flash LFS stop.
[1682324121.351] Test time: 2023-04-24 16:15:21
[1682324121.352] Run test1 -- External Flash LFS
[1682324121.352] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324182.001] Receive timeout.
[1682324182.074] Test time: 2023-04-24 16:16:22
[1682324182.074] Run test2 -- External Flash LFS
[1682324182.074] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324242.691] Receive timeout.
[1682324242.699] Test time: 2023-04-24 16:17:22
[1682324242.699] Run test3 -- External Flash LFS
[1682324242.699] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324303.355] Receive timeout.
[1682324320.002] Test time: 2023-04-24 16:18:40
[1682324320.003] Run test4 -- External Flash LFS
[1682324320.003] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324326.434] Test time: 2023-04-24 16:18:46
[1682324326.435] Run test0 -- External Flash LFS
[1682324326.435] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324359.863] Flash LFS stop.
[1682324359.869] Test time: 2023-04-24 16:19:19
[1682324359.87] Run test1 -- External Flash LFS
[1682324359.87] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324366.214] Flash LFS stop.
[1682324366.219] Test time: 2023-04-24 16:19:26
[1682324366.221] Run test2 -- External Flash LFS
[1682324366.221] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324375.782] Flash LFS stop.
[1682324375.789] Test time: 2023-04-24 16:19:35
[1682324375.79] Run test3 -- External Flash LFS
[1682324375.79] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324377.734] Flash LFS stop.
[1682324377.74] Test time: 2023-04-24 16:19:37
[1682324377.741] Run test4 -- External Flash LFS
[1682324377.741] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324378.486] Flash LFS stop.
[1682324378.498] Test time: 2023-04-24 16:19:38
[1682324378.499] Run test5 -- External Flash LFS
[1682324378.5] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682324379.19] Flash LFS stop.
[1682324379.198] Test time: 2023-04-24 16:19:39
[1682324379.199] Run test6 -- External Flash LFS
[1682324379.2] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324379.798] Flash LFS stop.
[1682324379.805] Test time: 2023-04-24 16:19:39
[1682324379.806] Run test7 -- External Flash LFS
[1682324379.806] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324380.422] Flash LFS stop.
[1682324380.429] Test time: 2023-04-24 16:19:40
[1682324380.43] Run test8 -- External Flash LFS
[1682324380.43] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324383.03] Flash LFS stop.
[1682324383.035] Test time: 2023-04-24 16:19:43
[1682324383.036] Run test9 -- External Flash LFS
[1682324383.037] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324386.71] Flash LFS stop.
[1682324386.716] Test time: 2023-04-24 16:19:46
[1682324386.716] Run test10 -- External Flash LFS
[1682324386.717] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324387.941] Flash LFS stop.
[1682324387.947] Test time: 2023-04-24 16:19:47
[1682324387.949] Run test11 -- External Flash LFS
[1682324387.949] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682324390.709] Flash LFS stop.
[1682324390.714] Test time: 2023-04-24 16:19:50
[1682324390.715] Run test12 -- External Flash LFS
[1682324390.715] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324392.709] Flash LFS stop.
[1682324392.717] Test time: 2023-04-24 16:19:52
[1682324392.718] Run test13 -- External Flash LFS
[1682324392.718] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324396.901] Flash LFS stop.
[1682324396.907] Test time: 2023-04-24 16:19:56
[1682324396.908] Run test14 -- External Flash LFS
[1682324396.908] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324397.429] Flash LFS stop.
[1682324397.436] Test time: 2023-04-24 16:19:57
[1682324397.438] Run test15 -- External Flash LFS
[1682324397.438] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324397.781] Flash LFS stop.
[1682324397.79] Test time: 2023-04-24 16:19:57
[1682324397.791] Run test16 -- External Flash LFS
[1682324397.791] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324397.989] Flash LFS stop.
[1682324397.996] Test time: 2023-04-24 16:19:57
[1682324397.997] Run test17 -- External Flash LFS
[1682324397.997] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682324398.213] Flash LFS stop.
[1682324612.341] Test time: 2023-04-24 16:23:32
[1682324612.344] Run test0 -- External Flash LFS
[1682324612.344] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324638.213] Test time: 2023-04-24 16:23:58
[1682324638.214] Run test0 -- External Flash LFS
[1682324638.214] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324645.498] Flash LFS stop.
[1682324657.882] Flash LFS stop.
[1682324658.905] Flash LFS stop.
[1682324659.593] Flash LFS stop.
[1682324659.898] Flash LFS stop.
[1682324660.105] Flash LFS stop.
[1682324660.538] Flash LFS stop.
[1682324660.793] Flash LFS stop.
[1682324661.146] Flash LFS stop.
[1682324705.665] Test time: 2023-04-24 16:25:05
[1682324705.666] Run test0 -- External Flash LFS
[1682324705.666] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324709.144] Flash LFS stop.
[1682324732.18] Test time: 2023-04-24 16:25:32
[1682324732.181] Run test0 -- External Flash LFS
[1682324732.181] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324736.518] Flash LFS stop.
[1682324736.651] Test time: 2023-04-24 16:25:36
[1682324736.652] Run test1 -- External Flash LFS
[1682324736.653] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324737.894] Flash LFS stop.
[1682324738.018] Test time: 2023-04-24 16:25:38
[1682324738.019] Run test2 -- External Flash LFS
[1682324738.019] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324738.791] Flash LFS stop.
[1682324738.92] Test time: 2023-04-24 16:25:38
[1682324738.921] Run test3 -- External Flash LFS
[1682324738.922] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324739.91] Flash LFS stop.
[1682324740.035] Test time: 2023-04-24 16:25:40
[1682324740.036] Run test4 -- External Flash LFS
[1682324740.036] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324740.918] Flash LFS stop.
[1682324741.04] Test time: 2023-04-24 16:25:41
[1682324741.041] Run test5 -- External Flash LFS
[1682324741.041] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682324741.862] Flash LFS stop.
[1682324741.992] Test time: 2023-04-24 16:25:41
[1682324741.993] Run test6 -- External Flash LFS
[1682324741.993] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324742.79] Flash LFS stop.
[1682324742.912] Test time: 2023-04-24 16:25:42
[1682324742.913] Run test7 -- External Flash LFS
[1682324742.913] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324743.734] Flash LFS stop.
[1682324743.865] Test time: 2023-04-24 16:25:43
[1682324743.866] Run test8 -- External Flash LFS
[1682324743.866] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324744.582] Flash LFS stop.
[1682324744.706] Test time: 2023-04-24 16:25:44
[1682324744.707] Run test9 -- External Flash LFS
[1682324744.707] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324745.429] Flash LFS stop.
[1682324745.565] Test time: 2023-04-24 16:25:45
[1682324745.566] Run test10 -- External Flash LFS
[1682324745.566] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324746.341] Flash LFS stop.
[1682324746.468] Test time: 2023-04-24 16:25:46
[1682324746.469] Run test11 -- External Flash LFS
[1682324746.469] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682324746.998] Flash LFS stop.
[1682324747.129] Test time: 2023-04-24 16:25:47
[1682324747.129] Run test12 -- External Flash LFS
[1682324747.13] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324747.59] Flash LFS stop.
[1682324747.716] Test time: 2023-04-24 16:25:47
[1682324747.717] Run test13 -- External Flash LFS
[1682324747.718] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324748.134] Flash LFS stop.
[1682324748.268] Test time: 2023-04-24 16:25:48
[1682324748.271] Run test14 -- External Flash LFS
[1682324748.272] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324748.757] Flash LFS stop.
[1682324748.883] Test time: 2023-04-24 16:25:48
[1682324748.884] Run test15 -- External Flash LFS
[1682324748.884] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324749.398] Flash LFS stop.
[1682324749.531] Test time: 2023-04-24 16:25:49
[1682324749.532] Run test16 -- External Flash LFS
[1682324749.532] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682324750.038] Flash LFS stop.
[1682324750.16] Test time: 2023-04-24 16:25:50
[1682324750.161] Run test17 -- External Flash LFS
[1682324750.161] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682324750.661] Flash LFS stop.
[1682324762.392] Test time: 2023-04-24 16:26:02
[1682324762.394] Run test0 -- External Flash LFS
[1682324762.394] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324782.129] ^boot.rom
'v'!\n^boot.rom
'v'!\n^boot.rom
'v'!\n[1682324783.277] Work  mode: 1
[1682324783.277] Debug mode: 0
[1682324783.279] Debug type: 3
[1682324783.282] STEP: 1
[1682324783.283] SIM is ready.
[1682324783.374] Unverified external Flash: C816
[1682324784.045] NW is ready.
[1682324784.445] OneNET MQTT is ready.
[1682324784.813] Status report: {"speaker":{"cell_id":12823395,"rssi":-75,"timestamp":"1682324783","vbat":3923978}}
[1682324798.427] Test time: 2023-04-24 16:26:38
[1682324798.428] Run test0 -- External Flash LFS
[1682324798.431] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324798.979] NW is ready.
[1682324799.347] OneNET MQTT is ready.
[1682324799.732] Status report: {"speaker":{"cell_id":12823395,"rssi":-74,"timestamp":"1682324799","vbat":3923978}}
[1682324820.653] Test time: 2023-04-24 16:27:00
[1682324820.654] Run test0 -- External Flash LFS
[1682324820.654] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324845.912] Test time: 2023-04-24 16:27:25
[1682324845.916] Run test0 -- External Flash LFS
[1682324845.916] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682324851.057] 
[1682324851.058] Write(100)
[1682324851.073] Verify
[1682324851.089] End
[1682324857.617] 
[1682324857.617] Write(200)
[1682324857.648] Verify
[1682324857.665] End
[1682324864.224] 
[1682324864.225] Write(300)
[1682324864.256] Verify
[1682324864.272] End
[1682324871.376] 
[1682324871.377] Write(400)
[1682324871.378] Verify
[1682324871.392] End
[1682324877.904] 
[1682324877.904] Write(500)
[1682324877.92] Verify
[1682324877.936] End
[1682324877.936] Flash LFS stop.
[1682324878.071] Test time: 2023-04-24 16:27:58
[1682324878.072] Run test1 -- External Flash LFS
[1682324878.072] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682324884.528] 
[1682324884.528] Write(100)
[1682324884.56] Verify
[1682324884.575] End
[1682324891.168] 
[1682324891.168] Write(200)
[1682324891.199] Verify
[1682324891.215] End
[1682324898.255] 
[1682324898.255] Write(300)
[1682324898.258] Verify
[1682324898.271] End
[1682324904.783] 
[1682324904.783] Write(400)
[1682324904.799] Verify
[1682324904.815] End
[1682324911.358] 
[1682324911.359] Write(500)
[1682324911.391] Verify
[1682324911.406] End
[1682324911.407] Flash LFS stop.
[1682324911.538] Test time: 2023-04-24 16:28:31
[1682324911.539] Run test2 -- External Flash LFS
[1682324911.54] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682324911.646] Verify
[1682324911.678] End
[1682324920.286] ../../../../../../../PLAT/middleware/thirdparty/littlefs/lfs.c:1846:debug: Relocating {0x21e, 0x3b} -> {0x11f, 0x21e}
[1682324924.318] 
[1682324924.318] Write(100)
[1682324924.381] Verify
[1682324924.398] End
[1682324936.653] 
[1682324936.653] Write(200)
[1682324936.717] Verify
[1682324936.749] End
[1682324949.388] 
[1682324949.389] Write(300)
[1682324949.453] Verify
[1682324949.485] End
[1682324961.724] 
[1682324961.724] Write(400)
[1682324961.804] Verify
[1682324961.836] End
[1682324974.684] 
[1682324974.684] Write(500)
[1682324974.748] Verify
[1682324974.748] End
[1682324974.764] Flash LFS stop.
[1682324974.894] Test time: 2023-04-24 16:29:34
[1682324974.895] Run test3 -- External Flash LFS
[1682324974.895] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682324975.068] Verify
[1682324975.131] End
[1682325011.785] 
[1682325011.787] Write(100)
[1682325012.042] Verify
[1682325012.122] End
[1682325049.256] 
[1682325049.256] Write(200)
[1682325049.513] Verify
[1682325049.592] End
[1682325054.04] Test time: 2023-04-24 16:30:54
[1682325054.178] Run test4 -- External Flash LFS
[1682325054.178] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682325054.203] Test time: 2023-04-24 16:30:54
[1682325054.203] Run test5 -- External Flash LFS
[1682325054.203] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682325054.238] Test time: 2023-04-24 16:30:54
[1682325054.239] Run test6 -- External Flash LFS
[1682325054.24] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682325054.271] Test time: 2023-04-24 16:30:54
[1682325054.272] Run test7 -- External Flash LFS
[1682325054.322] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682325054.37] Test time: 2023-04-24 16:30:54
[1682325054.371] Run test8 -- External Flash LFS
[1682325054.371] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682325054.414] Test time: 2023-04-24 16:30:54
[1682325054.417] Run test9 -- External Flash LFS
[1682325054.418] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682325054.477] Test time: 2023-04-24 16:30:54
[1682325054.478] Run test10 -- External Flash LFS
[1682325054.478] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682325054.544] Test time: 2023-04-24 16:30:54
[1682325054.546] Run test11 -- External Flash LFS
[1682325054.566] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682325054.864] Test time: 2023-04-24 16:30:54
[1682325054.887] Run test12 -- External Flash LFS
[1682325054.946] {"name": "systest_flash_lfs", "param1": 50, "param2": 500, "param3": "0"}
[1682325055.06] Test time: 2023-04-24 16:30:55
[1682325055.062] Run test13 -- External Flash LFS
[1682325055.063] {"name": "systest_flash_lfs", "param1": 1024, "param2": 500, "param3": "0"}
[1682325055.587] Test time: 2023-04-24 16:30:55
[1682325055.599] Run test14 -- External Flash LFS
[1682325055.601] {"name": "systest_flash_lfs", "param1": 10240, "param2": 500, "param3": "0"}
[1682325055.664] Test time: 2023-04-24 16:30:55
[1682325055.679] Run test15 -- External Flash LFS
[1682325055.702] {"name": "systest_flash_lfs", "param1": 51200, "param2": 500, "param3": "0"}
[1682325055.76] Test time: 2023-04-24 16:30:55
[1682325055.767] Run test16 -- External Flash LFS
[1682325055.77] {"name": "systest_flash_lfs", "param1": 102400, "param2": 500, "param3": "0"}
[1682325055.815] Test time: 2023-04-24 16:30:55
[1682325055.826] Run test17 -- External Flash LFS
[1682325055.86] {"name": "systest_flash_lfs", "param1": 512000, "param2": 500, "param3": "0"}
[1682325200.55] Test time: 2023-04-24 16:33:20
[1682325200.551] Run test0 -- External Flash LFS
[1682325200.551] {"name": "systest_flash_lfs", "param1": 50, "param2": 300, "param3": "0"}
[1682325261.244] Receive timeout.
[1682325261.44] Test time: 2023-04-24 16:34:21
[1682325261.44] Run test1 -- External Flash LFS
[1682325261.44] {"name": "systest_flash_lfs", "param1": 1024, "param2": 300, "param3": "0"}
[1682325267.856] 
[1682325267.857] Write(100)
[1682325267.889] Verify
[1682325267.904] End
[1682325274.993] 
[1682325274.993] Write(200)
[1682325275.008] Verify
[1682325275.008] End
[1682325281.536] 
[1682325281.536] Write(300)
[1682325281.552] Verify
[1682325281.568] End
[1682325281.568] Flash LFS stop.
[1682325281.694] Test time: 2023-04-24 16:34:41
[1682325281.694] Run test2 -- External Flash LFS
[1682325281.695] {"name": "systest_flash_lfs", "param1": 10240, "param2": 300, "param3": "0"}
[1682325293.903] 
[1682325293.903] Write(100)
[1682325293.983] Verify
[1682325294.014] End
[1682325306.333] 
[1682325306.334] Write(200)
[1682325306.414] Verify
[1682325306.446] End
[1682325319.165] 
[1682325319.165] Write(300)
[1682325319.245] Verify
[1682325319.261] End
[1682325319.262] Flash LFS stop.
[1682325319.394] Test time: 2023-04-24 16:35:19
[1682325319.395] Run test3 -- External Flash LFS
[1682325319.396] {"name": "systest_flash_lfs", "param1": 51200, "param2": 300, "param3": "0"}
[1682325319.572] Verify
[1682325319.653] End
[1682325355.858] 
[1682325355.859] Write(100)
[1682325356.131] Verify
[1682325356.211] End
[1682325392.625] 
[1682325392.625] Write(200)
[1682325392.897] Verify
[1682325392.977] End
[1682325407.824] Status report: {"speaker":{"cell_id":12823395,"rssi":-73,"timestamp":"1682325407","vbat":3921829}}
[1682325429.999] 
[1682325429.999] Write(300)
[1682325430.255] Verify
[1682325430.319] End
[1682325430.32] Flash LFS stop.
[1682325430.443] Test time: 2023-04-24 16:37:10
[1682325430.444] Run test4 -- External Flash LFS
[1682325430.444] {"name": "systest_flash_lfs", "param1": 102400, "param2": 300, "param3": "0"}
[1682325431.551] Verify
[1682325431.839] End
[1682325492.515] Receive timeout.
[1682325492.646] Test time: 2023-04-24 16:38:12
[1682325492.647] Run test5 -- External Flash LFS
[1682325492.648] {"name": "systest_flash_lfs", "param1": 512000, "param2": 300, "param3": "0"}
[1682325492.697] 2400, 300, 0
[1682325553.385] Receive timeout.
[1682325553.521] Test time: 2023-04-24 16:39:13
[1682325553.523] Run test6 -- External Flash LFS
[1682325553.523] {"name": "systest_flash_lfs", "param1": 50, "param2": 300, "param3": "0"}
[1682325553.62] 0, 0
[1682325579.667] 
[1682325579.667] Write(100)
[1682325580.835] Verify
[1682325581.122] End
[1682325641.817] Receive timeout.
[1682325641.936] Test time: 2023-04-24 16:40:41
[1682325641.937] Run test7 -- External Flash LFS
[1682325641.937] {"name": "systest_flash_lfs", "param1": 1024, "param2": 300, "param3": "0"}
[1682325642.018] sh_lfs: 50, 300, 0
[1682325701.596] Test time: 2023-04-24 16:41:41
[1682325701.598] Run test0 -- External Flash LFS
[1682325701.598] {"name": "systest_flash_lfs", "param1": 50, "param2": 300, "param3": "0"}
[1682325707.81] Status report: {"speaker":{"cell_id":12823395,"rssi":-77,"timestamp":"1682325707","vbat":3919674}}
[1682325730.465] 
[1682325730.465] Write(200)
[1682325731.65] Verify
[1682325731.938] End
[1682325792.634] Receive timeout.
[1682325792.742] {"name": "systest_flash_lfs", "param1": 50, "param2": 300, "param3": "0"}
[1682325792.815] s: 50, 300, 0
[1682325816.43] ^boot.rom
'v'!\n^boot.rom
'v'!\n[1682325817.582] Work  mode: 1
[1682325817.584] Debug mode: 0
[1682325817.598] Debug type: 3
[1682325817.6] STEP: 1
[1682325817.601] SIM is ready.
[1682325817.694] Unverified external Flash: C816
[1682325818.254] NW is ready.
[1682325818.59] OneNET MQTT is ready.
[1682325818.623] Status report: {"speaker":{"cell_id":12823395,"rssi":-75,"timestamp":"1682325818","vbat":3921829}}
[1682325830.383] Test time: 2023-04-24 16:43:50
[1682325830.387] Run test0 -- External Flash LFS
[1682325830.387] {"name": "systest_flash_lfs", "param1": 50, "param2": 300, "param3": "0"}
[1682325830.919] Work  mode: 1
[1682325830.92] Debug mode: 0
[1682325830.921] Debug type: 3
[1682325830.922] STEP: 1
[1682325830.935] SIM is ready.
[1682325831.031] Unverified external Flash: C816
[1682325831.543] NW is ready.
[1682325831.895] OneNET MQTT is ready.
[1682325831.959] Status report: {"speaker":{"cell_id":12823395,"rssi":-76,"timestamp":"1682325831","vbat":3926133}}