Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2022 04: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 15267428 kB
MemAvailable 30521516 kB
Buffers 20 kB
Cached 16352512 kB
SwapCached 2132 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-26 04:37:20
DTS_END                         2022-09-26 04:37:20.4520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53776
TRN_ID                          214321
ATT_ID                          660
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: 8437.85 28146 1012542
customer order: creation 159.28 500 19113
customer order: refuse 14.48 447 1737
order to supplier: creation 43.37 1445 5204
order to supplier: removal 3.12 162854 374
invoice (draft): creation 42.91 3572 5149
invoice (draft): removal 46.98 1069 5637
invoice accept: apply 39.59 27286 4751
invoice accept: cancel 42.18 1219 5061
customer reserve: creation 3622.85 260 434742
customer reserve: removal 4151.36 94 498163
realization accept: apply 76.79 322 9215
realization accept: cancel 72.62 159 8714
payment from customer: creation 55.03 201 6604
payment from customer: removal 5.42 164 650
payment to supplier: creation 46.57 217 5588
payment to supplier: removal 3.24 129 389
service: total inventory turnovers 3.61 4341 433
service: total monetary turnovers 7.53 361 904
service: aggregate perf. data 0.92 15674 110
service: refresh index statistics 0.03 342599 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2022 04:38:34 to 26-09-2022 04:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5631
TEST_TIME 2 5860
TEST_TIME 3 5903
TEST_TIME 4 5448
TEST_TIME 5 5202
TEST_TIME 6 5275
TEST_TIME 7 5372
TEST_TIME 8 5961
TEST_TIME 9 6548
TEST_TIME 10 6744
TEST_TIME 11 6968
TEST_TIME 12 7197
TEST_TIME 13 7359
TEST_TIME 14 7717
TEST_TIME 15 7839
TEST_TIME 16 8197
TEST_TIME 17 8335
TEST_TIME 18 8484
TEST_TIME 19 8815
TEST_TIME 20 9034
TEST_TIME 21 9276
TEST_TIME 22 9046
TEST_TIME 23 9209
TEST_TIME 24 9124
TEST_TIME 25 9190
TEST_TIME 26 9230
TEST_TIME 27 9260
TEST_TIME 28 9523
TEST_TIME 29 9924
TEST_TIME 30 10044
TEST_TIME 31 10143
TEST_TIME 32 10279
TEST_TIME 33 10537
TEST_TIME 34 10516
TEST_TIME 35 10486
TEST_TIME 36 10351
TEST_TIME 37 10249
TEST_TIME 38 10138
TEST_TIME 39 10033
TEST_TIME 40 10017
TEST_TIME 41 9853
TEST_TIME 42 9721
TEST_TIME 43 9655
TEST_TIME 44 9525
TEST_TIME 45 9495
TEST_TIME 46 9516
TEST_TIME 47 9405
TEST_TIME 48 9331
TEST_TIME 49 9235
TEST_TIME 50 9314
TEST_TIME 51 9172
TEST_TIME 52 9216
TEST_TIME 53 9136
TEST_TIME 54 9001
TEST_TIME 55 8977
TEST_TIME 56 8878
TEST_TIME 57 8893
TEST_TIME 58 8800
TEST_TIME 59 8929
TEST_TIME 60 8834
TEST_TIME 61 8766
TEST_TIME 62 8736
TEST_TIME 63 8768
TEST_TIME 64 8688
TEST_TIME 65 8702
TEST_TIME 66 8707
TEST_TIME 67 8678
TEST_TIME 68 8641
TEST_TIME 69 8745
TEST_TIME 70 8723
TEST_TIME 71 8682
TEST_TIME 72 8647
TEST_TIME 73 8728
TEST_TIME 74 8862
TEST_TIME 75 8856
TEST_TIME 76 8846
TEST_TIME 77 8927
TEST_TIME 78 8944
TEST_TIME 79 8850
TEST_TIME 80 8766
TEST_TIME 81 8851
TEST_TIME 82 8756
TEST_TIME 83 8729
TEST_TIME 84 8744
TEST_TIME 85 8745
TEST_TIME 86 8719
TEST_TIME 87 8666
TEST_TIME 88 8622
TEST_TIME 89 8586
TEST_TIME 90 8546
TEST_TIME 91 8585
TEST_TIME 92 8573
TEST_TIME 93 8587
TEST_TIME 94 8580
TEST_TIME 95 8582
TEST_TIME 96 8592
TEST_TIME 97 8622
TEST_TIME 98 8632
TEST_TIME 99 8654
TEST_TIME 100 8681
TEST_TIME 101 8721
TEST_TIME 102 8694
TEST_TIME 103 8700
TEST_TIME 104 8729
TEST_TIME 105 8689
TEST_TIME 106 8666
TEST_TIME 107 8618
TEST_TIME 108 8611
TEST_TIME 109 8562
TEST_TIME 110 8516
TEST_TIME 111 8472
TEST_TIME 112 8413
TEST_TIME 113 8400
TEST_TIME 114 8380
TEST_TIME 115 8497
TEST_TIME 116 8467
TEST_TIME 117 8466
TEST_TIME 118 8444
TEST_TIME 119 8467
TEST_TIME 120 8453

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-09-2022 04:38:37 to 26-09-2022 04:38:41.

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 1412417 1412417 0 0.00 0 35235 14 0 0
doc_list_biud 1412407 1412407 0 0.00 0 38822 41 0 0
sp_add_invoice_to_stock 4834 4751 83 1.71 89 249690 27286 0 83
sp_cancel_adding_invoice 5061 5061 0 0.00 6 83294 1219 0 0
sp_cancel_client_order 1737 1737 0 0.00 5 8238 448 0 0
sp_cancel_customer_reserve 498163 498163 0 0.00 1 38837 95 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 4 5635 165 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 2412 129 0 0
sp_cancel_supplier_invoice 5644 5637 7 0.12 3 37963 1069 0 7
sp_cancel_supplier_order 374 374 0 0.00 12 1277846 162855 0 0
sp_cancel_write_off 8714 8714 0 0.00 2 8019 159 0 0
sp_client_order 19113 19113 0 0.00 6 29001 500 0 0
sp_customer_reserve 434742 434742 0 0.00 1 37525 260 0 0
sp_fill_shopping_cart 29462 29462 0 0.00 1 51466 105 0 0
sp_fill_shopping_cart_clo_res 434711 434711 0 0.00 0 15257 20 0 0
sp_get_clo_for_invoice 4710 4710 0 0.00 0 36927 354 0 0
sp_kill_cost_storno 14814 14814 0 0.00 0 35219 76 0 0
sp_kill_qstorno_ret_qs2qd 1011051 1011051 0 0.00 0 38792 43 0 0
sp_kill_qty_storno 533609 533609 0 0.00 0 38821 99 0 0
sp_lock_selected_doc 19308 19308 0 0.00 0 8989 8 0 0
sp_make_cost_storno 26116 26116 0 0.00 0 33615 99 0 0
sp_multiply_rows_for_pdistr 26115 26115 0 0.00 0 12829 42 0 0
sp_multiply_rows_for_qdistr 19113 19113 0 0.00 0 13720 177 0 0
sp_pay_from_customer 6604 6604 0 0.00 5 26424 202 0 0
sp_pay_to_supplier 5588 5588 0 0.00 6 31430 218 0 0
sp_payment_common 12192 12192 0 0.00 2 7920 56 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 1 8193 363 0 0
sp_qd_handle_on_invoice_upd_sts 9771 9771 0 0.00 0 23805 590 0 0
sp_qd_handle_on_reserve_upd_sts 9213 9213 0 0.00 0 14389 76 0 0
sp_reserve_write_off 9215 9215 0 0.00 9 17322 322 0 0
sp_supplier_invoice 5149 5149 0 0.00 8 98011 3572 0 0
sp_supplier_order 5204 5204 0 0.00 7 50494 1446 0 0
srv_aggregate_perf_data 110 110 0 0.00 2224 183944 15674 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 878 134227 38524 0 0
srv_find_qd_qs_mism 966404 966404 0 0.00 0 15964 10 0 0
srv_make_invnt_saldo 433 433 0 0.00 68 72177 4341 0 0
srv_make_money_saldo 904 904 0 0.00 1 10529 362 0 0
srv_recalc_idx_stat 4 4 0 0.00 100887 497092 342600 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 4752 4752 0 0.00 1 18397 1139 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 1 6278 520 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 1414 31 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 677 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 490 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 207 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 715 32 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 94107 7349 0 0
t$perf-norm:sp_cancel_write_off 889 889 0 0.00 0 2940 27 0 0
t$perf-norm:sp_client_order 19113 19113 0 0.00 0 5920 41 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 362 13 0 0
t$perf-norm:sp_pay_from_customer 6604 6604 0 0.00 0 7932 23 0 0
t$perf-norm:sp_pay_to_supplier 5588 5588 0 0.00 0 4713 24 0 0
t$perf-norm:sp_reserve_write_off 9215 9215 0 0.00 0 17079 27 0 0
t$perf-norm:sp_supplier_invoice 5149 5149 0 0.00 0 7002 34 0 0
t$perf-norm:sp_supplier_order 5204 5204 0 0.00 0 7185 36 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 2733 58 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 352 9 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 555 11 0 0
t$perf-norm:srv_make_money_saldo 904 904 0 0.00 0 2681 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 195 69 0 0
v_add_invoice_to_stock 9580 9414 166 1.73 0 4839 25 0 166
v_all_customers 34580 34580 0 0.00 0 66 4 0 0
v_all_suppliers 28670 28670 0 0.00 0 110 5 0 0
v_all_wares 38226 38226 0 0.00 0 94 5 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 357 16 0 0
v_cancel_client_order 3474 3474 0 0.00 0 2977 11 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 4302 20 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 3828 15 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 211 11 0 0
v_cancel_supplier_invoice 714 700 14 1.96 0 932 21 0 14
v_cancel_supplier_order 748 748 0 0.00 0 4386 16 0 0
v_cancel_write_off 1778 1778 0 0.00 0 2146 28 0 0
v_max_id_clo_ord 5204 5204 0 0.00 0 6480 7 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2676 15 0 0
v_max_id_ord_sup 5145 5145 0 0.00 0 3841 8 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 1292 9 0 0
v_max_non_paid_realizn 3580 3580 0 0.00 0 2745 10 0 0
v_min_id_clo_ord 5204 5204 0 0.00 0 2653 10 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 2119 11 0 0
v_min_id_ord_sup 5145 5145 0 0.00 0 3316 10 0 0
v_min_non_paid_invoice 5588 5588 0 0.00 0 7267 24 0 0
v_min_non_paid_realizn 6604 6604 0 0.00 0 7899 20 0 0
v_our_firm 9694 9694 0 0.00 0 80 5 0 0
v_reserve_write_off 18426 18426 0 0.00 0 4300 12 0 0
x_lock_depdocs_on_canc_invoice 10348 10348 0 0.00 0 12297 143 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 6903 398 0 0
x_make_qstorno_1000_1200 5204 5204 0 0.00 2 50482 1245 0 0
x_make_qstorno_1200_2000 5149 5149 0 0.00 4 90128 3276 0 0
x_make_qstorno_2100_3300 434742 434742 0 0.00 0 37523 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2022 04:38:42 to 26-09-2022 04:38:46.

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
02:07:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 22682 8192000000 9732196192 10028027904 800481632 8.901 100 86 172 0 828854256 7483952 20888672
02:09:55 25099 8192000000 9759633552 10064367616 831776880 9.217 100 90 180 0 866131776 12615232 21739664
02:11:24 7969 8192000000 9792525136 10110951424 864413168 9.544 100 83 167 0 904929376 21490128 19026080
02:12:39 9901 8192000000 9850749504 10185383936 913994848 10.037 100 76 152 0 968798832 37844752 16959232
02:13:52 3651 8192000000 9907660672 10251206656 962218192 10.511 100 83 166 0 1025300000 45110624 17971184
02:14:59 2769 8192000000 9964984288 10319003648 998176848 10.861 100 83 157 2 1087499632 73952864 15369920
02:16:04 1379 8192000000 10000051296 10362650624 1023825952 11.109 100 90 178 1 1115792032 74535824 17430256
02:17:07 1295 8192000000 10010497600 10381197312 1045351440 11.316 100 92 176 1 1130009568 66830608 17827520
02:18:10 933 8192000000 10037068400 10412437504 1060139296 11.458 100 98 190 2 1156148896 77152368 18857232
02:19:13 3082 8192000000 10024178880 10399846400 1071293856 11.564 100 93 171 2 1144699056 56882944 16522256
02:20:17 868 8192000000 10038085728 10423754752 1073524160 11.586 100 83 164 0 1158390640 68326688 16539792
02:21:18 5906 8192000000 10057249280 10444693504 1078724752 11.635 100 77 147 2 1186594016 94400640 13468624
02:22:25 3445 8192000000 10048980816 10440212480 1081892544 11.666 100 77 150 3 1162200656 65848704 14459408
02:23:30 1178 8192000000 10075530368 10464411648 1088054720 11.724 100 93 175 3 1198102976 91826176 18222080
02:24:32 991 8192000000 10044038288 10431717376 1087981232 11.723 100 82 155 4 1163515344 58708256 16825856
02:25:34 1032 8192000000 10064448032 10457542656 1100450768 11.842 100 94 174 0 1186319392 66508928 19359696
02:26:35 1425 8192000000 10055072176 10446237696 1096236944 11.802 100 78 147 4 1173533056 62278432 15017680
02:27:38 1158 8192000000 10041484560 10437124096 1102774144 11.864 100 85 157 2 1166437712 43630416 20033152
02:28:40 6857 8192000000 10046758864 10441232384 1090224304 11.745 100 72 139 3 1163644640 58481568 14938768
02:29:48 4784 8192000000 10019731552 10410172416 1071593584 11.567 100 58 108 2 1139135584 57056656 10485344
02:30:54 1031 8192000000 10016858992 10393784320 1059014016 11.447 99 95 178 3 1137183440 61074000 17095424
02:31:57 1133 8192000000 10008997632 10382438400 1032948128 11.197 98 94 174 0 1129967712 78309952 18709632
02:32:59 2249 8192000000 9992799760 10375700480 1040481568 11.269 99 70 128 1 1104198416 50910800 12806048
02:34:02 4329 8192000000 9979801680 10365149184 1049339408 11.354 99 77 146 1 1114541264 49411600 15790256
02:35:08 1257 8192000000 9982684688 10358652928 1021015488 11.082 99 81 156 2 1113013984 75547248 16451248
02:36:11 1254 8192000000 9988365072 10360680448 1038813952 11.253 100 99 195 3 1110802048 51531568 20456528
02:37:13 1601 8192000000 9971208624 10349400064 1049919424 11.360 100 74 130 5 1094459024 31876528 12663072
02:38:22 5825 8192000000 10021870256 10397155328 1064432048 11.499 100 86 154 3 1136073648 56494352 15147248
02:39:36 1137 8192000000 10050761296 10430410752 1077212864 11.621 100 89 168 1 1168694000 71447072 20034064
02:40:42 9530 8192000000 10068543520 10460643328 1092188768 11.763 100 70 137 1 1182693072 76438208 14066096
02:42:00 7550 8192000000 10102053072 10502852608 1098660544 11.825 100 66 128 0 1218986672 105874176 14451952
02:43:12 3267 8192000000 10134292352 10535571456 1113118080 11.962 100 71 134 0 1254805792 127069136 14618576
02:44:21 8066 8192000000 10170247936 10579988480 1126872048 12.092 100 70 129 1 1286499872 145344752 14283072
02:45:31 5416 8192000000 10206421936 10619887616 1140339632 12.219 100 64 125 0 1323745312 169616944 13788736
02:46:40 1089 8192000000 10201801616 10612191232 1150879360 12.318 100 95 181 4 1320315120 149732080 19703680
02:47:43 1225 8192000000 10253239328 10654011392 1160310304 12.406 100 96 183 2 1364477600 184704816 19462480
02:48:47 1136 8192000000 10278906560 10676645888 1151481216 12.323 99 92 182 0 1390219232 217844368 20893648
02:49:52 1514 8192000000 10295288640 10701447168 1168453552 12.482 100 94 187 0 1403047520 213404144 21189824
02:50:57 877 8192000000 10300929328 10723598336 1167001088 12.469 100 97 185 1 1413199232 225588048 20610096
02:52:01 2488 8192000000 10332946384 10745827328 1174124112 12.535 100 83 157 3 1444583584 252889104 17570368
02:53:06 1024 8192000000 10321784112 10749505536 1163551888 12.437 100 95 183 2 1426818848 243358208 19908752
02:54:16 936 8192000000 10312894896 10739441664 1166817760 12.467 100 91 163 0 1420340128 235649872 17872496
02:55:21 985 8192000000 10343668976 10786222080 1168812432 12.486 100 97 185 3 1453701216 262954896 21933888
02:56:25 1490 8192000000 10334062464 10793607168 1172040368 12.516 100 98 192 0 1443208016 249440976 21726672
02:57:29 1264 8192000000 10316429216 10741235712 1162758464 12.429 100 95 174 0 1421843600 239742976 19342160
02:58:32 4565 8192000000 10295897728 10733428736 1168382480 12.482 100 55 110 0 1407709280 226352528 12974272
02:59:54 1320 8192000000 10338207104 10765119488 1170250880 12.499 100 82 158 1 1450987664 261216272 19520512
03:01:00 842 8192000000 10345557648 10757881856 1167678304 12.475 100 93 177 2 1452225696 261778688 22768704
03:02:04 889 8192000000 10375896784 10802397184 1163034816 12.432 100 89 178 0 1479531120 295728432 20767872
03:03:09 1728 8192000000 10348429264 10800209920 1159745232 12.401 100 72 141 1 1455099584 278411312 16943040
03:04:16 1258 8192000000 10406568752 10831859712 1166342256 12.463 100 90 173 0 1508789168 322370704 20076208
03:05:21 3863 8192000000 10371087888 10848624640 1148527152 12.296 100 64 126 0 1469683200 304543680 16612368
03:06:31 20871 8192000000 10283142208 10725142528 1133926080 12.158 98 33 67 0 1380958848 238694224 8338544
03:08:16 39032 8192000000 10225632144 10695430144 1147961616 12.290 100 23 46 0 1341540032 188172032 5406384
03:09:57 24503 8192000000 10138209200 10625019904 1124840544 12.073 99 34 67 0 1261284176 128570544 7873088
03:11:23 40623 8192000000 10165358016 10592149504 1133705040 12.156 99 47 88 1 1248244688 105033072 9506576
03:13:06 44271 8192000000 10148983744 10570571776 1120829136 12.035 98 53 105 1 1233644992 101290352 11525504
03:14:59 76680 8192000000 10110412992 10570059776 1125398960 12.078 100 20 38 0 1225247904 95977104 3871840
03:17:20 31318 8192000000 10076193936 10564710400 1126329328 12.087 100 19 34 1 1183501920 53883792 3288800
03:18:53 30895 8192000000 10115064224 10560192512 1140967536 12.225 100 55 100 1 1196340336 44805792 10567008
03:20:31 73628 8192000000 10141412032 10590060544 1135555024 12.174 99 18 35 1 1218192864 78764576 3873264
03:22:57 76701 8192000000 10116464592 10629484544 1129940816 12.121 99 19 39 0 1248611456 114024512 4646128
03:25:17 94621 8192000000 10155396064 10617757696 1118710448 12.015 98 19 38 0 1245382384 122259088 4412848
03:28:04 114903 8192000000 10047766816 10517155840 1104247504 11.878 98 23 46 0 1192673616 83267312 5158800
03:31:06 12786 8192000000 10075987280 10517372928 1132028336 12.140 100 26 52 0 1181448608 43898400 5521872
03:32:28 85086 8192000000 10169513760 10624364544 1126323424 12.087 98 29 56 0 1240590256 108172304 6094528
03:34:55 87734 8192000000 10156600912 10643783680 1122461472 12.050 98 16 33 0 1253305728 126939008 3905248
03:37:28 97738 8192000000 10091724048 10568261632 1127712576 12.100 99 31 60 0 1195487456 60935616 6839264
03:40:24 84564 8192000000 10047846768 10512900096 1107290352 11.907 97 7 12 0 1136810624 28483936 1036336
03:42:49 134227 8192000000 10082555984 10527842304 1064129328 11.496 93 13 26 0 1099542560 33164080 2249152
03:46:03 50291 8192000000 10083591760 10597945344 1134663696 12.165 100 33 66 0 1253558128 111850176 7044256
03:47:56 19365 8192000000 10078106000 10500218880 1102113376 11.858 97 43 86 0 1160729040 50121440 8494224
03:49:17 31933 8192000000 10163916320 10601787392 1118600608 12.014 99 54 106 1 1247609360 117876080 11132672
03:52:14 119168 8192000000 10098780256 10593497088 1122235808 12.048 99 18 34 0 1196502160 70258624 4007728
03:55:13 121915 8192000000 10148920320 10624364544 1138053520 12.197 100 15 30 1 1275253936 133694224 3506192
03:59:12 52165 8192000000 10114128720 10548912128 1131110368 12.132 99 37 70 0 1206891680 67678768 8102544
04:01:06 29114 8192000000 10088332752 10546966528 1115745392 11.987 98 24 47 1 1184434432 63884176 4804864
04:02:39 30834 8192000000 10062352496 10527068160 1110134816 11.934 98 13 21 0 1149748384 37533824 2079744
04:04:10 50761 8192000000 10078719840 10543820800 1131759632 12.138 100 12 22 0 1190211024 56009776 2441616
04:06:02 77178 8192000000 10110124912 10588844032 1136418384 12.182 100 12 24 0 1189266512 50312352 2535776
04:08:19 104959 8192000000 10087310288 10610659328 1124715072 12.072 98 11 21 0 1246279856 119378448 2186336
04:11:04 88574 8192000000 10117099712 10573455360 1100604064 11.843 96 24 48 0 1182970464 77262768 5103632
04:13:34 48338 8192000000 10107750864 10562662400 1089379376 11.737 96 29 56 0 1171970256 77087568 5503312
04:15:29 50054 8192000000 10102844176 10560110592 1109893696 11.931 98 32 62 1 1239019376 122682304 6443376
04:17:24 18607 8192000000 10076403616 10497527808 1120598832 12.033 100 47 86 4 1161647776 31175616 9873328
04:18:43 34742 8192000000 10086863072 10515861504 1105318624 11.888 98 32 64 0 1166915408 55368544 6228240
04:20:27 9385 8192000000 10148185584 10567647232 1116851712 11.997 99 65 128 0 1226522592 96866160 12804720
04:21:43 80085 8192000000 10084799728 10541826048 1130523680 12.126 100 31 60 0 1172528336 35833424 6171232
04:24:12 27350 8192000000 10056729184 10504912896 1120998128 12.036 99 41 80 2 1155481120 26673792 7809200
04:25:46 78833 8192000000 10084166464 10560192512 1124956224 12.074 99 11 22 0 1166850352 39447056 2447072
04:28:12 39418 8192000000 10118328400 10559082496 1136865296 12.186 99 15 28 1 1207207696 66908384 3434016
04:30:34 62083 8192000000 10152877344 10632126464 1140374016 12.219 100 14 28 0 1274126544 130375552 3376976
04:32:36 55998 8192000000 10051493808 10498842624 1113386224 11.964 98 9 19 0 1148594592 33293520 1914848
04:34:42 33886 8192000000 10093421264 10534002688 1121540032 12.042 98 22 44 0 1179993856 53480368 4973456
04:36:19 64840 8192000000 9708217520 10124058624 762673808 8.517 67 15 30 1 851846528 85946464 3226256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-09-2022 04:38:48 to 26-09-2022 04:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 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:02, from 26-09-2022 04:39:05 to 26-09-2022 04:39:07.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          210675
MON$OLDEST_ACTIVE               214663
MON$OLDEST_SNAPSHOT             214663
MON$NEXT_TRANSACTION            214663
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2061792
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2849 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2849 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2849 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2849 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:121: 
:122: mscs-test-161.budzdorov.ru    Mon Sep 26 04:37:34 2022
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Mon Sep 26 04:37:37 2022
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
--- end of diff output ---

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

26.09.22 04:39:08. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-09-26 04:39:10 No severe PSQL-related problems occured
26.09.22 04:39:12. End of report.