Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.02.2023 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15208532 kB
MemAvailable 30344612 kB
Buffers 0 kB
Cached 16451436 kB
SwapCached 3576 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-07 01:38:35
DTS_END                         2023-02-07 01:38:35.2340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35012
TRN_ID                          243774
ATT_ID                          647
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9292.02 22299 1115042
customer order: creation 174.88 347 20986
customer order: refuse 14.46 380 1735
order to supplier: creation 46.13 1256 5535
order to supplier: removal 3.33 101351 400
invoice (draft): creation 46.77 3443 5612
invoice (draft): removal 52.04 768 6245
invoice accept: apply 42.41 19109 5089
invoice accept: cancel 47.19 855 5663
customer reserve: creation 3925.15 189 471018
customer reserve: removal 4634.91 57 556189
realization accept: apply 84.77 204 10172
realization accept: cancel 87.56 102 10507
payment from customer: creation 59.72 127 7166
payment from customer: removal 5.89 95 707
payment to supplier: creation 50.50 136 6060
payment to supplier: removal 3.70 78 444
service: total inventory turnovers 5.12 1046 614
service: total monetary turnovers 6.58 40 789
service: aggregate perf. data 0.89 19135 107
service: refresh index statistics 0.03 297252 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-02-2023 01:39:21 to 07-02-2023 01:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4145
TEST_TIME 2 3200
TEST_TIME 3 5151
TEST_TIME 4 6055
TEST_TIME 5 5704
TEST_TIME 6 6070
TEST_TIME 7 6050
TEST_TIME 8 6010
TEST_TIME 9 5853
TEST_TIME 10 6663
TEST_TIME 11 6768
TEST_TIME 12 6870
TEST_TIME 13 6993
TEST_TIME 14 7181
TEST_TIME 15 7309
TEST_TIME 16 7933
TEST_TIME 17 8018
TEST_TIME 18 8177
TEST_TIME 19 8302
TEST_TIME 20 8227
TEST_TIME 21 8297
TEST_TIME 22 8824
TEST_TIME 23 8977
TEST_TIME 24 8958
TEST_TIME 25 9104
TEST_TIME 26 9088
TEST_TIME 27 9101
TEST_TIME 28 9517
TEST_TIME 29 9406
TEST_TIME 30 9531
TEST_TIME 31 10163
TEST_TIME 32 10544
TEST_TIME 33 10693
TEST_TIME 34 10945
TEST_TIME 35 10869
TEST_TIME 36 10845
TEST_TIME 37 10568
TEST_TIME 38 10350
TEST_TIME 39 10185
TEST_TIME 40 10161
TEST_TIME 41 10095
TEST_TIME 42 9973
TEST_TIME 43 9948
TEST_TIME 44 9859
TEST_TIME 45 9813
TEST_TIME 46 9800
TEST_TIME 47 9703
TEST_TIME 48 9591
TEST_TIME 49 9502
TEST_TIME 50 9384
TEST_TIME 51 9480
TEST_TIME 52 9456
TEST_TIME 53 9577
TEST_TIME 54 9490
TEST_TIME 55 9559
TEST_TIME 56 9477
TEST_TIME 57 9399
TEST_TIME 58 9435
TEST_TIME 59 9590
TEST_TIME 60 9591
TEST_TIME 61 9554
TEST_TIME 62 9666
TEST_TIME 63 9667
TEST_TIME 64 9615
TEST_TIME 65 9623
TEST_TIME 66 9680
TEST_TIME 67 9592
TEST_TIME 68 9514
TEST_TIME 69 9461
TEST_TIME 70 9444
TEST_TIME 71 9481
TEST_TIME 72 9433
TEST_TIME 73 9364
TEST_TIME 74 9378
TEST_TIME 75 9384
TEST_TIME 76 9305
TEST_TIME 77 9300
TEST_TIME 78 9273
TEST_TIME 79 9294
TEST_TIME 80 9247
TEST_TIME 81 9293
TEST_TIME 82 9430
TEST_TIME 83 9412
TEST_TIME 84 9432
TEST_TIME 85 9383
TEST_TIME 86 9467
TEST_TIME 87 9460
TEST_TIME 88 9470
TEST_TIME 89 9486
TEST_TIME 90 9571
TEST_TIME 91 9551
TEST_TIME 92 9469
TEST_TIME 93 9430
TEST_TIME 94 9413
TEST_TIME 95 9416
TEST_TIME 96 9376
TEST_TIME 97 9321
TEST_TIME 98 9293
TEST_TIME 99 9226
TEST_TIME 100 9243
TEST_TIME 101 9278
TEST_TIME 102 9240
TEST_TIME 103 9251
TEST_TIME 104 9213
TEST_TIME 105 9170
TEST_TIME 106 9136
TEST_TIME 107 9136
TEST_TIME 108 9146
TEST_TIME 109 9149
TEST_TIME 110 9126
TEST_TIME 111 9168
TEST_TIME 112 9192
TEST_TIME 113 9188
TEST_TIME 114 9196
TEST_TIME 115 9192
TEST_TIME 116 9214
TEST_TIME 117 9226
TEST_TIME 118 9239
TEST_TIME 119 9219
TEST_TIME 120 9295

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-02-2023 01:39:25 to 07-02-2023 01:39:28.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1549983 1549983 0 0.00 0 24587 7 0 0
doc_list_biud 1549983 1549983 0 0.00 0 28991 27 0 0
sp_add_invoice_to_stock 5221 5089 132 2.52 18 156333 19110 0 132
sp_cancel_adding_invoice 5663 5663 0 0.00 5 54348 856 0 0
sp_cancel_client_order 1735 1735 0 0.00 3 9630 381 0 0
sp_cancel_customer_reserve 556189 556189 0 0.00 0 33090 58 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 8920 96 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 2 5203 79 0 0
sp_cancel_supplier_invoice 6257 6245 12 0.19 2 24967 768 0 12
sp_cancel_supplier_order 400 400 0 0.00 10 828533 101351 0 0
sp_cancel_write_off 10507 10507 0 0.00 1 15040 102 0 0
sp_client_order 20986 20986 0 0.00 3 24832 347 0 0
sp_customer_reserve 471018 471018 0 0.00 0 81328 190 0 0
sp_fill_shopping_cart 32130 32130 0 0.00 1 10104 40 0 0
sp_fill_shopping_cart_clo_res 471016 471016 0 0.00 0 13685 14 0 0
sp_get_clo_for_invoice 5086 5086 0 0.00 0 24125 292 0 0
sp_kill_cost_storno 17321 17321 0 0.00 0 8910 52 0 0
sp_kill_qstorno_ret_qs2qd 1129530 1129530 0 0.00 0 28986 31 0 0
sp_kill_qty_storno 595997 595997 0 0.00 0 28989 68 0 0
sp_lock_selected_doc 21193 21193 0 0.00 0 7961 5 0 0
sp_make_cost_storno 28484 28484 0 0.00 0 24581 59 0 0
sp_multiply_rows_for_pdistr 28484 28484 0 0.00 0 11800 31 0 0
sp_multiply_rows_for_qdistr 20986 20986 0 0.00 0 15553 144 0 0
sp_pay_from_customer 7166 7166 0 0.00 4 14738 128 0 0
sp_pay_to_supplier 6060 6060 0 0.00 4 24593 136 0 0
sp_payment_common 13226 13226 0 0.00 1 6970 31 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 9620 350 0 0
sp_qd_handle_on_invoice_upd_sts 10749 10749 0 0.00 0 28967 344 0 0
sp_qd_handle_on_reserve_upd_sts 10172 10172 0 0.00 0 25885 74 0 0
sp_reserve_write_off 10172 10172 0 0.00 6 25931 204 0 0
sp_supplier_invoice 5612 5612 0 0.00 6 76701 3443 0 0
sp_supplier_order 5535 5535 0 0.00 5 25575 1256 0 0
srv_aggregate_perf_data 107 107 0 0.00 3127 258289 19135 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1097 118406 44223 0 0
srv_find_qd_qs_mism 1065803 1065803 0 0.00 0 13034 6 0 0
srv_make_invnt_saldo 614 614 0 0.00 40 45642 1047 0 0
srv_make_money_saldo 789 789 0 0.00 1 5962 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 133495 424954 297253 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5089 5089 0 0.00 0 13456 196 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 6790 113 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 2256 11 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 1953 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 363 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 67 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 6885 28 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 26207 1594 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 2909 8 0 0
t$perf-norm:sp_client_order 20986 20986 0 0.00 0 8807 19 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 3432 11 0 0
t$perf-norm:sp_pay_from_customer 7166 7166 0 0.00 0 8530 14 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 3910 10 0 0
t$perf-norm:sp_reserve_write_off 10172 10172 0 0.00 0 7050 13 0 0
t$perf-norm:sp_supplier_invoice 5612 5612 0 0.00 0 4513 19 0 0
t$perf-norm:sp_supplier_order 5535 5535 0 0.00 0 13156 20 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 352 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 207 4 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 2440 31 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 3246 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10434 10170 264 2.53 0 9460 21 0 264
v_all_customers 37776 37776 0 0.00 0 57 1 0 0
v_all_suppliers 30704 30704 0 0.00 0 40 1 0 0
v_all_wares 41972 41972 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 1030 7 0 0
v_cancel_client_order 3470 3470 0 0.00 0 3379 7 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 1269 6 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 1802 6 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 5199 9 0 0
v_cancel_supplier_invoice 874 850 24 2.74 0 3635 23 0 24
v_cancel_supplier_order 800 800 0 0.00 0 374 5 0 0
v_cancel_write_off 2136 2136 0 0.00 0 1873 14 0 0
v_max_id_clo_ord 5535 5535 0 0.00 0 151 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 1822 9 0 0
v_max_id_ord_sup 5608 5608 0 0.00 0 3135 3 0 0
v_max_non_paid_invoice 1851 1851 0 0.00 0 685 3 0 0
v_max_non_paid_realizn 4003 4003 0 0.00 0 2918 6 0 0
v_min_id_clo_ord 5535 5535 0 0.00 0 5154 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 5228 22 0 0
v_min_id_ord_sup 5608 5608 0 0.00 0 6134 13 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 5802 24 0 0
v_min_non_paid_realizn 7166 7166 0 0.00 0 5629 23 0 0
v_our_firm 10522 10522 0 0.00 0 56 1 0 0
v_reserve_write_off 20344 20344 0 0.00 0 3709 8 0 0
x_lock_depdocs_on_canc_invoice 11483 11483 0 0.00 0 22821 121 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 16443 401 0 0
x_make_qstorno_1000_1200 5535 5535 0 0.00 1 25567 1174 0 0
x_make_qstorno_1200_2000 5612 5612 0 0.00 2 72416 3296 0 0
x_make_qstorno_2100_3300 471018 471018 0 0.00 0 81325 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-02-2023 01:39:29 to 07-02-2023 01:39:33.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:39 21 8192000000 8862905360 8895647744 56151776 0.680 21 9 11 0 56602928 157856 293296
23:09:40 37031 8192000000 9922522912 10234277888 991408368 10.795 100 86 172 0 1019855440 5918640 22528432
23:11:21 22774 8192000000 9961730176 10285219840 1041199296 11.276 100 88 176 0 1072188208 8361520 22627392
23:12:47 10989 8192000000 9995209408 10329542656 1078323056 11.631 100 83 166 1 1109173232 10589760 20260416
23:14:10 8514 8192000000 10053790976 10403024896 1136322368 12.181 100 84 168 0 1168216224 12011168 19882688
23:15:22 4384 8192000000 10084250688 10442416128 1172971504 12.525 100 84 164 0 1205437264 13192976 19272784
23:16:28 3799 8192000000 10138980032 10509316096 1223820880 12.997 100 87 174 0 1257066352 14029584 19215888
23:17:35 1894 8192000000 10163815024 10538459136 1252669312 13.263 100 87 172 0 1285068784 13935440 18464032
23:18:39 1108 8192000000 10187553328 10570293248 1277881424 13.494 100 85 169 0 1308093904 11519104 18693376
23:19:42 810 8192000000 10214847744 10601787392 1301406992 13.708 100 82 163 2 1330809328 11461216 17941120
23:20:45 19628 8192000000 10218969024 10617016320 1310528272 13.791 100 75 149 2 1337723664 9280448 17914944
23:22:06 4963 8192000000 10212303040 10620559360 1314658448 13.828 100 45 91 1 1333135344 7551040 10925856
23:23:11 8417 8192000000 10212649664 10624921600 1321478384 13.890 100 16 32 0 1330847520 6213904 3155232
23:24:23 12776 8192000000 10225188640 10643505152 1334920304 14.012 100 22 44 1 1345729104 5411008 5397792
23:25:40 32152 8192000000 10227646800 10655911936 1341231728 14.069 100 19 38 0 1350722096 5315744 4174624
23:27:13 6287 8192000000 10237168912 10653073408 1340075936 14.058 100 18 36 0 1348987872 5330944 3580992
23:28:20 7855 8192000000 10234135856 10657619968 1340841776 14.065 100 14 27 1 1353117264 9598480 2677008
23:29:30 65157 8192000000 10181282608 10585362432 1225536464 13.013 93 29 58 0 1238408832 4753408 8118960
23:31:36 7121 8192000000 10135115328 10533281792 1205947872 12.832 95 37 71 0 1220039504 6632096 7459536
23:32:46 11255 8192000000 10146486608 10553749504 1241963872 13.164 98 24 48 1 1253277296 5365360 5948064
23:33:59 11769 8192000000 10162747920 10572406784 1275798720 13.475 100 25 48 0 1287450448 4804576 6847152
23:35:13 18267 8192000000 10171534592 10583072768 1272277264 13.442 99 27 52 0 1283466832 4542944 6646624
23:36:35 9215 8192000000 10186403520 10603601920 1298541376 13.682 100 21 42 1 1309142208 5998928 4601904
23:37:44 16626 8192000000 10197533872 10617413632 1308208384 13.770 100 18 35 1 1318278688 6368096 3702208
23:39:05 33398 8192000000 10244935040 10657857536 1339241952 14.051 100 38 76 1 1353677472 5745280 8690240
23:40:50 32554 8192000000 10259835712 10679705600 1352967120 14.174 100 50 100 2 1376205472 11503840 11734512
23:42:30 10198 8192000000 10272970224 10694090752 1365562592 14.287 100 44 86 0 1387290560 12118480 9609488
23:43:43 6178 8192000000 10296230096 10708852736 1373502640 14.358 100 68 133 0 1403506320 15146608 14857072
23:44:56 4236 8192000000 10303588784 10726567936 1389467392 14.501 100 50 100 1 1415529056 14312048 11749616
23:46:13 8741 8192000000 10301891056 10728914944 1392283968 14.526 100 46 86 1 1416138528 13886544 9968016
23:47:25 8125 8192000000 10323629568 10741805056 1394666528 14.547 100 69 136 0 1432111024 20478912 16965584
23:48:43 28149 8192000000 10318423680 10757656576 1410020624 14.684 100 35 70 0 1435709680 16941552 8747504
23:50:31 9330 8192000000 10340352880 10762395648 1407598112 14.663 100 69 135 1 1446347632 22868512 15881008
23:51:48 1913 8192000000 10338023808 10757083136 1400449104 14.599 100 65 127 0 1437018192 22077776 14491312
23:52:55 10594 8192000000 10302035280 10719625216 1361568272 14.251 98 52 104 0 1401288928 26848576 12872080
23:54:11 15335 8192000000 10309619968 10728603648 1354121328 14.185 99 58 115 0 1404948656 36855648 13971680
23:55:31 4547 8192000000 10291806944 10699780096 1355065520 14.193 100 65 128 1 1396656528 25966608 15624400
23:56:41 2856 8192000000 10309570624 10717306880 1363825552 14.272 100 64 124 0 1412342288 33304448 15212288
23:57:49 5840 8192000000 10309404496 10722508800 1368814832 14.316 100 51 100 0 1414704624 33333584 12556208
23:59:07 1092 8192000000 10335442400 10738192384 1372779536 14.352 100 85 165 1 1436471232 43117760 20573936
00:00:15 3106 8192000000 10334456800 10742865920 1371918416 14.344 100 63 126 1 1438607120 50588448 16100256
00:01:29 11862 8192000000 10343729968 10761408512 1389393360 14.500 100 52 104 1 1445517872 42780240 13344272
00:02:43 32186 8192000000 10332117936 10751926272 1373178096 14.356 99 47 94 0 1427017232 42023168 11815968
00:04:18 8744 8192000000 10326256400 10759831552 1371758016 14.343 99 33 65 0 1417229344 36634992 8836336
00:06:00 22799 8192000000 10324946704 10763489280 1373862064 14.362 99 23 46 0 1423394976 43138096 6394816
00:08:27 21093 8192000000 10359874224 10800668672 1397298608 14.571 100 24 48 0 1456520400 52497872 6723920
00:10:04 34935 8192000000 10315338960 10776211456 1388586944 14.493 100 15 30 0 1433849888 41930000 3332944
00:11:48 53651 8192000000 10306012768 10756231168 1376627808 14.386 99 10 20 0 1400489344 21486608 2374928
00:13:47 49943 8192000000 10287770688 10734624768 1354431840 14.187 97 10 20 0 1364687072 8135392 2119840
00:15:38 30071 8192000000 10301593168 10748469248 1396563296 14.564 100 48 95 1 1413151904 5178032 11410576
00:17:21 89079 8192000000 10314530208 10766479360 1402476464 14.617 100 45 90 0 1427652944 14990016 10186464
00:20:10 30502 8192000000 10305700208 10747719680 1380762240 14.423 99 50 98 0 1401363648 9413936 11187472
00:21:47 118406 8192000000 10304380272 10766995456 1399958000 14.595 100 14 28 0 1414373696 11360016 3055680
00:24:47 37207 8192000000 10323264240 10771513344 1402396848 14.616 100 15 29 0 1421423344 16062816 2963680
00:26:39 47860 8192000000 10322291392 10790051840 1413916672 14.719 100 13 25 0 1432529808 15868672 2744464
00:28:58 84954 8192000000 10307610288 10783567872 1373948336 14.362 98 15 29 1 1395402944 17779488 3675120
00:31:24 87248 8192000000 10322902992 10772459520 1374131024 14.364 98 14 27 0 1389074800 11811072 3132704
00:33:52 95096 8192000000 10292199840 10748751872 1326781504 13.938 95 16 32 0 1343371344 13201584 3388256
00:36:31 63450 8192000000 10287840352 10752712704 1356102112 14.202 98 21 42 0 1379185680 18244192 4839376
00:38:37 39825 8192000000 10279996672 10728001536 1343508592 14.089 97 18 36 1 1357411360 10056208 3846560
00:40:20 87113 8192000000 10275456464 10727661568 1315209248 13.833 95 14 27 0 1326702208 8949024 2543936
00:43:15 34307 8192000000 10287604448 10728206336 1350022960 14.148 98 31 63 0 1369523152 12760512 6739680
00:44:54 31039 8192000000 10281836400 10714009600 1342521936 14.080 98 46 90 1 1362178464 9028256 10628272
00:46:51 93859 8192000000 10255974256 10704142336 1291903360 13.622 94 20 40 2 1306527344 10421808 4202176
00:51:26 60479 8192000000 10245478384 10696527872 1322529888 13.900 97 14 29 0 1337307328 11568512 3208928
00:53:41 83766 8192000000 10248648064 10696851456 1302745584 13.720 96 24 48 0 1321008448 11960336 6302528
00:56:06 64329 8192000000 10250971360 10702528512 1338632176 14.045 99 8 14 0 1352872688 12600624 1639888
00:58:11 62219 8192000000 10294979248 10738774016 1359552944 14.233 99 28 56 0 1377708528 11697360 6458224
01:00:37 80641 8192000000 10263697136 10720182272 1347981936 14.129 98 21 43 1 1362390032 9798432 4609664
01:03:00 42632 8192000000 10281215584 10732204032 1344068176 14.094 98 19 38 0 1363090816 14624848 4397792
01:04:52 51290 8192000000 10276070768 10726297600 1345480336 14.107 98 15 30 0 1359504848 10740944 3283568
01:07:02 47998 8192000000 10296660864 10749431808 1365560432 14.287 99 25 50 0 1383688960 12800576 5327952
01:08:57 101954 8192000000 10298754352 10744688640 1363271712 14.267 98 25 50 1 1386272496 17487904 5512880
01:11:39 54641 8192000000 10299556528 10753859584 1390045840 14.506 100 29 58 0 1409905792 13617888 6242064
01:13:34 48830 8192000000 10290742016 10757009408 1397621856 14.574 100 8 16 0 1406747344 7681056 1444432
01:15:23 54137 8192000000 10270171680 10731622400 1339304912 14.051 96 15 31 0 1349347552 6583152 3459488
01:17:21 100533 8192000000 10287461152 10754269184 1380227408 14.419 99 27 54 0 1401273280 14349920 6695952
01:20:21 13359 8192000000 10288842544 10741374976 1376531776 14.386 99 37 74 0 1393466192 8643552 8290864
01:21:43 73740 8192000000 10285775296 10749493248 1376672768 14.387 99 22 44 0 1393900096 12091104 5136224
01:24:28 70002 8192000000 10263378960 10734239744 1339354304 14.052 97 8 16 1 1354014800 12907056 1753440
01:26:40 54850 8192000000 10266241456 10706440192 1316021536 13.841 96 34 63 1 1331776704 8605216 7149952
01:28:35 57942 8192000000 10287903648 10739994624 1362801264 14.262 100 27 54 0 1390595616 21889312 5905040
01:30:41 36314 8192000000 10275098896 10715619328 1336515664 14.026 98 28 56 0 1352495936 9911072 6069200
01:32:23 33843 8192000000 10261693824 10704052224 1326111216 13.932 98 18 34 0 1337154640 7528768 3514656
01:33:57 72228 8192000000 10256532544 10712875008 1320803664 13.884 97 9 18 0 1332142288 9550624 1788000
01:36:14 84578 8192000000 10281525216 10732748800 1325385344 13.925 97 9 19 0 1346328960 18870336 2073280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-02-2023 01:39:35 to 07-02-2023 01:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-02-2023 01:39:51 to 07-02-2023 01:39:53.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243228
MON$OLDEST_ACTIVE               243911
MON$OLDEST_SNAPSHOT             243911
MON$NEXT_TRANSACTION            243911
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2036320
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:133: 
:134: mscs-test-161.budzdorov.ru    Tue Feb  7 01:38:44 2023
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Tue Feb  7 01:38:53 2023
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:140: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

07.02.23 01:39:54. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-07 01:39:57 No severe PSQL-related problems occured
07.02.23 01:39:58. End of report.