Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.05.2022 22:40
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 16553328 kB
MemAvailable 31517300 kB
Buffers 0 kB
Cached 15192788 kB
SwapCached 2580 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-05-09 22:38:20
DTS_END                         2022-05-09 22:38:20.2060
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/40428
TRN_ID                          230234
ATT_ID                          661
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: 9127.19 25021 1095263
customer order: creation 162.42 367 19490
customer order: refuse 14.03 425 1683
order to supplier: creation 43.93 1315 5272
order to supplier: removal 3.46 103616 415
invoice (draft): creation 43.32 3674 5198
invoice (draft): removal 54.71 837 6565
invoice accept: apply 39.61 18603 4753
invoice accept: cancel 49.47 802 5936
customer reserve: creation 3612.04 186 433445
customer reserve: removal 4821.71 56 578605
realization accept: apply 77.93 199 9351
realization accept: cancel 80.34 118 9641
payment from customer: creation 55.94 124 6713
payment from customer: removal 5.41 82 649
payment to supplier: creation 47.45 148 5694
payment to supplier: removal 3.50 112 420
service: total inventory turnovers 4.60 1163 552
service: total monetary turnovers 6.48 24 777
service: aggregate perf. data 0.83 19219 100
service: refresh index statistics 0.03 349347 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-05-2022 22:40:25 to 09-05-2022 22:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4840
TEST_TIME 2 2910
TEST_TIME 3 3833
TEST_TIME 4 3935
TEST_TIME 5 4814
TEST_TIME 6 5232
TEST_TIME 7 4990
TEST_TIME 8 5088
TEST_TIME 9 5401
TEST_TIME 10 5905
TEST_TIME 11 6025
TEST_TIME 12 6119
TEST_TIME 13 6287
TEST_TIME 14 6356
TEST_TIME 15 6472
TEST_TIME 16 6795
TEST_TIME 17 7205
TEST_TIME 18 7681
TEST_TIME 19 8195
TEST_TIME 20 8394
TEST_TIME 21 8728
TEST_TIME 22 8750
TEST_TIME 23 8821
TEST_TIME 24 8898
TEST_TIME 25 9010
TEST_TIME 26 8763
TEST_TIME 27 9179
TEST_TIME 28 9083
TEST_TIME 29 10878
TEST_TIME 30 10838
TEST_TIME 31 10741
TEST_TIME 32 10542
TEST_TIME 33 10534
TEST_TIME 34 10275
TEST_TIME 35 10204
TEST_TIME 36 10147
TEST_TIME 37 9984
TEST_TIME 38 9883
TEST_TIME 39 9888
TEST_TIME 40 9873
TEST_TIME 41 9781
TEST_TIME 42 9669
TEST_TIME 43 9550
TEST_TIME 44 9550
TEST_TIME 45 9437
TEST_TIME 46 9405
TEST_TIME 47 9239
TEST_TIME 48 9374
TEST_TIME 49 9310
TEST_TIME 50 9615
TEST_TIME 51 9524
TEST_TIME 52 9462
TEST_TIME 53 9441
TEST_TIME 54 9374
TEST_TIME 55 9298
TEST_TIME 56 9413
TEST_TIME 57 9591
TEST_TIME 58 9638
TEST_TIME 59 9574
TEST_TIME 60 9664
TEST_TIME 61 9682
TEST_TIME 62 9745
TEST_TIME 63 9820
TEST_TIME 64 9691
TEST_TIME 65 9634
TEST_TIME 66 9572
TEST_TIME 67 9565
TEST_TIME 68 9503
TEST_TIME 69 9479
TEST_TIME 70 9423
TEST_TIME 71 9393
TEST_TIME 72 9327
TEST_TIME 73 9244
TEST_TIME 74 9253
TEST_TIME 75 9275
TEST_TIME 76 9322
TEST_TIME 77 9289
TEST_TIME 78 9274
TEST_TIME 79 9281
TEST_TIME 80 9273
TEST_TIME 81 9217
TEST_TIME 82 9237
TEST_TIME 83 9278
TEST_TIME 84 9280
TEST_TIME 85 9252
TEST_TIME 86 9325
TEST_TIME 87 9349
TEST_TIME 88 9369
TEST_TIME 89 9417
TEST_TIME 90 9435
TEST_TIME 91 9399
TEST_TIME 92 9469
TEST_TIME 93 9376
TEST_TIME 94 9342
TEST_TIME 95 9335
TEST_TIME 96 9275
TEST_TIME 97 9257
TEST_TIME 98 9256
TEST_TIME 99 9234
TEST_TIME 100 9188
TEST_TIME 101 9161
TEST_TIME 102 9130
TEST_TIME 103 9116
TEST_TIME 104 9080
TEST_TIME 105 9083
TEST_TIME 106 9111
TEST_TIME 107 9170
TEST_TIME 108 9203
TEST_TIME 109 9214
TEST_TIME 110 9204
TEST_TIME 111 9271
TEST_TIME 112 9244
TEST_TIME 113 9291
TEST_TIME 114 9294
TEST_TIME 115 9240
TEST_TIME 116 9184
TEST_TIME 117 9202
TEST_TIME 118 9180
TEST_TIME 119 9184
TEST_TIME 120 9129

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-05-2022 22:40:29 to 09-05-2022 22:40:32.

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 1498429 1498429 0 0.00 0 21370 7 0 0
doc_list_biud 1498429 1498429 0 0.00 0 45135 28 0 0
sp_add_invoice_to_stock 4854 4753 101 2.08 30 166888 18604 0 101
sp_cancel_adding_invoice 5936 5936 0 0.00 4 46841 803 0 0
sp_cancel_client_order 1683 1683 0 0.00 4 26155 426 0 0
sp_cancel_customer_reserve 578605 578605 0 0.00 1 45138 57 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 8616 82 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 10492 112 0 0
sp_cancel_supplier_invoice 6572 6565 7 0.10 2 29354 837 0 7
sp_cancel_supplier_order 415 415 0 0.00 11 994732 103616 0 0
sp_cancel_write_off 9641 9641 0 0.00 1 21496 118 0 0
sp_client_order 19490 19490 0 0.00 3 22341 368 0 0
sp_customer_reserve 433445 433445 0 0.00 0 96379 186 0 0
sp_fill_shopping_cart 29952 29952 0 0.00 1 22467 45 0 0
sp_fill_shopping_cart_clo_res 433443 433443 0 0.00 0 16537 15 0 0
sp_get_clo_for_invoice 4751 4751 0 0.00 0 32219 350 0 0
sp_kill_cost_storno 16646 16646 0 0.00 0 14793 63 0 0
sp_kill_qstorno_ret_qs2qd 1173831 1173831 0 0.00 0 45131 31 0 0
sp_kill_qty_storno 616946 616946 0 0.00 0 45134 68 0 0
sp_lock_selected_doc 19635 19635 0 0.00 0 11151 6 0 0
sp_make_cost_storno 26508 26508 0 0.00 0 17115 63 0 0
sp_multiply_rows_for_pdistr 26508 26508 0 0.00 0 17882 37 0 0
sp_multiply_rows_for_qdistr 19490 19490 0 0.00 0 20614 152 0 0
sp_pay_from_customer 6713 6713 0 0.00 4 20191 124 0 0
sp_pay_to_supplier 5694 5694 0 0.00 5 19480 148 0 0
sp_payment_common 12407 12407 0 0.00 1 19370 36 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 26017 391 0 0
sp_qd_handle_on_invoice_upd_sts 10686 10686 0 0.00 0 26907 346 0 0
sp_qd_handle_on_reserve_upd_sts 9351 9351 0 0.00 0 16140 59 0 0
sp_reserve_write_off 9351 9351 0 0.00 6 19358 200 0 0
sp_supplier_invoice 5198 5198 0 0.00 6 76545 3675 0 0
sp_supplier_order 5272 5272 0 0.00 5 44192 1316 0 0
srv_aggregate_perf_data 100 100 0 0.00 2805 398469 19219 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2356 137747 46731 0 0
srv_find_qd_qs_mism 1050953 1050953 0 0.00 0 21370 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 26 79746 1164 0 0
srv_make_money_saldo 777 777 0 0.00 0 635 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 266576 516586 349347 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4754 4754 0 0.00 0 18502 192 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 6448 113 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 1646 12 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 6778 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1118 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2865 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 351 351 0 0.00 0 962 19 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 24361 1696 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 7044 23 0 0
t$perf-norm:sp_client_order 19490 19490 0 0.00 0 21737 19 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 4242 10 0 0
t$perf-norm:sp_pay_from_customer 6713 6713 0 0.00 0 22859 18 0 0
t$perf-norm:sp_pay_to_supplier 5694 5694 0 0.00 0 3934 10 0 0
t$perf-norm:sp_reserve_write_off 9351 9351 0 0.00 0 20914 13 0 0
t$perf-norm:sp_supplier_invoice 5198 5198 0 0.00 0 21856 19 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 7761 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 387 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 465 13 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 835 19 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 3730 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 30 10 0 0
v_add_invoice_to_stock 9702 9500 202 2.08 0 5029 23 0 202
v_all_customers 35544 35544 0 0.00 0 16 1 0 0
v_all_suppliers 29244 29244 0 0.00 0 19 1 0 0
v_all_wares 38980 38980 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 13478 23 0 0
v_cancel_client_order 3366 3366 0 0.00 0 3915 7 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2202 6 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 333 3 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 10484 25 0 0
v_cancel_supplier_invoice 716 702 14 1.95 0 1808 19 0 14
v_cancel_supplier_order 830 830 0 0.00 0 1425 5 0 0
v_cancel_write_off 1990 1990 0 0.00 0 2643 14 0 0
v_max_id_clo_ord 5269 5269 0 0.00 0 707 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 799 8 0 0
v_max_id_ord_sup 5193 5193 0 0.00 0 2124 2 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 10081 10 0 0
v_max_non_paid_realizn 3445 3445 0 0.00 0 19214 11 0 0
v_min_id_clo_ord 5269 5269 0 0.00 0 20570 26 0 0
v_min_id_clo_res 991 991 0 0.00 0 7709 20 0 0
v_min_id_ord_sup 5193 5193 0 0.00 0 6658 8 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 19220 33 0 0
v_min_non_paid_realizn 6713 6713 0 0.00 0 10444 21 0 0
v_our_firm 9972 9972 0 0.00 0 13 1 0 0
v_reserve_write_off 18702 18702 0 0.00 0 7337 6 0 0
x_lock_depdocs_on_canc_invoice 12150 12150 0 0.00 0 19981 124 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 27969 453 0 0
x_make_qstorno_1000_1200 5272 5272 0 0.00 1 42329 1214 0 0
x_make_qstorno_1200_2000 5198 5198 0 0.00 3 76535 3523 0 0
x_make_qstorno_2100_3300 433445 433445 0 0.00 0 96378 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-05-2022 22:40:33 to 09-05-2022 22:40:37.

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:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:24 34754 8192000000 9897332752 10208706560 975172400 10.637 100 85 170 0 1004506528 6481104 22853024
20:11:04 15997 8192000000 9935249840 10255179776 1019855312 11.071 100 90 180 0 1051868320 8709776 23303232
20:12:33 14523 8192000000 9975232016 10307891200 1058866368 11.446 100 83 166 0 1089273440 9245840 21161232
20:13:53 14543 8192000000 10032091632 10378878976 1115047040 11.980 100 72 144 0 1142728880 10051488 17630352
20:15:12 9990 8192000000 10086891824 10446069760 1174807904 12.542 100 76 152 0 1203999488 12038640 17152944
20:16:24 7623 8192000000 10120538080 10485972992 1207529840 12.846 100 82 162 1 1238668528 13458032 17680656
20:17:34 11758 8192000000 10146966976 10523729920 1240772528 13.153 100 81 162 1 1273983008 15547808 17662672
20:18:48 2019 8192000000 10196180912 10576056320 1281831056 13.530 100 95 192 0 1315677312 12816976 21029280
20:19:52 4615 8192000000 10186414928 10584928256 1293726704 13.638 100 87 173 1 1326555040 12926304 19902032
20:21:06 10190 8192000000 10212172032 10606690304 1303798496 13.730 100 55 110 0 1325917168 9753056 12365616
20:22:17 6738 8192000000 10192949392 10610716672 1315224240 13.833 100 26 53 0 1327276768 6433712 5618816
20:23:25 10135 8192000000 10205966624 10622353408 1324237696 13.915 100 14 28 0 1332884384 5790272 2856416
20:24:39 79205 8192000000 10218494192 10644668416 1332496368 13.990 100 24 47 1 1343897552 5564208 5836976
20:26:59 3491 8192000000 10231425472 10651725824 1338668224 14.045 100 31 62 1 1351803072 5975920 7158928
20:28:04 10845 8192000000 10234502608 10655842304 1340042112 14.058 100 23 46 0 1351703072 6225616 5435344
20:29:15 64882 8192000000 10209937488 10626506752 1266707440 13.391 95 33 66 2 1280138256 4965648 8465168
20:31:21 5761 8192000000 10166007632 10578046976 1250289904 13.241 97 22 44 2 1261602400 5897904 5414592
20:32:31 8375 8192000000 10134867984 10534227968 1223405136 12.993 98 15 30 0 1232071888 5131920 3534832
20:33:41 61572 8192000000 10167724560 10560262144 1242724528 13.171 98 38 75 3 1257068288 5194816 9148944
20:35:43 6849 8192000000 10179166240 10588835840 1285277616 13.561 100 34 68 0 1299256816 6255552 7723648
20:36:51 8036 8192000000 10190199632 10604429312 1299622992 13.692 100 22 44 0 1310613584 5289120 5701472
20:37:59 10686 8192000000 10203096064 10615218176 1310272160 13.789 100 15 30 1 1318335824 4919904 3143760
20:39:13 60602 8192000000 10237682160 10659205120 1341979264 14.075 100 29 58 1 1355753104 6998320 6775520
20:41:16 7837 8192000000 10249834112 10671996928 1348806672 14.137 100 40 78 1 1366103392 7961168 9335552
20:42:48 10302 8192000000 10275890256 10691526656 1360766528 14.244 100 45 89 2 1381935408 11080912 10087968
20:44:01 6718 8192000000 10283355104 10708226048 1372607648 14.350 100 40 79 0 1396244672 14408480 9228544
20:45:21 7296 8192000000 10294759312 10721435648 1382617056 14.440 100 50 98 2 1409783680 15867072 11299552
20:46:58 19972 8192000000 10337947536 10760126464 1401326960 14.607 100 64 128 0 1446351328 29747712 15276656
20:48:36 4615 8192000000 10361220000 10779750400 1417446368 14.750 100 74 146 1 1463028368 28133024 17448976
20:49:45 3486 8192000000 10362932976 10785730560 1420957632 14.781 100 69 138 0 1467008960 30041328 16010000
20:50:57 2973 8192000000 10362258864 10779783168 1423227312 14.801 100 69 139 0 1469385408 29311712 16846384
20:52:06 4038 8192000000 10346527760 10758369280 1372979760 14.354 97 77 154 2 1424770720 32520080 19270880
20:53:14 2453 8192000000 10353764672 10763345920 1394770400 14.548 99 89 178 2 1454260384 37828208 21661776
20:54:22 2356 8192000000 10377883312 10789027840 1412872944 14.709 100 84 168 3 1479423680 45406608 21144128
20:55:28 3174 8192000000 10373128880 10787831808 1406284096 14.651 100 80 161 1 1477559056 50962256 20312704
20:56:35 6108 8192000000 10370334592 10794106880 1398342528 14.580 99 62 124 1 1469848992 55296640 16209824
20:57:44 6005 8192000000 10334139056 10754498560 1358609424 14.225 98 48 95 2 1417660528 46742192 12308912
20:59:04 9528 8192000000 10345105584 10761863168 1386568672 14.475 100 54 108 0 1447957024 47130288 14258064
21:00:16 5869 8192000000 10335278064 10759045120 1383248528 14.446 100 37 74 2 1441281184 47539040 10493616
21:01:28 5980 8192000000 10340392688 10759888896 1382911440 14.443 100 54 108 1 1444754080 47899280 13943360
21:02:40 22990 8192000000 10338062768 10765234176 1379335376 14.411 99 31 60 2 1432400336 44708560 8356400
21:04:14 22519 8192000000 10328323328 10760220672 1378010928 14.399 100 24 48 1 1437351088 52117952 7222208
21:05:38 20370 8192000000 10325863232 10767458304 1348500416 14.134 99 25 50 2 1429773376 73623744 7649216
21:07:05 40595 8192000000 10296760272 10771804160 1336824032 14.029 98 19 38 0 1410599648 67393248 6382368
21:08:48 74055 8192000000 10276108656 10712469504 1331687808 13.982 97 20 41 2 1352107344 16583488 3836048
21:11:09 137747 8192000000 10270390336 10726371328 1369205472 14.320 100 29 58 0 1386288928 10271312 6812144
21:14:31 57290 8192000000 10283162336 10713448448 1350500272 14.152 98 67 134 4 1372456768 5964960 15991536
21:16:32 35412 8192000000 10281341520 10734239744 1375855200 14.379 100 25 50 1 1394873184 13429632 5588352
21:18:21 132339 8192000000 10297303952 10751930368 1372468752 14.349 99 22 44 0 1389388368 11522208 5397408
21:21:39 107630 8192000000 10302030432 10757660672 1363814032 14.272 97 17 34 0 1381597776 13338880 4444864
21:24:33 98450 8192000000 10297029344 10762366976 1387786432 14.486 100 22 44 0 1416740976 23894848 5059696
21:27:15 33236 8192000000 10312121280 10780700672 1394945280 14.550 100 20 40 1 1426178656 26300320 4933056
21:28:57 70269 8192000000 10298636592 10762792960 1394750800 14.548 100 2 4 0 1417144048 22116672 276576
21:31:17 23597 8192000000 10293719040 10748489728 1371077088 14.337 98 28 56 1 1386889168 9650448 6161632
21:32:44 77118 8192000000 10344101312 10793877504 1418447968 14.759 100 32 64 2 1445917600 20308336 7161296
21:35:11 45082 8192000000 10309585280 10785640448 1380955088 14.425 98 11 22 0 1403604816 19867696 2782032
21:37:48 56841 8192000000 10318193456 10781995008 1408169104 14.668 100 15 30 1 1429015120 17341696 3504320
21:39:46 29211 8192000000 10317169536 10762764288 1373774656 14.361 98 31 62 0 1391223424 10343472 7105296
21:41:20 98678 8192000000 10310284544 10757947392 1372359712 14.348 98 23 46 1 1390108928 12392816 5356400
21:44:06 40025 8192000000 10274213072 10735890432 1358848096 14.227 98 21 42 0 1372471056 8681984 4940976
21:45:56 52829 8192000000 10252090464 10706960384 1349831472 14.146 98 10 20 0 1359282464 7560112 1890880
21:47:49 55598 8192000000 10259402688 10712358912 1354499216 14.188 99 18 36 3 1368358176 8941392 4917568
21:49:49 73877 8192000000 10283561760 10730024960 1336098128 14.022 97 16 32 1 1352901744 13131312 3672304
21:52:03 74420 8192000000 10276862240 10735136768 1354220336 14.185 98 41 82 0 1382259136 17689456 10349344
21:54:21 50106 8192000000 10266033904 10710409216 1325702528 13.928 96 29 58 1 1342455744 10037840 6715376
21:56:18 79623 8192000000 10261860064 10726240256 1367291744 14.303 100 14 28 1 1380880080 10143840 3444496
21:58:49 48880 8192000000 10272136704 10715766784 1307468160 13.763 95 19 38 0 1320156672 8201264 4487248
22:00:39 82696 8192000000 10248455280 10690404352 1244560736 13.188 91 20 41 0 1259129248 10317408 4251104
22:03:06 35596 8192000000 10274339152 10710523904 1337133520 14.032 98 33 66 1 1354909040 10401120 7374400
22:04:48 73685 8192000000 10265782400 10720755712 1351599664 14.162 99 23 45 1 1369999264 13409328 4990272
22:07:08 121759 8192000000 10271313264 10717360128 1296088416 13.660 94 15 30 2 1320837408 21211152 3537840
22:10:10 96200 8192000000 10285583072 10748424192 1367398960 14.304 99 11 22 1 1381289552 11701264 2189328
22:12:58 26631 8192000000 10272225440 10717949952 1358963312 14.228 99 26 52 0 1375924064 11361504 5599248
22:14:37 69858 8192000000 10282276512 10742071296 1382813200 14.442 100 10 20 0 1394603472 9455408 2334864
22:16:47 95969 8192000000 10284050016 10751897600 1387266512 14.481 100 8 16 0 1397934624 8788832 1879280
22:19:30 48304 8192000000 10283219536 10742329344 1374582320 14.368 99 21 42 2 1386415936 7416864 4416752
22:21:57 44092 8192000000 10298892144 10752741376 1374639584 14.369 99 18 36 2 1388382272 9762752 3979936
22:23:46 60271 8192000000 10310544000 10775068672 1388869360 14.496 99 22 44 0 1410199744 15563936 5766448
22:25:47 63211 8192000000 10299210336 10755772416 1382153984 14.436 99 14 28 1 1405010576 19562544 3294048
22:27:58 39802 8192000000 10317064384 10767409152 1396581600 14.565 100 30 60 0 1415678832 12940112 6157120
22:29:42 25228 8192000000 10308841744 10747944960 1381150176 14.427 99 48 96 5 1399230528 5689984 12390368
22:31:10 72784 8192000000 10275876080 10720628736 1292288960 13.625 93 20 40 0 1312922352 16419760 4213632
22:33:31 108243 8192000000 10268727776 10722115584 1344936048 14.102 98 30 59 0 1366253152 14114656 7202448
22:36:40 32999 8192000000 10260329808 10719596544 1353684192 14.181 99 9 18 0 1369669984 13485360 2500432
22:38:13 18576 8192000000 9408716368 9724608512 565136464 6.453 42 4 8 0 568490448 2842272 511712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-05-2022 22:40:39 to 09-05-2022 22:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-05-2022 22:40:55 to 09-05-2022 22:40:56.

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          229848
MON$OLDEST_ACTIVE               230606
MON$OLDEST_SNAPSHOT             230606
MON$NEXT_TRANSACTION            230606
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                       2033504
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
--- end of diff output ---

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

09.05.22 22:40:58. 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-05-09 22:41:00 No severe PSQL-related problems occured
09.05.22 22:41:01. End of report.