Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.04.2022 22: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 16749100 kB
MemAvailable 31506144 kB
Buffers 0 kB
Cached 14943092 kB
SwapCached 2688 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.10
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 2022-04-11 22:38:09
DTS_END                         2022-04-11 22:38:09.5710
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(57:17)
IP                              ::1/39038
TRN_ID                          238736
ATT_ID                          665
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: 9183.09 21021 1101971
customer order: creation 173.22 369 20786
customer order: refuse 14.65 372 1758
order to supplier: creation 45.08 1266 5410
order to supplier: removal 3.38 103321 405
invoice (draft): creation 46.78 3430 5613
invoice (draft): removal 53.03 814 6364
invoice accept: apply 41.17 17864 4940
invoice accept: cancel 47.86 780 5743
customer reserve: creation 3772.46 177 452695
customer reserve: removal 4688.27 57 562592
realization accept: apply 83.61 179 10033
realization accept: cancel 85.28 113 10234
payment from customer: creation 57.88 104 6945
payment from customer: removal 5.65 91 678
payment to supplier: creation 49.32 122 5918
payment to supplier: removal 3.74 83 449
service: total inventory turnovers 4.82 975 578
service: total monetary turnovers 6.19 28 743
service: aggregate perf. data 0.69 25993 83
service: refresh index statistics 0.03 264285 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-04-2022 22:39:06 to 11-04-2022 22:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3189
TEST_TIME 2 4496
TEST_TIME 3 5251
TEST_TIME 4 5208
TEST_TIME 5 5397
TEST_TIME 6 5006
TEST_TIME 7 4868
TEST_TIME 8 4794
TEST_TIME 9 5319
TEST_TIME 10 5338
TEST_TIME 11 5535
TEST_TIME 12 5913
TEST_TIME 13 6036
TEST_TIME 14 6797
TEST_TIME 15 7141
TEST_TIME 16 7485
TEST_TIME 17 7841
TEST_TIME 18 7628
TEST_TIME 19 7715
TEST_TIME 20 8820
TEST_TIME 21 9006
TEST_TIME 22 8938
TEST_TIME 23 9071
TEST_TIME 24 9352
TEST_TIME 25 10047
TEST_TIME 26 10755
TEST_TIME 27 11040
TEST_TIME 28 10753
TEST_TIME 29 10540
TEST_TIME 30 10605
TEST_TIME 31 10375
TEST_TIME 32 10280
TEST_TIME 33 10139
TEST_TIME 34 10108
TEST_TIME 35 9960
TEST_TIME 36 9926
TEST_TIME 37 9687
TEST_TIME 38 9484
TEST_TIME 39 9532
TEST_TIME 40 9433
TEST_TIME 41 9268
TEST_TIME 42 9351
TEST_TIME 43 9254
TEST_TIME 44 9237
TEST_TIME 45 9465
TEST_TIME 46 9421
TEST_TIME 47 9344
TEST_TIME 48 9449
TEST_TIME 49 9534
TEST_TIME 50 9459
TEST_TIME 51 9564
TEST_TIME 52 9461
TEST_TIME 53 9468
TEST_TIME 54 9599
TEST_TIME 55 9558
TEST_TIME 56 9585
TEST_TIME 57 9487
TEST_TIME 58 9359
TEST_TIME 59 9438
TEST_TIME 60 9421
TEST_TIME 61 9372
TEST_TIME 62 9320
TEST_TIME 63 9325
TEST_TIME 64 9345
TEST_TIME 65 9350
TEST_TIME 66 9346
TEST_TIME 67 9327
TEST_TIME 68 9330
TEST_TIME 69 9431
TEST_TIME 70 9427
TEST_TIME 71 9399
TEST_TIME 72 9465
TEST_TIME 73 9525
TEST_TIME 74 9531
TEST_TIME 75 9531
TEST_TIME 76 9535
TEST_TIME 77 9570
TEST_TIME 78 9510
TEST_TIME 79 9433
TEST_TIME 80 9402
TEST_TIME 81 9361
TEST_TIME 82 9427
TEST_TIME 83 9407
TEST_TIME 84 9365
TEST_TIME 85 9347
TEST_TIME 86 9280
TEST_TIME 87 9264
TEST_TIME 88 9226
TEST_TIME 89 9206
TEST_TIME 90 9217
TEST_TIME 91 9266
TEST_TIME 92 9266
TEST_TIME 93 9293
TEST_TIME 94 9283
TEST_TIME 95 9340
TEST_TIME 96 9350
TEST_TIME 97 9347
TEST_TIME 98 9338
TEST_TIME 99 9385
TEST_TIME 100 9370
TEST_TIME 101 9354
TEST_TIME 102 9484
TEST_TIME 103 9482
TEST_TIME 104 9393
TEST_TIME 105 9330
TEST_TIME 106 9380
TEST_TIME 107 9338
TEST_TIME 108 9292
TEST_TIME 109 9259
TEST_TIME 110 9216
TEST_TIME 111 9255
TEST_TIME 112 9206
TEST_TIME 113 9190
TEST_TIME 114 9201
TEST_TIME 115 9193
TEST_TIME 116 9235
TEST_TIME 117 9241
TEST_TIME 118 9208
TEST_TIME 119 9236
TEST_TIME 120 9185

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-04-2022 22:39:10 to 11-04-2022 22:39:13.

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 1523376 1523376 0 0.00 0 31809 6 0 0
doc_list_biud 1523376 1523376 0 0.00 0 68958 27 0 0
sp_add_invoice_to_stock 5114 4940 174 3.40 25 157791 17865 0 174
sp_cancel_adding_invoice 5743 5743 0 0.00 4 80655 780 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 13123 373 0 0
sp_cancel_customer_reserve 562592 562592 0 0.00 1 68961 58 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 4504 91 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 8169 83 0 0
sp_cancel_supplier_invoice 6379 6364 15 0.23 1 12292 815 0 15
sp_cancel_supplier_order 405 405 0 0.00 7 1176457 103322 0 0
sp_cancel_write_off 10234 10234 0 0.00 1 21825 114 0 0
sp_client_order 20786 20786 0 0.00 3 31982 369 0 0
sp_customer_reserve 452695 452695 0 0.00 0 85628 178 0 0
sp_fill_shopping_cart 31800 31800 0 0.00 1 14718 37 0 0
sp_fill_shopping_cart_clo_res 452690 452690 0 0.00 0 26076 14 0 0
sp_get_clo_for_invoice 4935 4935 0 0.00 0 16146 312 0 0
sp_kill_cost_storno 17104 17104 0 0.00 0 9686 54 0 0
sp_kill_qstorno_ret_qs2qd 1142187 1142187 0 0.00 0 68942 32 0 0
sp_kill_qty_storno 602064 602064 0 0.00 0 68957 69 0 0
sp_lock_selected_doc 20767 20767 0 0.00 0 7055 4 0 0
sp_make_cost_storno 27831 27831 0 0.00 0 31640 58 0 0
sp_multiply_rows_for_pdistr 27831 27831 0 0.00 0 9640 27 0 0
sp_multiply_rows_for_qdistr 20786 20786 0 0.00 0 13706 181 0 0
sp_pay_from_customer 6945 6945 0 0.00 4 14156 104 0 0
sp_pay_to_supplier 5918 5918 0 0.00 4 21251 122 0 0
sp_payment_common 12863 12863 0 0.00 1 10748 26 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 13115 353 0 0
sp_qd_handle_on_invoice_upd_sts 10678 10678 0 0.00 0 36674 346 0 0
sp_qd_handle_on_reserve_upd_sts 10033 10033 0 0.00 0 16633 66 0 0
sp_reserve_write_off 10033 10033 0 0.00 6 19315 180 0 0
sp_supplier_invoice 5613 5613 0 0.00 6 124499 3430 0 0
sp_supplier_order 5410 5410 0 0.00 5 33426 1267 0 0
srv_aggregate_perf_data 83 83 0 0.00 2730 365838 25993 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1523 144078 44864 0 0
srv_find_qd_qs_mism 1055729 1055729 0 0.00 0 8627 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 36 44474 975 0 0
srv_make_money_saldo 743 743 0 0.00 0 2601 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 175363 363329 264286 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4940 4940 0 0.00 0 25577 181 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 7447 132 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 1855 12 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 3712 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 4418 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 675 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1757 31 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 18412 1569 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 5865 28 0 0
t$perf-norm:sp_client_order 20786 20786 0 0.00 0 19786 22 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 5546 11 0 0
t$perf-norm:sp_pay_from_customer 6945 6945 0 0.00 0 8654 12 0 0
t$perf-norm:sp_pay_to_supplier 5918 5918 0 0.00 0 7047 14 0 0
t$perf-norm:sp_reserve_write_off 10033 10033 0 0.00 0 5955 14 0 0
t$perf-norm:sp_supplier_invoice 5613 5613 0 0.00 0 10001 30 0 0
t$perf-norm:sp_supplier_order 5410 5410 0 0.00 0 10346 23 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 151 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2388 46 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 2823 27 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 5585 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 7 0 0
v_add_invoice_to_stock 10218 9870 348 3.40 0 8268 19 0 348
v_all_customers 37842 37842 0 0.00 0 21 1 0 0
v_all_suppliers 30594 30594 0 0.00 0 20 1 0 0
v_all_wares 41572 41572 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2077 10 0 0
v_cancel_client_order 3516 3516 0 0.00 0 1131 3 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2295 9 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 200 3 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 8157 15 0 0
v_cancel_supplier_invoice 832 802 30 3.60 0 1123 17 0 30
v_cancel_supplier_order 810 810 0 0.00 0 384 5 0 0
v_cancel_write_off 2006 2006 0 0.00 0 7497 21 0 0
v_max_id_clo_ord 5410 5410 0 0.00 0 299 1 0 0
v_max_id_clo_res 987 987 0 0.00 0 1702 15 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 3590 4 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 907 3 0 0
v_max_non_paid_realizn 3707 3707 0 0.00 0 3614 6 0 0
v_min_id_clo_ord 5410 5410 0 0.00 0 7236 6 0 0
v_min_id_clo_res 987 987 0 0.00 0 2257 7 0 0
v_min_id_ord_sup 5604 5604 0 0.00 0 4079 6 0 0
v_min_non_paid_invoice 5918 5918 0 0.00 0 7082 14 0 0
v_min_non_paid_realizn 6945 6945 0 0.00 0 10736 23 0 0
v_our_firm 10206 10206 0 0.00 0 13 1 0 0
v_reserve_write_off 20066 20066 0 0.00 0 2454 4 0 0
x_lock_depdocs_on_canc_invoice 11706 11706 0 0.00 0 15789 110 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 12770 388 0 0
x_make_qstorno_1000_1200 5410 5410 0 0.00 1 33412 1186 0 0
x_make_qstorno_1200_2000 5613 5613 0 0.00 3 123171 3299 0 0
x_make_qstorno_2100_3300 452695 452695 0 0.00 0 85626 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-04-2022 22:39:14 to 11-04-2022 22:39:18.

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
20:08:13 2 8192000000 8800818576 8812728320 6849456 0.083 2 1 2 0 6985536 61552 74528
20:09:14 20595 8192000000 9893654640 10205966336 978552976 10.670 100 85 170 0 1007109440 5643136 22913328
20:10:42 23704 8192000000 9955514080 10280583168 1040205504 11.267 100 87 174 1 1069993360 8165392 21622464
20:12:10 17074 8192000000 9998477072 10331250688 1082611200 11.672 100 87 174 0 1113637280 9855984 21170096
20:13:33 13176 8192000000 10032461824 10378989568 1117862624 12.007 100 79 158 0 1147545600 11030992 18651984
20:14:50 8969 8192000000 10093436288 10450223104 1177255104 12.565 100 78 156 0 1207090528 12289456 17545968
20:16:02 5024 8192000000 10130238624 10501115904 1217855632 12.942 100 82 164 0 1248908352 12528576 18524144
20:17:08 4561 8192000000 10163805264 10537041920 1255875616 13.292 100 89 179 0 1289246160 13914320 19456224
20:18:14 2592 8192000000 10196553312 10576035840 1277009504 13.486 100 92 183 2 1311260928 13509696 20741728
20:19:19 1942 8192000000 10203980000 10588454912 1292362992 13.626 100 86 171 1 1324722960 13347696 19012272
20:20:24 4979 8192000000 10196649552 10596962304 1299047360 13.687 100 54 109 1 1322668480 12085328 11535792
20:21:30 3275 8192000000 10198664272 10604445696 1304475952 13.736 100 31 62 1 1319400032 7704048 7220032
20:22:36 5326 8192000000 10197017808 10616606720 1317802688 13.857 100 14 28 0 1326952928 6269568 2880672
20:23:42 58194 8192000000 10211970432 10635063296 1326315392 13.934 100 21 42 0 1335887648 4418352 5153904
20:25:41 3656 8192000000 10236702736 10651377664 1330905712 13.975 100 42 83 2 1352473360 12248832 9318816
20:26:45 7043 8192000000 10205539952 10626215936 1309128544 13.778 99 19 37 1 1319041792 5523072 4390176
20:27:55 18476 8192000000 10204506000 10623406080 1301380640 13.708 98 18 36 0 1310877728 5387312 4109776
20:29:18 10529 8192000000 10169820400 10577805312 1246429680 13.205 96 29 58 1 1262733808 9680416 6623712
20:30:29 7498 8192000000 10157662016 10562260992 1245089888 13.193 98 23 47 1 1256812512 6303712 5418912
20:31:40 7621 8192000000 10158208640 10559041536 1225311216 13.011 96 27 54 1 1237539536 5945392 6282928
20:32:50 54353 8192000000 10144266992 10549313536 1214625248 12.912 96 39 76 2 1229766768 5625120 9516400
20:34:48 5055 8192000000 10175151312 10580512768 1281707312 13.529 100 36 72 2 1297003408 7512352 7783744
20:35:56 8454 8192000000 10200638544 10606022656 1297169392 13.669 100 32 62 3 1314657840 9797600 7690848
20:37:08 8793 8192000000 10194216384 10611077120 1306035360 13.750 100 23 46 0 1317976320 6492288 5448672
20:38:17 24072 8192000000 10232129104 10639163392 1322768304 13.902 100 49 98 2 1339149328 4934224 11446800
20:39:47 16812 8192000000 10243727840 10655698944 1339273776 14.051 100 37 74 1 1357183376 9902464 8007136
20:41:15 14524 8192000000 10274096432 10692308992 1364353648 14.276 100 46 92 0 1388015344 13355232 10306464
20:42:35 3389 8192000000 10295755424 10709872640 1365394864 14.286 99 54 107 2 1394116592 15927712 12794016
20:43:40 21963 8192000000 10290923952 10718679040 1384268160 14.455 100 37 74 1 1412226544 18799328 9159056
20:45:29 26607 8192000000 10309627136 10736619520 1394995408 14.550 100 38 76 2 1427258272 23038240 9224624
20:47:01 7248 8192000000 10326520928 10744356864 1397037392 14.569 100 61 120 0 1438774256 27695968 14040896
20:48:10 13223 8192000000 10333166640 10756829184 1404264768 14.633 100 51 101 0 1445283488 28753984 12264736
20:49:28 4555 8192000000 10355578304 10779070464 1416852992 14.745 100 54 106 1 1466798080 36607936 13337152
20:50:35 3855 8192000000 10369111776 10778107904 1411284848 14.695 100 84 168 0 1473801744 41666912 20849984
20:51:44 1520 8192000000 10368553968 10780389376 1410395200 14.687 100 85 169 1 1480484560 48354624 21734736
20:52:50 11680 8192000000 10374585936 10795130880 1417745888 14.753 100 70 139 1 1486140528 50517552 17877088
20:54:09 4063 8192000000 10362235792 10773798912 1400527680 14.600 100 69 136 1 1463279696 45453504 17298512
20:55:18 8610 8192000000 10346360928 10770976768 1397418768 14.572 100 42 83 2 1458046512 49271552 11356192
20:56:34 3099 8192000000 10363656544 10768740352 1391397456 14.518 100 83 166 1 1460434656 48378576 20658624
20:57:41 19263 8192000000 10343167840 10766839808 1372307168 14.348 99 42 84 1 1444418688 59230928 12880592
20:59:04 21862 8192000000 10325178896 10761240576 1380300784 14.419 100 31 62 1 1438238768 49106384 8831600
21:00:53 19115 8192000000 10310306672 10745159680 1358158544 14.221 100 25 49 1 1415148144 49677632 7311968
21:02:28 16336 8192000000 10317343808 10755100672 1361165536 14.248 100 28 58 2 1420736624 51784608 7786480
21:03:56 36977 8192000000 10287580080 10747744256 1363837824 14.272 100 17 34 2 1409308784 40371744 5099216
21:05:34 47358 8192000000 10257447856 10695024640 1341494608 14.071 99 31 61 0 1365587456 16409392 7683456
21:07:27 9567 8192000000 10260179216 10693828608 1325524304 13.927 98 41 82 1 1344501536 9652320 9324912
21:08:47 28353 8192000000 10259937872 10705682432 1350885936 14.155 100 15 30 0 1365101728 11091872 3123920
21:10:17 61720 8192000000 10253036496 10707255296 1342311280 14.078 99 14 28 0 1355308016 9915856 3080880
21:12:20 39042 8192000000 10266732528 10719223808 1367186144 14.302 100 8 16 1 1378130128 9355968 1588016
21:13:59 84200 8192000000 10279392544 10741985280 1378023008 14.399 100 25 50 2 1391607200 7762176 5822016
21:17:13 71842 8192000000 10267879472 10732281856 1363020736 14.264 99 15 30 0 1378078480 11281200 3776544
21:19:39 44375 8192000000 10278801888 10742661120 1374965216 14.372 100 26 52 0 1399913760 19004752 5943792
21:21:25 73809 8192000000 10297404176 10762293248 1375478736 14.376 99 18 35 0 1400267552 20891072 3897744
21:23:42 67161 8192000000 10305758688 10764742656 1392381248 14.527 100 11 22 0 1406504656 11796016 2327392
21:25:50 91281 8192000000 10307479712 10773581824 1373756384 14.361 98 26 52 2 1395825376 16328064 5740928
21:28:24 29418 8192000000 10315602272 10762088448 1399683952 14.592 100 23 46 0 1415405344 9947232 5774160
21:30:08 47117 8192000000 10319502016 10768732160 1389364480 14.500 99 26 52 0 1408275280 13065616 5845184
21:31:56 50499 8192000000 10303003808 10772037632 1387183728 14.481 99 20 40 1 1409007648 16850896 4973024
21:33:47 70663 8192000000 10285212704 10750525440 1349530080 14.143 97 12 24 0 1370511968 18561808 2420080
21:36:19 43527 8192000000 10289994144 10742059008 1371892336 14.344 99 33 66 2 1391144688 11759984 7492368
21:39:39 51309 8192000000 10269389680 10726428672 1341548880 14.071 97 12 24 0 1360238672 15895136 2794656
21:41:30 36258 8192000000 10267032832 10735087616 1347166896 14.122 99 11 20 1 1365116368 15919872 2029600
21:43:07 58532 8192000000 10250357296 10700460032 1289739104 13.602 94 15 30 0 1304135984 11238640 3158240
21:45:07 58726 8192000000 10269494496 10720784384 1325167008 13.923 97 14 28 0 1344519792 15551792 3800992
21:47:15 66242 8192000000 10259603552 10722836480 1328017248 13.949 98 19 38 0 1347367856 15435776 3914832
21:49:22 75297 8192000000 10270932352 10719158272 1281244816 13.524 93 25 50 0 1309861680 22618944 5997920
21:51:38 104046 8192000000 10269460192 10723819520 1321039328 13.886 98 17 34 0 1338594704 14092896 3462480
21:54:22 78443 8192000000 10269054656 10721128448 1310373600 13.789 96 14 28 0 1329221184 15831328 3016256
21:56:42 105479 8192000000 10268226880 10709368832 1343273024 14.087 99 19 38 0 1355434384 8001760 4159600
21:59:30 12294 8192000000 10275477552 10716672000 1364729824 14.280 100 28 54 1 1383040816 12445680 5865312
22:00:43 62052 8192000000 10263795360 10738356224 1359920288 14.237 99 13 26 0 1379561392 16347792 3293312
22:02:51 39957 8192000000 10261451760 10706124800 1314064896 13.823 96 19 38 0 1327419072 8967152 4387024
22:04:48 67634 8192000000 10245660752 10708926464 1357216992 14.212 100 13 26 1 1368705792 8689568 2799232
22:06:57 70834 8192000000 10261569104 10714054656 1317468464 13.854 96 13 25 2 1334975888 14407968 3099456
22:09:17 61839 8192000000 10291173088 10746781696 1383875680 14.451 100 14 28 0 1394762096 7917840 2968576
22:11:20 13253 8192000000 10292826736 10739875840 1374954224 14.371 99 42 82 4 1391506944 6292656 10260064
22:12:36 58364 8192000000 10301225408 10756173824 1376719680 14.387 99 25 50 0 1397291712 14854048 5717984
22:14:41 38310 8192000000 10285887568 10735824896 1367275520 14.303 99 21 42 0 1381099088 9178768 4644800
22:16:20 21341 8192000000 10315335536 10755276800 1391629392 14.520 100 37 74 0 1410809744 11274176 7906176
22:18:05 34289 8192000000 10293955568 10737594368 1367910496 14.308 99 26 53 0 1383780368 10513520 5356352
22:19:42 82426 8192000000 10304974192 10772795392 1375084640 14.373 99 24 48 1 1398089984 17521328 5484016
22:22:04 144078 8192000000 10296143248 10745704448 1361059520 14.247 97 27 54 0 1382451280 14544000 6847760
22:25:29 64311 8192000000 10270674512 10739929088 1358711344 14.226 98 8 16 0 1368864704 8361392 1791968
22:27:34 63875 8192000000 10279209728 10737856512 1360815072 14.245 98 19 38 0 1374381536 8836576 4729888
22:29:38 76497 8192000000 10268840304 10728308736 1324762400 13.920 96 9 18 0 1339826784 13119056 1945328
22:31:55 56207 8192000000 10285418320 10731577344 1341828624 14.074 97 13 26 1 1352711760 8011168 2871968
22:34:16 91265 8192000000 10258917136 10706743296 1300021504 13.695 95 9 18 0 1314394256 12316560 2056192
22:36:57 39323 8192000000 10237346496 10669457408 1252424128 13.260 93 34 68 1 1271196048 11059424 7712496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-04-2022 22:39:20 to 11-04-2022 22:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-04-2022 22:39:36 to 11-04-2022 22:39:37.

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          236643
MON$OLDEST_ACTIVE               238880
MON$OLDEST_SNAPSHOT             238880
MON$NEXT_TRANSACTION            238880
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                       2014080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:232: 
:233: mscs-test-161.budzdorov.ru    Mon Apr 11 22:38:22 2022
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:235: 
--- end of diff output ---

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

11.04.22 22:39:38. 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
2022-04-11 22:39:41 No severe PSQL-related problems occured
11.04.22 22:39:42. End of report.