Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.04.2022 16:39
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 16816696 kB
MemAvailable 31563896 kB
Buffers 0 kB
Cached 14933516 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 16:38:15
DTS_END                         2022-04-11 16:38:15.1560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35196
TRN_ID                          232514
ATT_ID                          690
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: 8911.37 25866 1069364
customer order: creation 159.17 346 19100
customer order: refuse 13.43 332 1612
order to supplier: creation 42.44 1277 5093
order to supplier: removal 3.51 100881 421
invoice (draft): creation 43.33 3581 5199
invoice (draft): removal 52.57 806 6308
invoice accept: apply 38.08 18407 4570
invoice accept: cancel 46.90 710 5628
customer reserve: creation 3600.60 177 432072
customer reserve: removal 4635.51 59 556261
realization accept: apply 77.86 165 9343
realization accept: cancel 76.15 103 9138
payment from customer: creation 55.34 96 6641
payment from customer: removal 5.45 68 654
payment to supplier: creation 46.68 134 5602
payment to supplier: removal 3.18 66 382
service: total inventory turnovers 4.69 951 563
service: total monetary turnovers 5.68 22 681
service: aggregate perf. data 0.77 18308 92
service: refresh index statistics 0.03 370831 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-04-2022 16:39:41 to 11-04-2022 16:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3560
TEST_TIME 2 3724
TEST_TIME 3 3540
TEST_TIME 4 4618
TEST_TIME 5 4848
TEST_TIME 6 4520
TEST_TIME 7 4497
TEST_TIME 8 4996
TEST_TIME 9 5334
TEST_TIME 10 5781
TEST_TIME 11 5909
TEST_TIME 12 6073
TEST_TIME 13 6342
TEST_TIME 14 6972
TEST_TIME 15 7497
TEST_TIME 16 7714
TEST_TIME 17 7977
TEST_TIME 18 8689
TEST_TIME 19 8850
TEST_TIME 20 8789
TEST_TIME 21 9404
TEST_TIME 22 9374
TEST_TIME 23 10091
TEST_TIME 24 10081
TEST_TIME 25 10381
TEST_TIME 26 10851
TEST_TIME 27 10946
TEST_TIME 28 10782
TEST_TIME 29 10591
TEST_TIME 30 10496
TEST_TIME 31 10799
TEST_TIME 32 10606
TEST_TIME 33 10364
TEST_TIME 34 10263
TEST_TIME 35 10199
TEST_TIME 36 10125
TEST_TIME 37 10187
TEST_TIME 38 10220
TEST_TIME 39 9977
TEST_TIME 40 9845
TEST_TIME 41 9895
TEST_TIME 42 9783
TEST_TIME 43 9719
TEST_TIME 44 9566
TEST_TIME 45 9444
TEST_TIME 46 9437
TEST_TIME 47 9399
TEST_TIME 48 9292
TEST_TIME 49 9388
TEST_TIME 50 9315
TEST_TIME 51 9233
TEST_TIME 52 9345
TEST_TIME 53 9329
TEST_TIME 54 9257
TEST_TIME 55 9193
TEST_TIME 56 9356
TEST_TIME 57 9387
TEST_TIME 58 9311
TEST_TIME 59 9406
TEST_TIME 60 9491
TEST_TIME 61 9431
TEST_TIME 62 9485
TEST_TIME 63 9512
TEST_TIME 64 9522
TEST_TIME 65 9498
TEST_TIME 66 9372
TEST_TIME 67 9309
TEST_TIME 68 9350
TEST_TIME 69 9303
TEST_TIME 70 9233
TEST_TIME 71 9224
TEST_TIME 72 9207
TEST_TIME 73 9136
TEST_TIME 74 9079
TEST_TIME 75 9058
TEST_TIME 76 9047
TEST_TIME 77 8969
TEST_TIME 78 8969
TEST_TIME 79 8954
TEST_TIME 80 8966
TEST_TIME 81 8954
TEST_TIME 82 8972
TEST_TIME 83 9028
TEST_TIME 84 9191
TEST_TIME 85 9138
TEST_TIME 86 9197
TEST_TIME 87 9181
TEST_TIME 88 9209
TEST_TIME 89 9243
TEST_TIME 90 9285
TEST_TIME 91 9205
TEST_TIME 92 9202
TEST_TIME 93 9148
TEST_TIME 94 9172
TEST_TIME 95 9136
TEST_TIME 96 9086
TEST_TIME 97 9044
TEST_TIME 98 9033
TEST_TIME 99 8985
TEST_TIME 100 9105
TEST_TIME 101 9055
TEST_TIME 102 9040
TEST_TIME 103 9121
TEST_TIME 104 9137
TEST_TIME 105 9174
TEST_TIME 106 9134
TEST_TIME 107 9138
TEST_TIME 108 9148
TEST_TIME 109 9170
TEST_TIME 110 9209
TEST_TIME 111 9165
TEST_TIME 112 9092
TEST_TIME 113 9074
TEST_TIME 114 9058
TEST_TIME 115 9010
TEST_TIME 116 8977
TEST_TIME 117 8955
TEST_TIME 118 8985
TEST_TIME 119 8968
TEST_TIME 120 8917

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-04-2022 16:39:44 to 11-04-2022 16:39:48.

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 1469609 1469609 0 0.00 0 30365 6 0 0
doc_list_biud 1469609 1469609 0 0.00 0 56674 29 0 0
sp_add_invoice_to_stock 4709 4570 139 2.95 35 204778 18408 0 139
sp_cancel_adding_invoice 5628 5628 0 0.00 4 47587 710 0 0
sp_cancel_client_order 1612 1612 0 0.00 4 15271 332 0 0
sp_cancel_customer_reserve 556261 556261 0 0.00 1 24867 60 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 2 4868 68 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 3382 67 0 0
sp_cancel_supplier_invoice 6318 6308 10 0.15 1 56729 807 0 10
sp_cancel_supplier_order 421 421 0 0.00 10 1214679 100881 0 0
sp_cancel_write_off 9138 9138 0 0.00 1 10639 103 0 0
sp_client_order 19100 19100 0 0.00 3 37978 346 0 0
sp_customer_reserve 432072 432072 0 0.00 0 79403 177 0 0
sp_fill_shopping_cart 29383 29383 0 0.00 1 19629 44 0 0
sp_fill_shopping_cart_clo_res 432057 432057 0 0.00 0 23752 13 0 0
sp_get_clo_for_invoice 4555 4555 0 0.00 0 20718 341 0 0
sp_kill_cost_storno 15802 15802 0 0.00 0 9373 55 0 0
sp_kill_qstorno_ret_qs2qd 1128389 1128389 0 0.00 0 56673 33 0 0
sp_kill_qty_storno 593266 593266 0 0.00 0 56674 70 0 0
sp_lock_selected_doc 19245 19245 0 0.00 0 7195 4 0 0
sp_make_cost_storno 26141 26141 0 0.00 0 25144 53 0 0
sp_multiply_rows_for_pdistr 26141 26141 0 0.00 0 30351 31 0 0
sp_multiply_rows_for_qdistr 19100 19100 0 0.00 0 37618 161 0 0
sp_pay_from_customer 6641 6641 0 0.00 4 16708 97 0 0
sp_pay_to_supplier 5602 5602 0 0.00 5 33609 135 0 0
sp_payment_common 12243 12243 0 0.00 1 33580 25 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 15262 311 0 0
sp_qd_handle_on_invoice_upd_sts 10183 10183 0 0.00 0 21592 337 0 0
sp_qd_handle_on_reserve_upd_sts 9343 9343 0 0.00 0 16426 57 0 0
sp_reserve_write_off 9343 9343 0 0.00 6 16700 165 0 0
sp_supplier_invoice 5199 5199 0 0.00 6 114415 3582 0 0
sp_supplier_order 5093 5093 0 0.00 5 44616 1278 0 0
srv_aggregate_perf_data 92 92 0 0.00 2269 161676 18308 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1719 140432 51305 0 0
srv_find_qd_qs_mism 1024809 1024809 0 0.00 0 16647 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 22 34730 952 0 0
srv_make_money_saldo 681 681 0 0.00 1 436 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 232910 593386 370831 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4570 4570 0 0.00 0 15803 170 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 0 4012 97 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 1138 10 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 3176 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 10842 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 1197 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 4781 36 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 26480 1394 0 0
t$perf-norm:sp_cancel_write_off 874 874 0 0.00 0 3632 14 0 0
t$perf-norm:sp_client_order 19100 19100 0 0.00 0 10811 16 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 1479 6 0 0
t$perf-norm:sp_pay_from_customer 6641 6641 0 0.00 0 13774 12 0 0
t$perf-norm:sp_pay_to_supplier 5602 5602 0 0.00 0 11672 16 0 0
t$perf-norm:sp_reserve_write_off 9343 9343 0 0.00 0 10090 14 0 0
t$perf-norm:sp_supplier_invoice 5199 5199 0 0.00 0 12117 19 0 0
t$perf-norm:sp_supplier_order 5093 5093 0 0.00 0 10974 16 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 130 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 261 6 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 724 22 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 1148 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9388 9110 278 2.96 0 4822 17 0 278
v_all_customers 34806 34806 0 0.00 0 37 1 0 0
v_all_suppliers 29032 29032 0 0.00 0 36 1 0 0
v_all_wares 38200 38200 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 399 6 0 0
v_cancel_client_order 3224 3224 0 0.00 0 634 3 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 1245 5 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 228 3 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 2513 11 0 0
v_cancel_supplier_invoice 774 754 20 2.58 0 3397 23 0 20
v_cancel_supplier_order 842 842 0 0.00 0 699 5 0 0
v_cancel_write_off 1748 1748 0 0.00 0 3076 15 0 0
v_max_id_clo_ord 5093 5093 0 0.00 0 3411 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 9902 20 0 0
v_max_id_ord_sup 5190 5190 0 0.00 0 4938 6 0 0
v_max_non_paid_invoice 1369 1369 0 0.00 0 1759 4 0 0
v_max_non_paid_realizn 3548 3548 0 0.00 0 3060 4 0 0
v_min_id_clo_ord 5093 5093 0 0.00 0 2351 5 0 0
v_min_id_clo_res 957 957 0 0.00 0 4903 15 0 0
v_min_id_ord_sup 5190 5190 0 0.00 0 16133 12 0 0
v_min_non_paid_invoice 5602 5602 0 0.00 0 33575 25 0 0
v_min_non_paid_realizn 6641 6641 0 0.00 0 4593 13 0 0
v_our_firm 9580 9580 0 0.00 0 36 1 0 0
v_reserve_write_off 18686 18686 0 0.00 0 3044 4 0 0
x_lock_depdocs_on_canc_invoice 11559 11559 0 0.00 0 24842 127 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 13942 353 0 0
x_make_qstorno_1000_1200 5093 5093 0 0.00 1 44606 1199 0 0
x_make_qstorno_1200_2000 5199 5199 0 0.00 3 111622 3409 0 0
x_make_qstorno_2100_3300 432072 432072 0 0.00 0 79402 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-04-2022 16:39:49 to 11-04-2022 16:39:53.

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
14:08:18 2 8192000000 8797886512 8808857600 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:19 34319 8192000000 9904187360 10213048320 981152432 10.695 100 83 166 0 1009567184 6040880 22373872
14:10:57 21270 8192000000 9945076192 10263076864 1021272192 11.084 100 90 180 1 1053376784 8413088 23691504
14:12:24 15417 8192000000 9986600928 10318557184 1066293904 11.517 100 79 159 0 1094718224 8945664 19478656
14:13:50 14543 8192000000 10054256384 10405056512 1137119104 12.188 100 85 170 1 1168304160 11664832 19520224
14:15:08 9505 8192000000 10107431504 10467020800 1184452224 12.632 100 85 168 0 1217290944 13575344 19263376
14:16:19 4832 8192000000 10140210640 10512064512 1230581120 13.059 100 81 162 3 1260893152 12110672 18201360
14:17:26 2970 8192000000 10184792256 10566217728 1268130384 13.404 100 83 164 2 1299099184 13146512 17822288
14:18:30 2040 8192000000 10214429136 10596515840 1293849520 13.639 100 88 176 0 1326230512 12431312 19949680
14:19:34 17717 8192000000 10203068784 10611494912 1302391280 13.717 100 42 84 0 1324145504 11852336 9901888
14:20:55 5043 8192000000 10199200464 10611912704 1310587616 13.791 100 35 72 0 1325979200 7144800 8246784
14:22:01 10381 8192000000 10205121520 10621239296 1318695040 13.865 100 18 36 0 1328813504 6135392 3983072
14:23:14 51856 8192000000 10214181712 10637373440 1325220720 13.924 100 18 36 0 1335418160 5167536 5029904
14:25:11 5592 8192000000 10231501264 10647564288 1330492144 13.972 100 32 64 2 1349021296 11398912 7130240
14:26:17 7311 8192000000 10227076064 10643677184 1330643968 13.973 100 21 42 2 1341936336 5766624 5525744
14:27:26 9455 8192000000 10208783344 10623442944 1274163440 13.460 96 18 36 2 1283272720 5055104 4054176
14:28:39 16230 8192000000 10208231392 10615078912 1269394816 13.416 96 55 107 2 1288905024 6206464 13303744
14:29:57 6846 8192000000 10150518480 10554613760 1213942912 12.906 95 28 56 2 1226177680 6230960 6003808
14:31:06 13456 8192000000 10117700176 10512486400 1157689888 12.382 93 18 36 0 1167182944 4564128 4928928
14:32:22 12910 8192000000 10157166768 10566221824 1270798864 13.429 100 28 56 3 1283511152 5831712 6880576
14:33:36 42908 8192000000 10154595104 10567086080 1240654400 13.152 97 27 54 1 1251908896 4833040 6421456
14:35:19 6752 8192000000 10180416016 10590253056 1283530592 13.545 100 33 66 1 1301403328 9890336 7982400
14:36:27 7751 8192000000 10187971232 10599645184 1293690752 13.638 100 19 38 1 1303516736 5261328 4564656
14:37:37 21462 8192000000 10215823760 10627407872 1306943504 13.758 100 18 36 1 1316422496 5086480 4392512
14:39:41 42226 8192000000 10235006896 10654105600 1331514800 13.981 100 29 58 0 1347003440 9096912 6391728
14:41:32 9224 8192000000 10269437504 10682658816 1358362032 14.223 100 55 110 0 1383195600 12512736 12320832
14:42:45 12148 8192000000 10279633616 10695729152 1369129648 14.319 100 46 93 2 1393071296 13068912 10872736
14:44:07 16444 8192000000 10291507104 10718830592 1385550336 14.466 100 35 71 5 1409396400 15418176 8427888
14:45:28 5165 8192000000 10318732304 10736287744 1394683872 14.548 100 54 108 1 1426592144 18339696 13568576
14:46:41 20925 8192000000 10326933200 10754576384 1405784432 14.646 100 54 108 1 1441346384 22353216 13208736
14:48:05 3552 8192000000 10353738064 10769338368 1413725552 14.717 100 83 166 1 1460632032 26838000 20068480
14:49:15 3360 8192000000 10342039792 10760937472 1402553856 14.618 99 65 129 4 1445977248 27819488 15603904
14:50:23 5185 8192000000 10360123408 10777812992 1421716112 14.788 100 62 124 1 1466916832 29454928 15745792
14:51:33 2872 8192000000 10377977184 10796228608 1425258592 14.819 100 86 170 0 1484096784 37663056 21175136
14:52:42 3296 8192000000 10371453712 10781089792 1408394112 14.670 100 93 184 0 1472732048 41214992 23122944
14:53:49 3563 8192000000 10322258624 10726621184 1367294944 14.303 98 82 164 2 1422664000 35267552 20101504
14:54:55 4240 8192000000 10348816928 10758344704 1379501520 14.412 99 87 174 1 1441681920 39695344 22485056
14:56:05 5084 8192000000 10327791472 10733047808 1359957152 14.237 100 64 127 0 1423786192 47535072 16293968
14:57:15 11921 8192000000 10319637504 10734473216 1369839152 14.326 100 58 117 1 1426224048 40910784 15474112
14:58:34 1718 8192000000 10353971744 10756460544 1381787840 14.433 100 90 182 2 1453644288 48722224 23134224
14:59:38 16187 8192000000 10327582480 10759217152 1377324320 14.393 100 38 77 1 1435723440 47802960 10596160
15:01:07 18775 8192000000 10327265568 10778435584 1378096384 14.400 100 24 48 1 1435646432 51069248 6480800
15:02:28 22016 8192000000 10296835408 10735304704 1336314064 14.024 98 18 36 2 1377985200 36749984 4921152
15:03:56 29785 8192000000 10288731744 10721763328 1328984688 13.958 98 25 50 1 1372342528 36369856 6987984
15:05:27 32717 8192000000 10300834128 10729975808 1353577936 14.180 99 40 79 1 1380834992 17639792 9617264
15:07:20 24887 8192000000 10300960624 10731778048 1371000528 14.336 100 57 116 2 1405185152 19662336 14522288
15:09:15 63028 8192000000 10287789152 10751684608 1368572304 14.314 99 10 16 2 1384964880 14176848 2215728
15:12:36 94976 8192000000 10323060304 10772733952 1406151680 14.650 100 23 46 0 1431432208 19804624 5475904
15:15:12 44010 8192000000 10308396624 10766430208 1401843216 14.611 100 11 22 1 1416900160 12991072 2065872
15:17:06 56035 8192000000 10305563904 10760192000 1403586240 14.627 100 28 56 1 1419253408 8886224 6780944
15:19:05 38515 8192000000 10299255088 10770329600 1394872288 14.549 99 13 26 0 1408800672 10063936 3864448
15:20:44 64774 8192000000 10303370048 10778714112 1407818384 14.665 100 12 24 0 1421394608 10771696 2804528
15:23:01 125825 8192000000 10306347168 10777919488 1386892864 14.478 98 14 28 1 1405000592 14888592 3219136
15:26:25 74042 8192000000 10310586400 10786709504 1414753168 14.726 100 17 34 0 1434478768 15829424 3896176
15:28:48 71392 8192000000 10309346464 10781138944 1395360192 14.554 99 11 22 0 1415353040 17192288 2800560
15:31:00 104915 8192000000 10317954656 10781130752 1409894272 14.683 100 12 24 1 1422969488 10854752 2220464
15:33:52 52122 8192000000 10315811712 10781741056 1397211008 14.570 99 22 44 0 1421100880 18811024 5078848
15:35:48 125939 8192000000 10315833360 10785628160 1382883600 14.442 98 10 20 1 1395837712 10825968 2128144
15:38:55 140432 8192000000 10323352496 10789310464 1359450880 14.232 96 7 13 0 1382692016 22182288 1058848
15:42:17 47993 8192000000 10286864176 10735046656 1340919520 14.066 96 24 48 0 1354839952 8989120 4931312
15:44:09 108565 8192000000 10281895104 10731524096 1358001440 14.219 98 40 79 1 1377164992 10103056 9060496
15:46:58 33436 8192000000 10267476768 10732494848 1371711424 14.342 100 8 16 2 1384280688 10829184 1740080
15:48:45 83612 8192000000 10267634096 10731442176 1376176416 14.382 100 7 14 0 1387743152 9639904 1926832
15:51:29 63453 8192000000 10246392192 10687647744 1333857392 14.002 98 22 44 0 1347734064 8536368 5340304
15:53:34 32139 8192000000 10250794992 10708553728 1350112336 14.148 99 20 40 1 1364097584 9198752 4786496
15:55:21 75327 8192000000 10217705696 10678448128 1276716368 13.483 94 12 23 0 1289632368 10432944 2483056
15:57:37 53424 8192000000 10237305520 10681024512 1291330816 13.616 97 21 41 1 1309106656 12848448 4927392
15:59:37 56024 8192000000 10261880592 10705387520 1304587696 13.737 97 23 46 0 1326259376 15661104 6010576
16:01:37 88559 8192000000 10249207952 10708787200 1326319088 13.934 98 17 34 1 1356691952 25881120 4491744
16:04:06 46137 8192000000 10250055856 10689261568 1306964944 13.759 97 33 66 0 1327693168 13483712 7244512
16:05:54 55177 8192000000 10262315120 10704633856 1331465728 13.980 98 41 82 1 1354393696 13789808 9138160
16:07:49 132491 8192000000 10263130944 10709929984 1337789120 14.037 98 15 30 0 1355593056 14738176 3065760
16:11:32 34930 8192000000 10290644784 10732253184 1378890832 14.407 100 44 88 2 1399163584 10956512 9316240
16:13:09 68596 8192000000 10271345888 10727317504 1369635472 14.324 99 6 12 0 1377994240 7370272 988496
16:15:27 73621 8192000000 10299434192 10754510848 1391233408 14.517 100 16 33 1 1405475728 9964336 4277984
16:17:47 92078 8192000000 10299782944 10771214336 1396108512 14.560 100 13 26 0 1415074784 15479344 3486928
16:20:30 73667 8192000000 10289207680 10749825024 1378937808 14.407 99 13 26 1 1400640640 18157008 3545824
16:22:50 83766 8192000000 10288089856 10749943808 1370751680 14.334 99 9 18 0 1387402688 14758320 1892688
16:25:22 28347 8192000000 10295865968 10737901568 1369236384 14.320 99 40 80 0 1388977360 10902000 8838976
16:26:51 64341 8192000000 10308092720 10760273920 1373867088 14.362 99 36 73 0 1397952208 15978864 8106256
16:29:08 133497 8192000000 10270367328 10721329152 1350920224 14.156 98 9 18 0 1372743264 19934640 1888400
16:32:26 49664 8192000000 10280022608 10740113408 1389342752 14.500 100 11 22 0 1398930400 7415024 2172624
16:34:20 70136 8192000000 10286414960 10745327616 1386037744 14.470 100 10 20 0 1398178688 9861776 2279168
16:36:35 77935 8192000000 10263714624 10720292864 1335827072 14.020 97 15 30 0 1348934576 9066960 4040544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-04-2022 16:39:55 to 11-04-2022 16:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-04-2022 16:40:10 to 11-04-2022 16:40:12.

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          232288
MON$OLDEST_ACTIVE               233066
MON$OLDEST_SNAPSHOT             233066
MON$NEXT_TRANSACTION            233066
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                       2016576
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 ---
:198: 
:199: mscs-test-161.budzdorov.ru    Mon Apr 11 16:38:21 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
--- end of diff output ---

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

11.04.22 16:40:13. 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 16:40:15 No severe PSQL-related problems occured
11.04.22 16:40:17. End of report.