Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2022 10: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 14722772 kB
MemAvailable 30072488 kB
Buffers 0 kB
Cached 16941132 kB
SwapCached 3636 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2022-12-26 10:37:21
DTS_END                         2022-12-26 10:37:21.3600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45224
TRN_ID                          204641
ATT_ID                          585
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: 9059.55 26297 1087146
customer order: creation 163.23 521 19588
customer order: refuse 14.38 440 1725
order to supplier: creation 44.25 1414 5310
order to supplier: removal 3.43 187036 411
invoice (draft): creation 45.06 3468 5407
invoice (draft): removal 50.64 1078 6077
invoice accept: apply 41.46 28403 4975
invoice accept: cancel 45.85 1302 5502
customer reserve: creation 3881.21 268 465745
customer reserve: removal 4486.39 113 538367
realization accept: apply 79.56 300 9547
realization accept: cancel 77.47 179 9296
payment from customer: creation 57.78 195 6934
payment from customer: removal 5.52 136 662
payment to supplier: creation 47.68 206 5722
payment to supplier: removal 3.38 130 405
service: total inventory turnovers 3.53 4935 423
service: total monetary turnovers 7.78 426 933
service: aggregate perf. data 0.94 18272 113
service: refresh index statistics 0.03 277115 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-12-2022 10:38:33 to 26-12-2022 10:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5190
TEST_TIME 2 5778
TEST_TIME 3 5372
TEST_TIME 4 5520
TEST_TIME 5 5893
TEST_TIME 6 5488
TEST_TIME 7 5778
TEST_TIME 8 5692
TEST_TIME 9 6141
TEST_TIME 10 6573
TEST_TIME 11 6832
TEST_TIME 12 6862
TEST_TIME 13 6842
TEST_TIME 14 6968
TEST_TIME 15 6988
TEST_TIME 16 7284
TEST_TIME 17 7392
TEST_TIME 18 7764
TEST_TIME 19 7890
TEST_TIME 20 7985
TEST_TIME 21 8018
TEST_TIME 22 8069
TEST_TIME 23 8147
TEST_TIME 24 8203
TEST_TIME 25 8238
TEST_TIME 26 8400
TEST_TIME 27 8443
TEST_TIME 28 8596
TEST_TIME 29 8616
TEST_TIME 30 8730
TEST_TIME 31 8976
TEST_TIME 32 9024
TEST_TIME 33 9294
TEST_TIME 34 9612
TEST_TIME 35 10179
TEST_TIME 36 10400
TEST_TIME 37 10668
TEST_TIME 38 10751
TEST_TIME 39 10535
TEST_TIME 40 10753
TEST_TIME 41 10563
TEST_TIME 42 10338
TEST_TIME 43 10277
TEST_TIME 44 10229
TEST_TIME 45 10063
TEST_TIME 46 9951
TEST_TIME 47 9913
TEST_TIME 48 9824
TEST_TIME 49 9766
TEST_TIME 50 9702
TEST_TIME 51 9843
TEST_TIME 52 9749
TEST_TIME 53 9784
TEST_TIME 54 9764
TEST_TIME 55 9699
TEST_TIME 56 9599
TEST_TIME 57 9595
TEST_TIME 58 9522
TEST_TIME 59 9444
TEST_TIME 60 9343
TEST_TIME 61 9290
TEST_TIME 62 9368
TEST_TIME 63 9326
TEST_TIME 64 9336
TEST_TIME 65 9314
TEST_TIME 66 9290
TEST_TIME 67 9337
TEST_TIME 68 9309
TEST_TIME 69 9346
TEST_TIME 70 9350
TEST_TIME 71 9333
TEST_TIME 72 9346
TEST_TIME 73 9386
TEST_TIME 74 9487
TEST_TIME 75 9527
TEST_TIME 76 9461
TEST_TIME 77 9423
TEST_TIME 78 9347
TEST_TIME 79 9282
TEST_TIME 80 9256
TEST_TIME 81 9252
TEST_TIME 82 9235
TEST_TIME 83 9207
TEST_TIME 84 9141
TEST_TIME 85 9086
TEST_TIME 86 9028
TEST_TIME 87 9017
TEST_TIME 88 9046
TEST_TIME 89 9013
TEST_TIME 90 9050
TEST_TIME 91 9012
TEST_TIME 92 8980
TEST_TIME 93 9015
TEST_TIME 94 9010
TEST_TIME 95 9022
TEST_TIME 96 9051
TEST_TIME 97 9063
TEST_TIME 98 9082
TEST_TIME 99 9109
TEST_TIME 100 9104
TEST_TIME 101 9138
TEST_TIME 102 9189
TEST_TIME 103 9186
TEST_TIME 104 9106
TEST_TIME 105 9108
TEST_TIME 106 9147
TEST_TIME 107 9101
TEST_TIME 108 9064
TEST_TIME 109 9020
TEST_TIME 110 9017
TEST_TIME 111 9007
TEST_TIME 112 8994
TEST_TIME 113 9008
TEST_TIME 114 8987
TEST_TIME 115 8955
TEST_TIME 116 8974
TEST_TIME 117 9000
TEST_TIME 118 8973
TEST_TIME 119 9031
TEST_TIME 120 9079

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2022 10:38:37 to 26-12-2022 10:38:40.

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 1512731 1512731 0 0.00 0 17379 14 0 0
doc_list_biud 1512718 1512718 0 0.00 0 65597 47 0 0
sp_add_invoice_to_stock 5080 4975 105 2.06 30 209500 28404 0 105
sp_cancel_adding_invoice 5502 5502 0 0.00 5 107700 1302 0 0
sp_cancel_client_order 1725 1725 0 0.00 6 8875 441 0 0
sp_cancel_customer_reserve 538367 538367 0 0.00 1 47843 114 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 4 3175 137 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4295 130 0 0
sp_cancel_supplier_invoice 6085 6077 8 0.13 2 65606 1078 0 8
sp_cancel_supplier_order 411 411 0 0.00 13 1496119 187037 0 0
sp_cancel_write_off 9296 9296 0 0.00 2 9543 180 0 0
sp_client_order 19588 19588 0 0.00 6 47506 521 0 0
sp_customer_reserve 465745 465745 0 0.00 0 61090 268 0 0
sp_fill_shopping_cart 30289 30289 0 0.00 1 22145 95 0 0
sp_fill_shopping_cart_clo_res 465720 465720 0 0.00 0 16151 21 0 0
sp_get_clo_for_invoice 4938 4938 0 0.00 0 34474 327 0 0
sp_kill_cost_storno 15865 15865 0 0.00 0 9273 79 0 0
sp_kill_qstorno_ret_qs2qd 1092518 1092518 0 0.00 0 65595 49 0 0
sp_kill_qty_storno 575861 575861 0 0.00 0 65597 113 0 0
sp_lock_selected_doc 20120 20120 0 0.00 0 8902 9 0 0
sp_make_cost_storno 27141 27141 0 0.00 0 17367 87 0 0
sp_multiply_rows_for_pdistr 27139 27139 0 0.00 0 8313 41 0 0
sp_multiply_rows_for_qdistr 19588 19588 0 0.00 0 47477 214 0 0
sp_pay_from_customer 6934 6934 0 0.00 5 11383 195 0 0
sp_pay_to_supplier 5722 5722 0 0.00 6 12153 207 0 0
sp_payment_common 12656 12656 0 0.00 2 9332 55 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 8865 362 0 0
sp_qd_handle_on_invoice_upd_sts 10438 10438 0 0.00 0 26283 586 0 0
sp_qd_handle_on_reserve_upd_sts 9547 9547 0 0.00 0 20017 64 0 0
sp_reserve_write_off 9547 9547 0 0.00 9 20123 300 0 0
sp_supplier_invoice 5407 5407 0 0.00 9 100409 3469 0 0
sp_supplier_order 5310 5310 0 0.00 6 47716 1415 0 0
srv_aggregate_perf_data 113 113 0 0.00 2640 256782 18272 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 733 151117 28073 0 0
srv_find_qd_qs_mism 1037445 1037445 0 0.00 0 10953 11 0 0
srv_make_invnt_saldo 423 423 0 0.00 103 115455 4936 0 0
srv_make_money_saldo 933 933 0 0.00 2 13114 426 0 0
srv_recalc_idx_stat 4 4 0 0.00 49612 512542 277115 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4978 4978 0 0.00 1 12805 1036 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 2 7632 505 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 2070 29 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 10479 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 1036 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1092 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 2399 52 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 114553 7283 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 5298 27 0 0
t$perf-norm:sp_client_order 19588 19588 0 0.00 0 7884 41 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 598 15 0 0
t$perf-norm:sp_pay_from_customer 6934 6934 0 0.00 0 3741 21 0 0
t$perf-norm:sp_pay_to_supplier 5722 5722 0 0.00 0 5567 22 0 0
t$perf-norm:sp_reserve_write_off 9547 9547 0 0.00 0 5452 26 0 0
t$perf-norm:sp_supplier_invoice 5407 5407 0 0.00 0 5637 31 0 0
t$perf-norm:sp_supplier_order 5310 5310 0 0.00 0 4038 35 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 273 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 753 13 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 694 13 0 0
t$perf-norm:srv_make_money_saldo 933 933 0 0.00 0 538 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 201 62 0 0
v_add_invoice_to_stock 10080 9870 210 2.08 0 5738 26 0 210
v_all_customers 35994 35994 0 0.00 0 129 4 0 0
v_all_suppliers 29866 29866 0 0.00 0 62 5 0 0
v_all_wares 39176 39176 0 0.00 0 62 5 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1647 17 0 0
v_cancel_client_order 3450 3450 0 0.00 0 2237 11 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 1908 15 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 1 321 12 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 4287 16 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 1210 29 0 16
v_cancel_supplier_order 822 822 0 0.00 0 436 12 0 0
v_cancel_write_off 2000 2000 0 0.00 0 2750 29 0 0
v_max_id_clo_ord 5304 5304 0 0.00 0 277 6 0 0
v_max_id_clo_res 977 977 0 0.00 0 1829 18 0 0
v_max_id_ord_sup 5397 5397 0 0.00 0 4080 8 0 0
v_max_non_paid_invoice 1490 1490 0 0.00 0 312 9 0 0
v_max_non_paid_realizn 3596 3596 0 0.00 0 399 8 0 0
v_min_id_clo_ord 5304 5304 0 0.00 0 4969 9 0 0
v_min_id_clo_res 977 977 0 0.00 0 2457 14 0 0
v_min_id_ord_sup 5397 5397 0 0.00 0 9064 13 0 0
v_min_non_paid_invoice 5722 5722 0 0.00 0 9180 22 0 0
v_min_non_paid_realizn 6934 6934 0 0.00 0 9322 25 0 0
v_our_firm 9858 9858 0 0.00 0 51 5 0 0
v_reserve_write_off 19094 19094 0 0.00 0 4281 11 0 0
x_lock_depdocs_on_canc_invoice 11183 11183 0 0.00 0 9174 117 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 9390 396 0 0
x_make_qstorno_1000_1200 5310 5310 0 0.00 2 47707 1235 0 0
x_make_qstorno_1200_2000 5407 5407 0 0.00 4 100379 3200 0 0
x_make_qstorno_2100_3300 465741 465741 0 0.00 0 55818 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2022 10:38:41 to 26-12-2022 10:38:45.

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
08:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:25 35182 8192000000 9725575648 10024620032 795585568 8.852 100 91 182 0 826110448 8259600 22265280
08:10:05 19302 8192000000 9760681248 10068176896 836965808 9.269 100 93 184 0 872576064 13834000 21776256
08:11:29 18024 8192000000 9807494400 10125463552 863096912 9.531 100 87 174 0 907742832 24760592 19885328
08:13:00 7325 8192000000 9869025504 10200281088 913343472 10.030 100 87 172 0 978757072 46656592 18757008
08:14:16 5112 8192000000 9920099296 10260717568 955486672 10.445 100 84 169 0 1035092064 61390736 18214656
08:15:23 4678 8192000000 9940921728 10294308864 985609616 10.739 100 82 153 2 1061512496 60250256 15652624
08:16:33 1102 8192000000 9982563120 10340163584 1004391696 10.921 100 85 158 1 1100704288 80893232 15419360
08:17:37 881 8192000000 10011348144 10377203712 1035984208 11.226 100 97 187 0 1125657376 71314080 18359088
08:18:40 1373 8192000000 10012287248 10381557760 1046955904 11.331 100 94 176 3 1136323264 71674352 17693008
08:19:43 1222 8192000000 10043137776 10417823744 1053610448 11.395 100 93 182 0 1164231152 91497168 19123536
08:20:46 1412 8192000000 10039158384 10415554560 1058741152 11.444 100 95 184 2 1161013360 83994176 18278032
08:21:50 1063 8192000000 10031518352 10413584384 1071135440 11.563 100 97 188 0 1154204960 64410624 18658896
08:22:52 1233 8192000000 10023886992 10406264832 1074428048 11.594 100 90 172 0 1145296368 52465056 18403264
08:23:55 1221 8192000000 10068056720 10462851072 1081906368 11.666 100 92 174 1 1194555936 94053024 18596544
08:24:58 911 8192000000 10043287984 10434359296 1090817264 11.750 100 83 158 1 1166392368 59455664 16119440
08:26:00 1000 8192000000 10047971152 10436087808 1089063136 11.734 100 90 171 2 1169320800 62397984 17859680
08:27:03 1260 8192000000 10066290160 10454384640 1093751424 11.778 100 96 176 1 1191925088 79659840 18513824
08:28:06 1065 8192000000 10082216176 10471706624 1099151888 11.830 100 96 187 2 1206492032 88293168 19046976
08:29:08 1081 8192000000 10063984992 10452070400 1100739264 11.845 100 96 182 3 1184150432 63614240 19796928
08:30:11 915 8192000000 10062720224 10453782528 1093427200 11.775 100 94 178 1 1182576176 71578656 17570320
08:31:13 1165 8192000000 10029516368 10412957696 1083604256 11.682 100 90 173 0 1156593472 54447568 18541648
08:32:15 1226 8192000000 10005385904 10386108416 1075005312 11.600 100 85 159 2 1121356336 30936880 15414144
08:33:19 1111 8192000000 10014143232 10390020096 1051137344 11.372 100 92 176 4 1137728272 67859744 18731184
08:34:21 1232 8192000000 10025843648 10405879808 1063431312 11.489 100 98 186 1 1147896192 64988928 19475952
08:35:23 760 8192000000 10015451712 10391769088 1054022272 11.399 100 92 179 1 1140602080 67893584 18686224
08:36:28 911 8192000000 9983166288 10362404864 1039839696 11.263 100 91 177 0 1112983552 54615168 18528688
08:37:36 1424 8192000000 9994634112 10369654784 1047187904 11.334 99 79 149 3 1117613408 56283408 14142096
08:38:44 6624 8192000000 10015815152 10401226752 1071004528 11.562 100 69 136 0 1137350816 52138496 14207792
08:40:00 9806 8192000000 10031795168 10408431616 1069006144 11.543 99 75 136 2 1151632592 69082176 13544272
08:41:25 15799 8192000000 10068820912 10463576064 1099623744 11.834 100 73 142 1 1197145040 83263776 14257520
08:42:44 3645 8192000000 10140916304 10530041856 1112326608 11.954 100 75 144 0 1253804560 126731488 14746464
08:43:52 10367 8192000000 10179886080 10580402176 1119918128 12.026 100 62 121 0 1292231248 159832928 12480192
08:45:09 3222 8192000000 10151338032 10553470976 1129644464 12.118 100 65 129 0 1272487392 129110352 13732576
08:46:16 4814 8192000000 10215036320 10611224576 1136156944 12.179 100 78 148 2 1330028416 176930192 16941280
08:47:24 1127 8192000000 10227939744 10634522624 1152048640 12.329 100 96 189 2 1338317776 164744688 21524448
08:48:28 1356 8192000000 10221021056 10620813312 1148536768 12.296 100 86 168 1 1332689696 165075136 19077792
08:49:36 786 8192000000 10248885792 10647040000 1156152208 12.367 100 89 167 0 1354001456 180075216 17774032
08:50:39 1321 8192000000 10295248544 10700132352 1157988848 12.384 100 90 174 0 1406520720 229366016 19165856
08:51:43 3804 8192000000 10316374592 10721570816 1167815200 12.476 100 91 182 3 1428075888 238680672 21580016
08:52:49 854 8192000000 10337438208 10751782912 1169389984 12.491 100 95 190 2 1451000544 260375488 21235072
08:53:53 1227 8192000000 10348620064 10768687104 1176399728 12.557 100 97 186 0 1457278976 259367136 21512112
08:54:57 1537 8192000000 10375047776 10795634688 1182925456 12.617 100 96 191 0 1486984224 282471936 21586832
08:56:01 1326 8192000000 10364561504 10791788544 1187895184 12.664 100 90 177 0 1476381888 266840032 21646672
08:57:05 1888 8192000000 10395138880 10809696256 1173185872 12.527 100 98 181 1 1508384992 313446640 21752480
08:58:10 853 8192000000 10426112256 10830266368 1184705888 12.634 100 99 195 0 1530838144 323418640 22713616
08:59:15 1067 8192000000 10481192176 10901114880 1187618192 12.661 100 91 174 2 1586401360 378480096 20303072
09:00:19 1287 8192000000 10475837232 10924822528 1194909568 12.729 100 99 186 1 1577833472 360163312 22760592
09:01:24 720 8192000000 10510143280 10956959744 1206734384 12.839 100 96 185 0 1616570240 386602672 23233184
09:02:27 1160 8192000000 10535974624 10983886848 1215465680 12.920 100 100 195 0 1638378208 398302160 24610368
09:03:30 894 8192000000 10551711056 10987540480 1208044896 12.851 100 96 190 2 1654444656 423550816 22848944
09:04:34 7768 8192000000 10584496304 11033595904 1212848592 12.895 100 66 132 1 1680898336 451078944 16970800
09:05:45 1830 8192000000 10582710640 11028230144 1192021280 12.702 100 73 137 2 1684841616 475949264 16871072
09:07:00 3498 8192000000 10598503504 11030409216 1185526224 12.642 100 53 105 0 1703956672 505062496 13367952
09:08:19 7299 8192000000 10543806176 11015991296 1179881632 12.589 99 50 92 1 1644380032 452695040 11803360
09:09:36 14870 8192000000 10498143840 10960334848 1168601936 12.484 99 43 85 1 1600801216 421817664 10381616
09:10:53 14022 8192000000 10444660304 10931806208 1156069936 12.366 100 27 54 0 1547290672 383594000 7626736
09:12:49 23796 8192000000 10208667632 10696138752 1142784432 12.242 99 22 44 0 1317972112 169964832 5222848
09:14:15 83442 8192000000 10109892336 10614353920 1088098608 11.725 94 13 26 0 1173265472 82082448 3084416
09:16:48 21072 8192000000 10151715744 10588618752 1120975840 12.036 98 48 95 0 1218135984 86737776 10422368
09:18:23 151115 8192000000 10124656864 10587299840 1127951808 12.102 99 34 64 1 1187863200 51985856 7925536
09:21:55 104469 8192000000 10098020384 10528804864 1128810224 12.110 99 52 100 3 1197250112 57817760 10622128
09:24:41 40791 8192000000 10125809120 10584469504 1143041440 12.244 100 31 57 1 1242224464 93145744 6037280
09:26:24 16299 8192000000 10147522544 10596020224 1148413872 12.295 100 53 102 1 1244870064 84697856 11758336
09:27:45 37902 8192000000 10149192928 10662199296 1142012160 12.234 99 38 76 0 1253609632 103258608 8338864
09:29:26 27626 8192000000 10143109824 10649645056 1147923456 12.290 100 11 22 0 1249879440 99499248 2456736
09:31:00 48493 8192000000 10128806480 10596655104 1133283424 12.152 99 13 26 0 1224635424 88351216 3000784
09:33:18 34538 8192000000 10071185216 10535157760 1137393744 12.191 100 31 54 1 1181697280 38676672 5626864
09:34:53 56935 8192000000 10075006768 10560905216 1124222176 12.067 99 18 35 0 1174659008 47064144 3372688
09:36:53 55545 8192000000 10079676592 10534752256 1123607632 12.061 99 8 16 0 1148653952 23135184 1911136
09:39:14 38466 8192000000 10090698912 10576773120 1129319904 12.115 100 26 52 0 1197893632 63428368 5145360
09:40:53 28651 8192000000 10100249568 10537422848 1103450240 11.870 98 40 80 0 1152990336 41383904 8156192
09:42:28 7731 8192000000 10084275248 10516303872 1114956000 11.979 99 52 103 0 1180698432 55519744 10222688
09:44:05 24504 8192000000 10077111712 10552893440 1125478688 12.079 99 31 62 1 1203645616 71324176 6842752
09:45:32 32086 8192000000 10138012208 10579034112 1133599760 12.155 100 44 85 0 1228125856 85291088 9235008
09:47:11 14074 8192000000 10122647616 10556809216 1133749008 12.157 100 52 102 0 1209633648 64984528 10900112
09:48:33 29715 8192000000 10119914880 10559320064 1116933216 11.998 99 40 76 0 1188040064 63057520 8049328
09:50:04 64513 8192000000 10132905792 10598334464 1113119376 11.962 98 20 40 1 1242131616 123841136 5171104
09:52:09 76115 8192000000 10096639728 10590535680 1121033552 12.037 99 14 28 0 1199243600 75304320 2905728
09:54:28 60643 8192000000 10085265360 10518003712 1123310064 12.058 99 42 82 1 1163312192 32324960 7677168
09:56:36 13440 8192000000 10095961984 10525986816 1122128560 12.047 99 51 102 0 1190252992 57722208 10402224
09:57:50 66261 8192000000 10069848256 10565382144 1119840960 12.025 99 29 59 0 1237038320 111097712 6099648
09:59:57 70492 8192000000 10036415072 10499661824 1097018032 11.809 97 11 22 0 1121549152 21740928 2790192
10:02:07 37372 8192000000 10088730720 10541551616 1121686416 12.043 99 26 50 0 1185535264 58593056 5255792
10:03:50 67329 8192000000 10096864800 10566705152 1131999392 12.140 100 16 32 0 1203090448 66797488 4293568
10:06:32 52751 8192000000 10097093120 10557358080 1113746816 11.968 98 11 22 0 1158576704 42755728 2074160
10:08:25 25575 8192000000 10079757424 10519343104 1107250816 11.906 97 42 84 1 1155176480 39674016 8251648
10:10:08 30918 8192000000 10127932416 10560724992 1116428128 11.993 99 42 82 0 1228667952 102907008 9332816
10:11:40 43716 8192000000 10140093744 10593492992 1114881984 11.979 98 27 54 1 1242829472 121680000 6267488
10:13:33 37967 8192000000 10113867152 10571616256 1095308560 11.793 96 26 49 1 1202479584 101007376 6163648
10:15:15 37904 8192000000 10134626816 10568019968 1134306960 12.162 99 37 74 0 1232949024 90726032 7916032
10:16:58 26162 8192000000 10168233296 10602946560 1154319904 12.350 100 38 74 1 1255134928 93089840 7725184
10:18:28 49332 8192000000 10102473472 10538258432 1072822128 11.579 94 26 50 1 1131184224 53250960 5111136
10:20:19 4933 8192000000 10141869424 10567184384 1134128928 12.160 100 57 112 0 1228507616 82905488 11473200
10:22:44 50857 8192000000 10074736960 10611650560 1123237568 12.058 99 22 44 0 1202461472 74383392 4840512
10:24:37 69383 8192000000 10071983648 10550775808 1136515632 12.183 100 7 13 0 1153570656 15529792 1525232
10:26:47 53319 8192000000 10141543472 10603728896 1145950688 12.271 100 32 64 0 1232879408 79894480 7034240
10:28:41 39337 8192000000 10120144400 10565128192 1108622320 11.919 97 26 52 0 1189733920 75291968 5819632
10:30:20 29725 8192000000 10140022464 10612953088 1128974176 12.112 98 34 69 0 1216980192 80085328 7920688
10:32:04 20392 8192000000 10177736720 10623729664 1149528608 12.305 99 36 71 0 1277539856 119273776 8737472
10:33:37 33285 8192000000 10236636864 10668654592 1150722240 12.316 100 47 93 0 1301117584 140223056 10172288
10:35:23 24965 8192000000 10229305424 10660589568 1137075408 12.188 99 50 100 0 1306717088 158912832 10728848
10:36:58 43087 8192000000 9168594672 9502605312 288740480 3.404 26 6 12 0 384201904 94054160 1407264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-12-2022 10:38:47 to 26-12-2022 10:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2022 10:39:05 to 26-12-2022 10:39:07.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          214077
MON$OLDEST_ACTIVE               214078
MON$OLDEST_SNAPSHOT             214078
MON$NEXT_TRANSACTION            214078
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-30 07:30:08.6030 GMT
MON$PAGES                       2067808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:531: 
:532: mscs-test-161.budzdorov.ru    Mon Dec 26 10:37:52 2022
:533:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:534: 
--- end of diff output ---

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

26.12.22 10:39:08. There are 3 logs in /var/tmp/logs-oltp40 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
2022-12-26 10:39:11 No severe PSQL-related problems occured
26.12.22 10:39:12. End of report.