Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 07: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 16458856 kB
MemAvailable 31428296 kB
Buffers 0 kB
Cached 15261144 kB
SwapCached 440 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.1
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 2021-12-25 07:37:09
DTS_END                         2021-12-25 07:37:09.9950
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/60118
TRN_ID                          229226
ATT_ID                          685
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: 8913.83 24527 1069659
customer order: creation 165.68 409 19881
customer order: refuse 14.48 387 1738
order to supplier: creation 43.27 1383 5192
order to supplier: removal 3.50 114131 420
invoice (draft): creation 44.26 3608 5311
invoice (draft): removal 49.70 1002 5964
invoice accept: apply 40.88 19433 4905
invoice accept: cancel 45.52 1003 5462
customer reserve: creation 3752.71 192 450325
customer reserve: removal 4462.65 71 535518
realization accept: apply 80.72 196 9686
realization accept: cancel 87.28 125 10473
payment from customer: creation 56.87 130 6824
payment from customer: removal 5.43 68 651
payment to supplier: creation 46.67 142 5600
payment to supplier: removal 3.57 134 428
service: total inventory turnovers 3.75 3973 450
service: total monetary turnovers 5.93 558 712
service: aggregate perf. data 0.96 13845 115
service: refresh index statistics 0.03 329741 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-12-2021 07:38:47 to 25-12-2021 07:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4002
TEST_TIME 2 4183
TEST_TIME 3 3936
TEST_TIME 4 4139
TEST_TIME 5 5092
TEST_TIME 6 4566
TEST_TIME 7 5063
TEST_TIME 8 4957
TEST_TIME 9 5123
TEST_TIME 10 5548
TEST_TIME 11 5712
TEST_TIME 12 5671
TEST_TIME 13 6224
TEST_TIME 14 6140
TEST_TIME 15 6651
TEST_TIME 16 6937
TEST_TIME 17 7517
TEST_TIME 18 7916
TEST_TIME 19 7885
TEST_TIME 20 8115
TEST_TIME 21 8004
TEST_TIME 22 8455
TEST_TIME 23 9048
TEST_TIME 24 9228
TEST_TIME 25 9967
TEST_TIME 26 9975
TEST_TIME 27 10616
TEST_TIME 28 10561
TEST_TIME 29 10370
TEST_TIME 30 10147
TEST_TIME 31 10347
TEST_TIME 32 10439
TEST_TIME 33 10561
TEST_TIME 34 10459
TEST_TIME 35 10284
TEST_TIME 36 10309
TEST_TIME 37 10098
TEST_TIME 38 10031
TEST_TIME 39 9928
TEST_TIME 40 9933
TEST_TIME 41 9866
TEST_TIME 42 9953
TEST_TIME 43 9816
TEST_TIME 44 9717
TEST_TIME 45 9933
TEST_TIME 46 9828
TEST_TIME 47 9668
TEST_TIME 48 9646
TEST_TIME 49 9578
TEST_TIME 50 9544
TEST_TIME 51 9487
TEST_TIME 52 9480
TEST_TIME 53 9415
TEST_TIME 54 9326
TEST_TIME 55 9262
TEST_TIME 56 9142
TEST_TIME 57 9203
TEST_TIME 58 9129
TEST_TIME 59 9209
TEST_TIME 60 9163
TEST_TIME 61 9139
TEST_TIME 62 9215
TEST_TIME 63 9237
TEST_TIME 64 9349
TEST_TIME 65 9348
TEST_TIME 66 9367
TEST_TIME 67 9371
TEST_TIME 68 9398
TEST_TIME 69 9469
TEST_TIME 70 9484
TEST_TIME 71 9486
TEST_TIME 72 9418
TEST_TIME 73 9400
TEST_TIME 74 9368
TEST_TIME 75 9275
TEST_TIME 76 9251
TEST_TIME 77 9194
TEST_TIME 78 9211
TEST_TIME 79 9189
TEST_TIME 80 9191
TEST_TIME 81 9156
TEST_TIME 82 9131
TEST_TIME 83 9118
TEST_TIME 84 9084
TEST_TIME 85 9118
TEST_TIME 86 9142
TEST_TIME 87 9158
TEST_TIME 88 9142
TEST_TIME 89 9141
TEST_TIME 90 9241
TEST_TIME 91 9253
TEST_TIME 92 9316
TEST_TIME 93 9385
TEST_TIME 94 9289
TEST_TIME 95 9207
TEST_TIME 96 9170
TEST_TIME 97 9137
TEST_TIME 98 9140
TEST_TIME 99 9094
TEST_TIME 100 9046
TEST_TIME 101 9038
TEST_TIME 102 9093
TEST_TIME 103 9098
TEST_TIME 104 9083
TEST_TIME 105 9123
TEST_TIME 106 9206
TEST_TIME 107 9237
TEST_TIME 108 9214
TEST_TIME 109 9220
TEST_TIME 110 9190
TEST_TIME 111 9164
TEST_TIME 112 9122
TEST_TIME 113 9084
TEST_TIME 114 9087
TEST_TIME 115 9067
TEST_TIME 116 9024
TEST_TIME 117 9006
TEST_TIME 118 8967
TEST_TIME 119 8987
TEST_TIME 120 8917

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 07:38:51 to 25-12-2021 07:38:54.

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 1486291 1486291 0 0.00 0 23802 7 0 0
doc_list_biud 1486291 1486291 0 0.00 0 50623 32 0 0
sp_add_invoice_to_stock 4986 4905 81 1.62 48 184174 19434 0 81
sp_cancel_adding_invoice 5462 5462 0 0.00 5 116118 1003 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 15897 387 0 0
sp_cancel_customer_reserve 535518 535518 0 0.00 1 50625 71 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 1829 68 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 8337 135 0 0
sp_cancel_supplier_invoice 5974 5964 10 0.16 2 33962 1003 0 10
sp_cancel_supplier_order 420 420 0 0.00 5 1115966 114132 0 0
sp_cancel_write_off 10473 10473 0 0.00 1 10529 125 0 0
sp_client_order 19881 19881 0 0.00 4 21406 409 0 0
sp_customer_reserve 450325 450325 0 0.00 0 122859 192 0 0
sp_fill_shopping_cart 30372 30372 0 0.00 1 14487 44 0 0
sp_fill_shopping_cart_clo_res 450317 450317 0 0.00 0 16037 17 0 0
sp_get_clo_for_invoice 4897 4897 0 0.00 0 26892 388 0 0
sp_kill_cost_storno 17014 17014 0 0.00 0 9349 70 0 0
sp_kill_qstorno_ret_qs2qd 1087893 1087893 0 0.00 0 50620 38 0 0
sp_kill_qty_storno 574158 574158 0 0.00 0 50623 83 0 0
sp_lock_selected_doc 20271 20271 0 0.00 0 8471 7 0 0
sp_make_cost_storno 27007 27007 0 0.00 0 15041 61 0 0
sp_multiply_rows_for_pdistr 27007 27007 0 0.00 0 13986 38 0 0
sp_multiply_rows_for_qdistr 19881 19881 0 0.00 0 21388 214 0 0
sp_pay_from_customer 6824 6824 0 0.00 4 21410 131 0 0
sp_pay_to_supplier 5600 5600 0 0.00 5 14653 143 0 0
sp_payment_common 12424 12424 0 0.00 1 7270 30 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 15890 362 0 0
sp_qd_handle_on_invoice_upd_sts 10359 10359 0 0.00 0 27167 406 0 0
sp_qd_handle_on_reserve_upd_sts 9686 9686 0 0.00 0 15010 60 0 0
sp_reserve_write_off 9686 9686 0 0.00 7 15170 197 0 0
sp_supplier_invoice 5311 5311 0 0.00 8 87110 3608 0 0
sp_supplier_order 5192 5192 0 0.00 5 34081 1384 0 0
srv_aggregate_perf_data 115 115 0 0.00 2475 119758 13845 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1446 121202 44097 0 0
srv_find_qd_qs_mism 1023479 1023479 0 0.00 0 23801 6 0 0
srv_make_invnt_saldo 450 450 0 0.00 45 102855 3974 0 0
srv_make_money_saldo 712 712 0 0.00 1 20920 559 0 0
srv_recalc_idx_stat 4 4 0 0.00 218547 469332 329742 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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 4905 4905 0 0.00 0 12105 166 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 15395 183 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 2054 14 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 6986 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 11330 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1006 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 2121 23 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 22899 1543 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 1970 8 0 0
t$perf-norm:sp_client_order 19881 19881 0 0.00 0 5929 19 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 7754 10 0 0
t$perf-norm:sp_pay_from_customer 6824 6824 0 0.00 0 9600 11 0 0
t$perf-norm:sp_pay_to_supplier 5600 5600 0 0.00 0 14760 13 0 0
t$perf-norm:sp_reserve_write_off 9686 9686 0 0.00 0 14886 17 0 0
t$perf-norm:sp_supplier_invoice 5311 5311 0 0.00 0 7558 18 0 0
t$perf-norm:sp_supplier_order 5192 5192 0 0.00 0 3638 18 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 762 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 97 4 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 2771 28 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 1831 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9956 9794 162 1.62 0 6768 15 0 162
v_all_customers 36448 36448 0 0.00 0 38 1 0 0
v_all_suppliers 28976 28976 0 0.00 0 37 1 0 0
v_all_wares 39760 39760 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1221 7 0 0
v_cancel_client_order 3476 3476 0 0.00 0 2054 4 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 636 5 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1088 4 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 1311 5 0 0
v_cancel_supplier_invoice 762 742 20 2.62 0 3099 17 0 20
v_cancel_supplier_order 840 840 0 0.00 0 3935 10 0 0
v_cancel_write_off 1922 1922 0 0.00 0 1209 13 0 0
v_max_id_clo_ord 5188 5188 0 0.00 0 911 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 2691 10 0 0
v_max_id_ord_sup 5304 5304 0 0.00 0 6646 9 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 1499 3 0 0
v_max_non_paid_realizn 3444 3444 0 0.00 0 952 3 0 0
v_min_id_clo_ord 5188 5188 0 0.00 0 6654 10 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 1941 6 0 0
v_min_id_ord_sup 5304 5304 0 0.00 0 8991 11 0 0
v_min_non_paid_invoice 5600 5600 0 0.00 0 4893 21 0 0
v_min_non_paid_realizn 6824 6824 0 0.00 0 7266 21 0 0
v_our_firm 10072 10072 0 0.00 0 23 1 0 0
v_reserve_write_off 19370 19370 0 0.00 0 3734 5 0 0
x_lock_depdocs_on_canc_invoice 11055 11055 0 0.00 0 18195 115 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 4104 323 0 0
x_make_qstorno_1000_1200 5192 5192 0 0.00 2 34067 1294 0 0
x_make_qstorno_1200_2000 5311 5311 0 0.00 4 86923 3450 0 0
x_make_qstorno_2100_3300 450325 450325 0 0.00 0 122857 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 07:38:55 to 25-12-2021 07:38:59.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:13 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:14 24561 8192000000 9729730352 10028630016 808680656 8.984 100 87 174 0 838004448 7688496 21635296
05:09:48 19101 8192000000 9774695328 10081804288 846786704 9.368 100 88 176 0 880670000 12247072 21636224
05:11:21 19702 8192000000 9813513376 10135826432 875857280 9.658 100 81 162 1 918471968 23447600 19167088
05:12:55 14855 8192000000 9881455488 10219048960 932010656 10.214 100 83 166 0 990037344 40030032 17996656
05:14:14 12719 8192000000 9942630448 10291830784 972777648 10.614 100 79 158 0 1051058192 61931504 16349040
05:15:30 4454 8192000000 9982044880 10340388864 1009212656 10.968 100 83 164 0 1097020288 71377392 16430240
05:16:36 4145 8192000000 10009970736 10375409664 1034532528 11.212 100 78 155 0 1121214976 71141152 15541296
05:17:41 2395 8192000000 10022368144 10390749184 1054413120 11.403 100 87 173 2 1132593264 60679424 17500720
05:18:45 2130 8192000000 10053539376 10427092992 1073517264 11.586 100 96 192 0 1162462768 68603264 20342240
05:19:50 5481 8192000000 10028347440 10405302272 1079105600 11.639 100 68 137 1 1144516528 50903808 14507120
05:20:56 7306 8192000000 10010019168 10411438080 1087138368 11.715 100 47 94 0 1137601584 40577792 9885424
05:22:05 11012 8192000000 10006446896 10413723648 1094385104 11.784 100 17 34 1 1119330064 21975920 2969040
05:23:17 53828 8192000000 10043567552 10454798336 1094708480 11.787 100 39 78 1 1136145296 32533360 8903456
05:25:14 5071 8192000000 10031353936 10432180224 1102128704 11.858 100 45 91 0 1139164080 27398256 9637120
05:26:19 10693 8192000000 10021360368 10438709248 1094532384 11.786 100 32 64 2 1147693888 46200896 6960608
05:27:30 5687 8192000000 10002289488 10402316288 1094159136 11.782 100 46 87 2 1120017344 16052336 9805872
05:28:41 58988 8192000000 9956135248 10358874112 1004914080 10.926 93 25 50 0 1029917520 19085696 5917744
05:30:40 5257 8192000000 9965598080 10354606080 1014373872 11.018 97 42 84 1 1046506208 22938096 9194240
05:31:47 5239 8192000000 9947273904 10322653184 1014256976 11.017 98 49 101 2 1041299680 16274848 10767856
05:32:53 10556 8192000000 9938513136 10334605312 994534320 10.826 97 35 70 0 1016133168 13292640 8306208
05:34:05 51676 8192000000 9960243376 10366750720 1026215232 11.132 98 21 42 0 1050408272 19585568 4607472
05:35:58 9450 8192000000 9969824368 10364989440 1057391104 11.432 100 36 72 0 1085991552 20375616 8224832
05:37:08 17161 8192000000 9998510464 10386632704 1081185504 11.659 100 46 93 0 1107751456 17200768 9365184
05:38:47 34979 8192000000 10068936944 10462633984 1097972592 11.818 100 55 111 1 1161576528 52543072 11060864
05:40:30 82212 8192000000 10132848512 10545782784 1119463152 12.022 100 36 72 1 1211736672 83631184 8642336
05:43:02 29334 8192000000 10135785568 10544410624 1134966624 12.168 100 47 92 1 1232208816 87807264 9434928
05:44:38 28185 8192000000 10176001888 10608140288 1148892912 12.299 100 37 74 2 1283754128 126775168 8086048
05:46:14 13644 8192000000 10230441872 10648641536 1161611360 12.418 100 56 112 0 1328817584 154074528 13131696
05:47:43 4168 8192000000 10271930016 10674077696 1168881600 12.486 100 81 162 2 1371308112 185021952 17404560
05:48:54 5554 8192000000 10301939760 10714660864 1180471088 12.595 100 60 119 1 1406693376 212541264 13681024
05:50:07 2198 8192000000 10396927360 10797924352 1179317696 12.584 99 91 184 2 1495128240 295182512 20628032
05:51:11 2434 8192000000 10376746592 10785464320 1181242144 12.602 99 78 154 0 1473995632 275437392 17316096
05:52:21 2697 8192000000 10372675728 10798919680 1202476704 12.799 100 78 157 2 1477866272 255860816 19528752
05:53:31 1445 8192000000 10436194208 10853675008 1206668528 12.838 100 82 163 1 1534475808 309540048 18267232
05:54:40 11137 8192000000 10411501968 10820673536 1191057184 12.693 100 70 138 1 1502208784 294794080 16357520
05:55:55 7380 8192000000 10371796240 10798366720 1185593872 12.642 100 46 91 1 1480688848 283141040 11953936
05:57:16 5959 8192000000 10425743312 10853126144 1172012256 12.516 100 58 116 0 1534870496 348674512 14183728
05:58:34 2874 8192000000 10419766576 10827628544 1130499712 12.126 98 82 163 2 1506281904 356336544 19445648
05:59:44 10242 8192000000 10362918000 10802995200 1123947680 12.064 99 43 86 0 1476331536 341099856 11284000
06:00:58 9425 8192000000 10312849920 10761273344 1144972784 12.262 100 32 64 1 1422417776 269011632 8433360
06:02:13 15497 8192000000 10250603584 10743304192 1125897408 12.083 99 24 48 1 1373189552 240600752 6691392
06:03:31 27820 8192000000 10153497728 10608701440 1114576144 11.976 99 10 20 0 1267628368 150603152 2449072
06:05:02 56114 8192000000 10067886000 10502897664 1095032544 11.790 98 13 26 2 1132251056 34515120 2703392
06:07:08 14034 8192000000 10113128400 10523475968 1106141872 11.896 98 51 102 2 1186177760 69234368 10801520
06:08:27 35668 8192000000 10150575488 10642399232 1125953712 12.083 100 14 28 0 1299174112 169828976 3391424
06:10:07 38648 8192000000 10143329584 10607833088 1118688000 12.015 99 14 28 0 1215673808 93743888 3241920
06:11:53 121202 8192000000 10029922784 10479501312 1040053216 11.265 92 26 48 2 1068130512 23190736 4886560
06:14:57 73832 8192000000 10105249232 10549620736 1118391296 12.012 99 49 97 4 1195118768 66182416 10545056
06:17:38 25695 8192000000 10123406064 10576400384 1135049520 12.169 99 32 64 0 1217815264 75324784 7440960
06:19:05 59783 8192000000 10095137104 10563473408 1123646848 12.061 98 14 28 1 1186334912 59077936 3610128
06:21:50 45460 8192000000 10048857616 10516840448 1095270480 11.793 97 17 34 0 1189075040 89767184 4037376
06:23:36 81818 8192000000 10064177584 10515365888 1130110560 12.122 100 20 40 1 1168608176 34032608 4465008
06:26:06 58983 8192000000 10075837664 10535165952 1145293440 12.265 100 13 26 0 1186175104 37532272 3349392
06:28:06 22974 8192000000 10081882400 10538389504 1151761856 12.326 100 27 54 0 1198622384 40737408 6123120
06:29:30 22816 8192000000 10052208000 10502340608 1143138576 12.245 100 19 37 2 1161364800 14277568 3948656
06:30:53 61174 8192000000 10092760496 10538156032 1146765328 12.279 100 24 47 1 1190802048 39320176 4716544
06:32:56 78614 8192000000 10090676096 10596487168 1142963648 12.243 99 29 58 0 1202933184 54137120 5832416
06:35:25 68820 8192000000 10129522896 10575306752 1127185776 12.095 97 30 57 1 1203781376 70433280 6162320
06:37:46 58786 8192000000 10151205696 10616860672 1166046336 12.460 100 17 35 2 1245143280 75424640 3672304
06:39:48 46965 8192000000 10125849792 10595745792 1156865328 12.374 99 26 52 0 1252870752 89860512 6144912
06:41:35 70585 8192000000 10089570608 10531053568 1075349264 11.603 93 20 40 0 1111347968 32070672 3928032
06:43:47 49445 8192000000 10082831216 10532823040 1139840592 12.214 99 27 54 0 1202027824 56690736 5496496
06:45:44 29085 8192000000 10102776080 10555260928 1135712112 12.175 100 53 105 0 1194316720 48568992 10035616
06:47:17 99712 8192000000 10134582336 10587688960 1130917344 12.130 98 22 44 0 1185138000 49965824 4254832
06:49:58 67383 8192000000 10064815152 10521112576 1131394496 12.135 99 22 43 0 1169007728 32873072 4740160
06:52:06 49483 8192000000 10097526192 10530848768 1136604896 12.184 99 35 69 0 1190211904 46280032 7326976
06:53:57 66526 8192000000 10089133248 10532921344 1092243008 11.764 96 21 42 0 1175113808 78635072 4235728
06:57:10 69748 8192000000 10049637984 10537230336 1122717600 12.053 100 8 14 1 1180218464 55880800 1620064
06:59:21 19878 8192000000 10045204688 10483716096 1099810512 11.836 98 30 59 0 1132047776 26395040 5842224
07:00:46 16048 8192000000 10055052816 10462912512 1094759456 11.788 98 63 125 0 1122685696 14488848 13437392
07:02:03 62054 8192000000 10071519056 10513608704 1089987584 11.743 97 21 42 0 1159434976 64504784 4942608
07:04:11 66560 8192000000 10080979120 10539614208 1061459824 11.470 95 26 52 1 1113721040 47168128 5093088
07:06:19 86272 8192000000 10057938752 10554597376 1095914176 11.799 98 13 26 0 1213321824 114706016 2701632
07:08:46 77902 8192000000 10103802352 10559983616 1096455840 11.804 97 18 36 0 1165769568 66027808 3285920
07:11:14 98311 8192000000 10048900240 10532622336 1116423952 11.993 100 24 48 0 1160893744 39591824 4877968
07:14:25 65224 8192000000 10073923424 10540429312 1132939312 12.149 100 7 14 0 1181565184 47126800 1499072
07:16:30 53271 8192000000 10151111792 10588180480 1132296768 12.143 99 35 70 0 1223793600 83539552 7957280
07:18:24 56877 8192000000 10200050464 10654572544 1151592864 12.324 100 31 62 1 1262934992 104336064 7006064
07:20:59 84583 8192000000 10169013680 10639073280 1128733296 12.109 98 11 22 0 1248953920 117483088 2737536
07:23:48 94806 8192000000 10085222512 10566172672 1099670864 11.835 96 7 13 2 1150200144 49056464 1472816
07:26:27 43687 8192000000 10132798800 10569031680 1135018896 12.169 99 41 82 0 1198691344 55675584 7996864
07:29:27 23008 8192000000 10110631376 10555383808 1149284496 12.303 100 28 56 1 1202579792 47645680 5649616
07:30:58 54269 8192000000 10093908592 10567303168 1125903296 12.083 99 14 28 0 1186889296 57556512 3429488
07:33:23 56367 8192000000 10094056032 10544869376 1133127040 12.151 99 11 22 0 1204951728 69735488 2089200
07:35:22 31221 8192000000 10070040848 10525622272 1101531984 11.852 97 18 36 0 1159904320 54593152 3779184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2021 07:39:01 to 25-12-2021 07:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 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 25-12-2021 07:39:17 to 25-12-2021 07:39:19.

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          227595
MON$OLDEST_ACTIVE               229517
MON$OLDEST_SNAPSHOT             229517
MON$NEXT_TRANSACTION            229517
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                       2053152
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Sat Dec 25 07:37:14 2021
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Sat Dec 25 07:37:22 2021
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Sat Dec 25 07:37:22 2021
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:145: 
:146: 
:147: mscs-test-161.budzdorov.ru    Sat Dec 25 07:37:23 2021
:148:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:149: 
:150: 
:151: mscs-test-161.budzdorov.ru    Sat Dec 25 07:37:23 2021
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:153: 
--- end of diff output ---

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

25.12.21 07:39:20. 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
2021-12-25 07:39:23 No severe PSQL-related problems occured
25.12.21 07:39:24. End of report.