Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.05.2022 07:48
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 16028516 kB
MemAvailable 31470404 kB
Buffers 0 kB
Cached 15666420 kB
SwapCached 2616 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.2
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-05-12 07:47:07
DTS_END                         2022-05-12 07:47:07.3900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34862
TRN_ID                          222867
ATT_ID                          638
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: 9008.10 24091 1080972
customer order: creation 172.02 420 20642
customer order: refuse 14.99 409 1799
order to supplier: creation 45.10 1273 5412
order to supplier: removal 3.33 186681 400
invoice (draft): creation 45.82 2959 5498
invoice (draft): removal 48.80 1014 5856
invoice accept: apply 42.04 25995 5045
invoice accept: cancel 44.49 1289 5339
customer reserve: creation 3939.49 244 472739
customer reserve: removal 4355.19 113 522623
realization accept: apply 82.69 277 9923
realization accept: cancel 83.45 182 10014
payment from customer: creation 59.17 176 7100
payment from customer: removal 5.73 126 688
payment to supplier: creation 49.49 185 5939
payment to supplier: removal 3.35 140 402
service: total inventory turnovers 4.20 4134 504
service: total monetary turnovers 7.76 385 931
service: aggregate perf. data 0.94 17770 113
service: refresh index statistics 0.04 238038 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-05-2022 07:48:27 to 12-05-2022 07:48:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6253
TEST_TIME 2 6624
TEST_TIME 3 5864
TEST_TIME 4 6346
TEST_TIME 5 6503
TEST_TIME 6 6303
TEST_TIME 7 6298
TEST_TIME 8 6205
TEST_TIME 9 6194
TEST_TIME 10 6184
TEST_TIME 11 6297
TEST_TIME 12 6232
TEST_TIME 13 6396
TEST_TIME 14 6313
TEST_TIME 15 6229
TEST_TIME 16 6216
TEST_TIME 17 6812
TEST_TIME 18 6954
TEST_TIME 19 7005
TEST_TIME 20 7074
TEST_TIME 21 7069
TEST_TIME 22 7255
TEST_TIME 23 7357
TEST_TIME 24 7310
TEST_TIME 25 7517
TEST_TIME 26 7894
TEST_TIME 27 8136
TEST_TIME 28 8274
TEST_TIME 29 8522
TEST_TIME 30 8900
TEST_TIME 31 9213
TEST_TIME 32 9758
TEST_TIME 33 10177
TEST_TIME 34 10413
TEST_TIME 35 10534
TEST_TIME 36 10756
TEST_TIME 37 10656
TEST_TIME 38 10763
TEST_TIME 39 10688
TEST_TIME 40 10499
TEST_TIME 41 10394
TEST_TIME 42 10497
TEST_TIME 43 10573
TEST_TIME 44 10515
TEST_TIME 45 10387
TEST_TIME 46 10395
TEST_TIME 47 10305
TEST_TIME 48 10279
TEST_TIME 49 10358
TEST_TIME 50 10281
TEST_TIME 51 10169
TEST_TIME 52 10077
TEST_TIME 53 9972
TEST_TIME 54 9898
TEST_TIME 55 9785
TEST_TIME 56 9770
TEST_TIME 57 9705
TEST_TIME 58 9671
TEST_TIME 59 9611
TEST_TIME 60 9632
TEST_TIME 61 9572
TEST_TIME 62 9640
TEST_TIME 63 9600
TEST_TIME 64 9590
TEST_TIME 65 9611
TEST_TIME 66 9716
TEST_TIME 67 9680
TEST_TIME 68 9684
TEST_TIME 69 9693
TEST_TIME 70 9751
TEST_TIME 71 9761
TEST_TIME 72 9758
TEST_TIME 73 9752
TEST_TIME 74 9693
TEST_TIME 75 9786
TEST_TIME 76 9733
TEST_TIME 77 9737
TEST_TIME 78 9760
TEST_TIME 79 9836
TEST_TIME 80 9743
TEST_TIME 81 9645
TEST_TIME 82 9606
TEST_TIME 83 9542
TEST_TIME 84 9570
TEST_TIME 85 9517
TEST_TIME 86 9503
TEST_TIME 87 9472
TEST_TIME 88 9431
TEST_TIME 89 9366
TEST_TIME 90 9320
TEST_TIME 91 9341
TEST_TIME 92 9330
TEST_TIME 93 9299
TEST_TIME 94 9309
TEST_TIME 95 9351
TEST_TIME 96 9348
TEST_TIME 97 9328
TEST_TIME 98 9313
TEST_TIME 99 9459
TEST_TIME 100 9425
TEST_TIME 101 9459
TEST_TIME 102 9425
TEST_TIME 103 9421
TEST_TIME 104 9461
TEST_TIME 105 9449
TEST_TIME 106 9419
TEST_TIME 107 9356
TEST_TIME 108 9327
TEST_TIME 109 9329
TEST_TIME 110 9281
TEST_TIME 111 9241
TEST_TIME 112 9223
TEST_TIME 113 9187
TEST_TIME 114 9152
TEST_TIME 115 9127
TEST_TIME 116 9104
TEST_TIME 117 9104
TEST_TIME 118 9102
TEST_TIME 119 9099
TEST_TIME 120 9027

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-05-2022 07:48:31 to 12-05-2022 07:48:34.

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 1517009 1517009 0 0.00 0 17697 14 0 0
doc_list_biud 1517009 1517009 0 0.00 0 25655 44 0 0
sp_add_invoice_to_stock 5233 5045 188 3.59 48 217492 25996 0 188
sp_cancel_adding_invoice 5339 5339 0 0.00 7 106621 1290 0 0
sp_cancel_client_order 1799 1799 0 0.00 6 11027 409 0 0
sp_cancel_customer_reserve 522623 522623 0 0.00 1 17327 113 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 4 3380 126 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 5 4112 141 0 0
sp_cancel_supplier_invoice 5868 5856 12 0.20 3 25691 1015 0 12
sp_cancel_supplier_order 400 400 0 0.00 7 1746389 186681 0 0
sp_cancel_write_off 10014 10014 0 0.00 2 7416 182 0 0
sp_client_order 20642 20642 0 0.00 6 12770 421 0 0
sp_customer_reserve 472739 472739 0 0.00 1 97043 245 0 0
sp_fill_shopping_cart 31546 31546 0 0.00 1 14625 78 0 0
sp_fill_shopping_cart_clo_res 472713 472713 0 0.00 0 26418 18 0 0
sp_get_clo_for_invoice 5005 5005 0 0.00 0 23500 258 0 0
sp_kill_cost_storno 16443 16443 0 0.00 0 7723 78 0 0
sp_kill_qstorno_ret_qs2qd 1061516 1061516 0 0.00 0 25650 48 0 0
sp_kill_qty_storno 560959 560959 0 0.00 0 25654 110 0 0
sp_lock_selected_doc 20742 20742 0 0.00 0 7104 6 0 0
sp_make_cost_storno 27967 27967 0 0.00 0 15126 75 0 0
sp_multiply_rows_for_pdistr 27967 27967 0 0.00 0 8121 39 0 0
sp_multiply_rows_for_qdistr 20642 20642 0 0.00 0 12496 156 0 0
sp_pay_from_customer 7100 7100 0 0.00 6 11353 176 0 0
sp_pay_to_supplier 5939 5939 0 0.00 7 12037 186 0 0
sp_payment_common 13039 13039 0 0.00 2 10489 45 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 10822 333 0 0
sp_qd_handle_on_invoice_upd_sts 10344 10344 0 0.00 0 23821 544 0 0
sp_qd_handle_on_reserve_upd_sts 9923 9923 0 0.00 0 13997 63 0 0
sp_reserve_write_off 9923 9923 0 0.00 9 15358 278 0 0
sp_supplier_invoice 5498 5498 0 0.00 11 66992 2959 0 0
sp_supplier_order 5412 5412 0 0.00 7 35004 1274 0 0
srv_aggregate_perf_data 113 113 0 0.00 2685 205062 17770 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 875 135117 33466 0 0
srv_find_qd_qs_mism 1031926 1031926 0 0.00 0 17676 11 0 0
srv_make_invnt_saldo 504 504 0 0.00 71 103152 4134 0 0
srv_make_money_saldo 931 931 0 0.00 1 16819 385 0 0
srv_recalc_idx_stat 5 5 0 0.00 84375 407584 238038 0 0
t$perf-abend:sp_add_invoice_to_stock 188 188 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 5045 5045 0 0.00 1 12426 927 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 1 7953 468 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 5075 30 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 1908 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 8709 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 2965 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1014 29 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 85427 7687 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 2467 26 0 0
t$perf-norm:sp_client_order 20642 20642 0 0.00 0 14308 39 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 3237 23 0 0
t$perf-norm:sp_pay_from_customer 7100 7100 0 0.00 0 9185 18 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 3063 19 0 0
t$perf-norm:sp_reserve_write_off 9923 9923 0 0.00 0 6710 23 0 0
t$perf-norm:sp_supplier_invoice 5498 5498 0 0.00 0 13267 33 0 0
t$perf-norm:sp_supplier_order 5412 5412 0 0.00 0 2683 31 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 986 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 620 18 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 755 15 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 424 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 184 96 0 0
v_add_invoice_to_stock 10384 10008 376 3.62 0 4902 29 0 376
v_all_customers 37918 37918 0 0.00 0 132 4 0 0
v_all_suppliers 30820 30820 0 0.00 0 103 4 0 0
v_all_wares 41284 41284 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 958 14 0 0
v_cancel_client_order 3598 3598 0 0.00 0 3464 11 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 2822 16 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 5337 16 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4002 21 0 0
v_cancel_supplier_invoice 806 782 24 2.97 0 378 22 0 24
v_cancel_supplier_order 800 800 0 0.00 0 2076 16 0 0
v_cancel_write_off 1972 1972 0 0.00 0 3985 27 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 3558 5 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2067 14 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 4221 7 0 0
v_max_non_paid_invoice 1432 1432 0 0.00 0 1123 9 0 0
v_max_non_paid_realizn 3578 3578 0 0.00 0 2708 7 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 2748 8 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 9681 20 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 3543 7 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 10474 16 0 0
v_min_non_paid_realizn 7100 7100 0 0.00 0 6716 18 0 0
v_our_firm 10410 10410 0 0.00 0 103 4 0 0
v_reserve_write_off 19846 19846 0 0.00 0 4421 11 0 0
x_lock_depdocs_on_canc_invoice 10804 10804 0 0.00 0 17039 141 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 14509 341 0 0
x_make_qstorno_1000_1200 5412 5412 0 0.00 3 34925 1111 0 0
x_make_qstorno_1200_2000 5498 5498 0 0.00 5 66978 2748 0 0
x_make_qstorno_2100_3300 472739 472739 0 0.00 0 97031 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-05-2022 07:48:35 to 12-05-2022 07:48:39.

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
05:17:11 6 8192000000 8798448048 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
05:18:11 30574 8192000000 9727826976 10026786816 804038352 8.937 100 91 182 0 834729024 8124224 22566448
05:19:50 12270 8192000000 9762802704 10074177536 842075136 9.321 100 85 170 0 874536992 12088672 20373184
05:21:05 13042 8192000000 9796502320 10113613824 866154224 9.562 100 88 176 0 905648128 18965728 20528176
05:22:28 6367 8192000000 9853810656 10189991936 912427888 10.021 100 74 144 1 973173040 45264304 15480848
05:23:39 3667 8192000000 9915238592 10257100800 957487440 10.464 100 86 168 1 1031318528 56247552 17583536
05:24:45 1751 8192000000 9963175920 10317586432 994507904 10.825 100 93 176 1 1080120096 67550544 18061648
05:25:49 1710 8192000000 9985372112 10349576192 1012482448 10.999 100 90 176 0 1102950720 73048736 17419536
05:26:53 1015 8192000000 9996266736 10363572224 1042456896 11.288 100 97 188 2 1118918528 57536544 18925088
05:27:55 1037 8192000000 10024883792 10394566656 1055899040 11.417 100 96 188 1 1142734320 68332880 18502400
05:28:59 1521 8192000000 10036020016 10415058944 1060791648 11.464 100 92 181 0 1156793952 77227424 18774880
05:30:05 1144 8192000000 10049748608 10429239296 1070938864 11.561 100 98 190 1 1168507696 78152704 19416128
05:31:07 1205 8192000000 10052364304 10437959680 1076748608 11.616 100 97 186 1 1179840480 83310352 19781520
05:32:10 1287 8192000000 10047235072 10429227008 1081021120 11.657 100 96 184 0 1166919696 67853312 18045264
05:33:13 1045 8192000000 10047698544 10434945024 1084840704 11.694 100 88 173 2 1166424368 63868464 17715200
05:34:16 681 8192000000 10036966352 10424528896 1073490304 11.585 99 88 169 0 1156873776 66730960 16652512
05:35:18 1410 8192000000 10047323760 10440916992 1085695472 11.702 100 78 155 0 1171028384 69110944 16221968
05:36:20 1147 8192000000 10057311072 10452410368 1092133152 11.763 100 92 169 1 1179796544 69521040 18142352
05:37:22 2989 8192000000 10042862592 10437058560 1091200256 11.754 100 69 132 1 1158128816 53528480 13400080
05:38:26 1171 8192000000 10029862416 10417872896 1075029552 11.600 99 87 168 0 1152010688 60530480 16450656
05:39:29 2455 8192000000 10048946640 10444673024 1091552496 11.757 100 80 152 0 1169707472 62416000 15738976
05:40:33 1183 8192000000 10040626656 10421444608 1070007504 11.552 100 93 172 0 1157574032 69009520 18557008
05:41:35 1350 8192000000 10007825632 10385674240 1048543664 11.347 100 93 177 0 1129273856 63181776 17548416
05:42:37 1135 8192000000 9962200560 10330820608 1010858112 10.984 99 90 160 3 1085321760 58897456 15566192
05:43:39 1388 8192000000 10035707344 10413391872 1052575424 11.385 100 99 188 4 1159394896 86234112 20585360
05:44:42 1149 8192000000 9975540064 10342776832 1030339392 11.172 99 97 191 1 1097379248 47508864 19530992
05:45:43 963 8192000000 9991296192 10367942656 1037559520 11.241 100 75 145 1 1109163616 56492496 15111600
05:46:50 1408 8192000000 9991847488 10369290240 1052531856 11.385 100 78 146 4 1113459616 44780544 16147216
05:47:57 7615 8192000000 9980772144 10362462208 1042671136 11.290 98 51 97 1 1100387712 47438256 10278320
05:49:08 2954 8192000000 10024670160 10404347904 1078109552 11.629 100 77 138 0 1142997568 52198784 12689232
05:50:18 28158 8192000000 10064724768 10470875136 1094146224 11.782 100 52 103 0 1183295200 78734320 10414656
05:51:51 1275 8192000000 10112071088 10497851392 1117338496 12.002 100 95 185 1 1232247360 94951616 19957248
05:52:55 2628 8192000000 10135506400 10531635200 1132434112 12.144 100 70 137 2 1252103920 104951632 14718176
05:54:09 1319 8192000000 10196619792 10588389376 1143538752 12.249 100 86 166 2 1301291968 140870256 16882960
05:55:15 3551 8192000000 10198857856 10599157760 1151464464 12.323 100 78 152 1 1315034336 148329632 15240240
05:56:21 943 8192000000 10234120704 10630049792 1161529504 12.418 100 94 178 0 1343806592 162933104 19343984
05:57:26 1104 8192000000 10298923792 10700185600 1170825072 12.505 100 86 170 2 1412628512 222083872 19719568
05:58:34 1142 8192000000 10310443216 10707804160 1175439136 12.548 100 95 176 1 1423190512 227420352 20331024
05:59:40 2427 8192000000 10346058000 10747973632 1184362544 12.631 100 87 170 0 1459026496 255297984 19365968
06:00:49 889 8192000000 10382567664 10784989184 1191686416 12.699 100 94 185 3 1492776688 279096608 21993664
06:01:55 2610 8192000000 10417959632 10823090176 1185417440 12.641 100 82 163 1 1527371760 322557024 19397296
06:03:03 1348 8192000000 10456204288 10872737792 1192414688 12.706 100 94 179 1 1573624288 360186160 21023440
06:04:08 1137 8192000000 10469829216 10921078784 1194489808 12.725 100 92 183 0 1578033536 361309344 22234384
06:05:16 1941 8192000000 10452368400 10862182400 1189425872 12.678 100 90 173 0 1556093920 345986096 20681952
06:06:22 1423 8192000000 10512700432 10925248512 1190001440 12.683 100 85 165 0 1623131024 412288608 20840976
06:07:28 860 8192000000 10535411792 10952372224 1194167840 12.722 100 98 192 0 1642764368 425757568 22838960
06:08:34 2325 8192000000 10550590352 10979512320 1197901040 12.757 100 79 155 2 1655476704 438564160 19011504
06:09:41 2211 8192000000 10540607264 10975473664 1160726816 12.410 98 96 190 1 1637473760 453577568 23169376
06:10:46 3563 8192000000 10573509824 10989338624 1172675488 12.522 100 90 172 1 1677420384 483857264 20887632
06:11:56 978 8192000000 10620534304 11054100480 1185111120 12.638 100 94 185 1 1725118640 516297584 23709936
06:12:59 1098 8192000000 10590894672 11052998656 1184635056 12.633 100 86 171 0 1693342800 487055760 21651984
06:14:01 1263 8192000000 10563400832 11029872640 1190421216 12.687 100 76 142 0 1669429424 460793184 18215024
06:15:07 4964 8192000000 10612977552 11069743104 1191965168 12.702 100 57 112 2 1712807472 506410400 14431904
06:16:52 13244 8192000000 10556968928 11050430464 1168037808 12.478 100 39 78 0 1665647872 486881328 10728736
06:18:10 20498 8192000000 10447261888 10943635456 1156334640 12.369 100 33 66 1 1541657984 376302496 9020848
06:19:32 34100 8192000000 10315819296 10809602048 1157334768 12.378 100 33 66 1 1453314400 287747584 8232048
06:21:09 61104 8192000000 10211918736 10672898048 1132931424 12.149 98 18 35 0 1291428448 154621296 3875728
06:23:14 41235 8192000000 10121279552 10611388416 1146267152 12.274 99 15 30 0 1234782688 85510896 3004640
06:24:56 51805 8192000000 10095886768 10585444352 1157598240 12.381 100 11 22 0 1230906624 71089824 2218560
06:26:48 43769 8192000000 10104242288 10544406528 1150952640 12.318 99 36 71 0 1188755760 30097328 7705792
06:28:33 38542 8192000000 10184340192 10655379456 1165893024 12.458 100 24 48 1 1279039472 107607760 5538688
06:31:10 124056 8192000000 10088773632 10539503616 1075023104 11.600 93 18 33 3 1122049472 43318832 3707536
06:34:39 44124 8192000000 10089393328 10511028224 1092309792 11.765 95 43 84 1 1137682016 36522368 8849856
06:36:24 133220 8192000000 10086059472 10563969024 1085185360 11.697 95 14 26 1 1142061872 53685536 3190976
06:40:28 44306 8192000000 10053616672 10485542912 1087177840 11.716 96 44 74 0 1118537536 23550560 7809136
06:42:26 45802 8192000000 10077348000 10508570624 1132432096 12.144 100 39 78 0 1176348672 35250288 8666288
06:44:13 33343 8192000000 10118557280 10559614976 1117172752 12.000 98 26 51 2 1203171360 80632320 5366288
06:45:47 20757 8192000000 10161508016 10594369536 1143672880 12.250 100 43 85 0 1247686624 94373056 9640688
06:47:16 52663 8192000000 10197241664 10681798656 1128884032 12.111 98 43 86 0 1303853776 165154384 9815360
06:49:58 54539 8192000000 10163225040 10634276864 1130806944 12.129 99 15 30 1 1263604704 129379296 3418464
06:51:58 72668 8192000000 10080980288 10636623872 1120024240 12.027 99 17 34 0 1255627104 131849440 3753424
06:54:17 19777 8192000000 10064629264 10501505024 1123888208 12.064 99 29 54 1 1150775504 21239328 5647968
06:55:44 37252 8192000000 10110764960 10563260416 1117180448 12.000 97 31 60 2 1195443440 71240080 7022912
06:57:40 49554 8192000000 10086114592 10551496704 1122525520 12.051 99 11 22 1 1163288160 38484560 2278080
06:59:30 23714 8192000000 10102693568 10543157248 1133489856 12.154 99 49 92 1 1189918752 46078512 10350384
07:00:53 32458 8192000000 10119154304 10556026880 1125899200 12.083 98 33 66 1 1194689888 62460896 6329792
07:02:53 53947 8192000000 10116716256 10552389632 1130642800 12.127 98 23 46 0 1178791552 42661312 5487440
07:04:48 38072 8192000000 10105780544 10576052224 1121005344 12.036 98 14 28 2 1192098784 67810032 3283408
07:06:31 135117 8192000000 10080291872 10514001920 1103727264 11.873 97 27 54 0 1157316640 48447904 5141472
07:09:46 68200 8192000000 10091844432 10528829440 1107079072 11.905 97 48 94 1 1176068000 59594784 9394144
07:11:56 22848 8192000000 10088934864 10533769216 1135945840 12.177 100 39 74 1 1169124416 25026848 8151728
07:13:24 67887 8192000000 10056025344 10503864320 1095930016 11.799 96 22 45 0 1167535168 67033488 4571664
07:15:33 73024 8192000000 10121615568 10588536832 1148925216 12.299 100 16 32 1 1239848000 87456128 3466656
07:17:46 43067 8192000000 10088961024 10575151104 1145919264 12.271 100 19 39 1 1204065472 53921824 4224384
07:19:34 56134 8192000000 10088822272 10533269504 1090647536 11.749 94 18 35 0 1142346688 47974240 3724912
07:21:32 65259 8192000000 10051213264 10525986816 1100216640 11.840 97 15 30 0 1150437824 46809696 3411488
07:23:39 27892 8192000000 10129758752 10582691840 1148541776 12.296 100 38 76 1 1213762736 57372672 7848288
07:25:07 38025 8192000000 10160792224 10614321152 1126143440 12.085 99 36 69 1 1236480032 102525280 7811312
07:26:45 119906 8192000000 10087934064 10557140992 1097844320 11.817 96 13 25 1 1159186784 58753744 2588720
07:29:46 11937 8192000000 10088067472 10507862016 1119860864 12.026 100 60 113 2 1170521488 39286592 11374032
07:30:58 12058 8192000000 10136856080 10573037568 1137742240 12.194 100 58 116 1 1218517648 68892672 11882736
07:32:13 21213 8192000000 10101942192 10534195200 1138144768 12.198 100 41 82 1 1185710928 39544944 8021216
07:33:36 104692 8192000000 10095632336 10546970624 1143430624 12.248 100 25 50 0 1197098208 48586752 5080832
07:36:22 40835 8192000000 10037412096 10519269376 1084790368 11.693 95 7 14 0 1113424992 27415888 1218736
07:38:03 67145 8192000000 10035712192 10513551360 1075492832 11.605 96 10 20 1 1128479792 50927168 2059792
07:40:11 47150 8192000000 10048959984 10523992064 1112530896 11.956 99 16 32 1 1150716496 34520336 3665264
07:42:16 43230 8192000000 10059011472 10506899456 1102080512 11.857 98 29 58 1 1141345536 33126384 6138640
07:44:00 26441 8192000000 10105058640 10558676992 1108592368 11.919 98 43 86 0 1197137392 79030784 9514240
07:45:28 14357 8192000000 10118693296 10552213504 1120238688 12.029 99 50 101 1 1200817952 70022064 10557200
07:46:42 38529 8192000000 9090902272 9376612352 225419680 2.678 20 9 16 1 277421904 49814608 2187616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 12-05-2022 07:48:41 to 12-05-2022 07:48:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 188 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 12-05-2022 07:48:59 to 12-05-2022 07:49:01.

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          218650
MON$OLDEST_ACTIVE               223177
MON$OLDEST_SNAPSHOT             223177
MON$NEXT_TRANSACTION            223177
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                       2093888
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu May 12 07:47:36 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

12.05.22 07:49:02. 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-05-12 07:49:04 No severe PSQL-related problems occured
12.05.22 07:49:06. End of report.