Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2021 07:49
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 16745756 kB
MemAvailable 31453996 kB
Buffers 0 kB
Cached 14916224 kB
SwapCached 0 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-10 07:48:33
DTS_END                         2021-12-10 07:48:33.6160
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/51782
TRN_ID                          232683
ATT_ID                          648
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: 8975.03 22177 1077004
customer order: creation 171.08 449 20530
customer order: refuse 14.83 355 1780
order to supplier: creation 46.50 1359 5580
order to supplier: removal 3.41 114338 409
invoice (draft): creation 45.18 3324 5421
invoice (draft): removal 50.94 974 6113
invoice accept: apply 40.81 18398 4897
invoice accept: cancel 45.80 957 5496
customer reserve: creation 3777.65 180 453318
customer reserve: removal 4484.54 68 538145
realization accept: apply 83.34 169 10001
realization accept: cancel 82.24 141 9869
payment from customer: creation 59.67 129 7160
payment from customer: removal 5.61 92 673
payment to supplier: creation 48.95 131 5874
payment to supplier: removal 3.28 130 394
service: total inventory turnovers 4.10 3289 492
service: total monetary turnovers 6.10 463 732
service: aggregate perf. data 0.97 13617 116
service: refresh index statistics 0.03 284972 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2021 07:49:44 to 10-12-2021 07:49:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4135
TEST_TIME 2 5056
TEST_TIME 3 5719
TEST_TIME 4 5114
TEST_TIME 5 4586
TEST_TIME 6 4880
TEST_TIME 7 5283
TEST_TIME 8 5211
TEST_TIME 9 5345
TEST_TIME 10 5558
TEST_TIME 11 5737
TEST_TIME 12 5770
TEST_TIME 13 5942
TEST_TIME 14 6376
TEST_TIME 15 6578
TEST_TIME 16 7126
TEST_TIME 17 7113
TEST_TIME 18 7346
TEST_TIME 19 7455
TEST_TIME 20 7773
TEST_TIME 21 8490
TEST_TIME 22 8643
TEST_TIME 23 8835
TEST_TIME 24 9244
TEST_TIME 25 10034
TEST_TIME 26 10759
TEST_TIME 27 10566
TEST_TIME 28 10291
TEST_TIME 29 10168
TEST_TIME 30 10155
TEST_TIME 31 10027
TEST_TIME 32 10023
TEST_TIME 33 9954
TEST_TIME 34 9920
TEST_TIME 35 9930
TEST_TIME 36 9755
TEST_TIME 37 9615
TEST_TIME 38 9508
TEST_TIME 39 9773
TEST_TIME 40 9634
TEST_TIME 41 9771
TEST_TIME 42 9736
TEST_TIME 43 9687
TEST_TIME 44 9788
TEST_TIME 45 9651
TEST_TIME 46 9683
TEST_TIME 47 9654
TEST_TIME 48 9684
TEST_TIME 49 9917
TEST_TIME 50 9849
TEST_TIME 51 9814
TEST_TIME 52 9729
TEST_TIME 53 9579
TEST_TIME 54 9514
TEST_TIME 55 9445
TEST_TIME 56 9389
TEST_TIME 57 9422
TEST_TIME 58 9382
TEST_TIME 59 9344
TEST_TIME 60 9289
TEST_TIME 61 9344
TEST_TIME 62 9345
TEST_TIME 63 9361
TEST_TIME 64 9335
TEST_TIME 65 9413
TEST_TIME 66 9547
TEST_TIME 67 9524
TEST_TIME 68 9542
TEST_TIME 69 9549
TEST_TIME 70 9715
TEST_TIME 71 9635
TEST_TIME 72 9726
TEST_TIME 73 9620
TEST_TIME 74 9501
TEST_TIME 75 9468
TEST_TIME 76 9400
TEST_TIME 77 9345
TEST_TIME 78 9288
TEST_TIME 79 9224
TEST_TIME 80 9202
TEST_TIME 81 9148
TEST_TIME 82 9127
TEST_TIME 83 9114
TEST_TIME 84 9144
TEST_TIME 85 9160
TEST_TIME 86 9241
TEST_TIME 87 9211
TEST_TIME 88 9222
TEST_TIME 89 9247
TEST_TIME 90 9241
TEST_TIME 91 9248
TEST_TIME 92 9291
TEST_TIME 93 9197
TEST_TIME 94 9164
TEST_TIME 95 9126
TEST_TIME 96 9097
TEST_TIME 97 9078
TEST_TIME 98 9083
TEST_TIME 99 9068
TEST_TIME 100 9065
TEST_TIME 101 9051
TEST_TIME 102 9058
TEST_TIME 103 9080
TEST_TIME 104 9123
TEST_TIME 105 9115
TEST_TIME 106 9141
TEST_TIME 107 9169
TEST_TIME 108 9153
TEST_TIME 109 9198
TEST_TIME 110 9119
TEST_TIME 111 9096
TEST_TIME 112 9052
TEST_TIME 113 9038
TEST_TIME 114 9007
TEST_TIME 115 8985
TEST_TIME 116 8962
TEST_TIME 117 8977
TEST_TIME 118 8972
TEST_TIME 119 8946
TEST_TIME 120 8978

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-12-2021 07:49:47 to 10-12-2021 07:49:51.

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 1495732 1495732 0 0.00 0 26004 7 0 0
doc_list_biud 1495730 1495730 0 0.00 0 37156 31 0 0
sp_add_invoice_to_stock 4978 4897 81 1.62 37 247065 18398 0 81
sp_cancel_adding_invoice 5496 5496 0 0.00 5 74284 958 0 0
sp_cancel_client_order 1780 1780 0 0.00 4 12252 355 0 0
sp_cancel_customer_reserve 538145 538145 0 0.00 1 29678 68 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 6137 92 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 9709 130 0 0
sp_cancel_supplier_invoice 6125 6113 12 0.19 2 16563 974 0 12
sp_cancel_supplier_order 409 409 0 0.00 8 1153943 114338 0 0
sp_cancel_write_off 9869 9869 0 0.00 1 29292 142 0 0
sp_client_order 20530 20530 0 0.00 4 19463 449 0 0
sp_customer_reserve 453318 453318 0 0.00 0 64843 180 0 0
sp_fill_shopping_cart 31529 31529 0 0.00 1 13552 42 0 0
sp_fill_shopping_cart_clo_res 453310 453310 0 0.00 0 22751 20 0 0
sp_get_clo_for_invoice 4889 4889 0 0.00 0 34420 475 0 0
sp_kill_cost_storno 16432 16432 0 0.00 0 21079 70 0 0
sp_kill_qstorno_ret_qs2qd 1092681 1092681 0 0.00 0 29671 37 0 0
sp_kill_qty_storno 576701 576701 0 0.00 0 37156 80 0 0
sp_lock_selected_doc 20651 20651 0 0.00 0 7769 6 0 0
sp_make_cost_storno 27923 27923 0 0.00 0 22855 55 0 0
sp_multiply_rows_for_pdistr 27923 27923 0 0.00 0 9673 37 0 0
sp_multiply_rows_for_qdistr 20530 20530 0 0.00 0 19452 264 0 0
sp_pay_from_customer 7160 7160 0 0.00 4 12001 130 0 0
sp_pay_to_supplier 5874 5874 0 0.00 5 18009 131 0 0
sp_payment_common 13034 13034 0 0.00 1 11804 28 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 12243 319 0 0
sp_qd_handle_on_invoice_upd_sts 10384 10384 0 0.00 0 37156 360 0 0
sp_qd_handle_on_reserve_upd_sts 10001 10001 0 0.00 0 15372 45 0 0
sp_reserve_write_off 10001 10001 0 0.00 7 15488 170 0 0
sp_supplier_invoice 5421 5421 0 0.00 7 75398 3324 0 0
sp_supplier_order 5580 5580 0 0.00 5 20901 1360 0 0
srv_aggregate_perf_data 116 116 0 0.00 2566 119520 13618 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1156 183884 48271 0 0
srv_find_qd_qs_mism 1029169 1029169 0 0.00 0 26003 6 0 0
srv_make_invnt_saldo 492 492 0 0.00 50 117515 3289 0 0
srv_make_money_saldo 732 732 0 0.00 1 18079 463 0 0
srv_recalc_idx_stat 4 4 0 0.00 68125 418438 284972 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 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4897 4897 0 0.00 0 9984 164 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 13332 109 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 4317 19 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2686 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 15679 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 5748 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1523 20 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 20908 1553 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 3786 12 0 0
t$perf-norm:sp_client_order 20530 20530 0 0.00 0 14692 20 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 808 7 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 14774 11 0 0
t$perf-norm:sp_pay_to_supplier 5874 5874 0 0.00 0 5140 10 0 0
t$perf-norm:sp_reserve_write_off 10001 10001 0 0.00 0 5871 12 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 5147 18 0 0
t$perf-norm:sp_supplier_order 5580 5580 0 0.00 0 14345 19 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1093 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 240 6 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 4170 29 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 932 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 9938 9776 162 1.63 0 10227 16 0 162
v_all_customers 37672 37672 0 0.00 0 89 1 0 0
v_all_suppliers 30836 30836 0 0.00 0 134 1 0 0
v_all_wares 41058 41058 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 4428 16 0 0
v_cancel_client_order 3560 3560 0 0.00 0 2813 6 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3093 8 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1647 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1617 9 0 0
v_cancel_supplier_invoice 778 754 24 3.08 0 5748 24 0 24
v_cancel_supplier_order 818 818 0 0.00 0 387 3 0 0
v_cancel_write_off 2026 2026 0 0.00 0 2420 14 0 0
v_max_id_clo_ord 5580 5580 0 0.00 0 4807 3 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 2988 16 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 7183 5 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 3912 5 0 0
v_max_non_paid_realizn 3735 3735 0 0.00 0 894 3 0 0
v_min_id_clo_ord 5580 5580 0 0.00 0 4045 8 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 6178 31 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 3889 5 0 0
v_min_non_paid_invoice 5874 5874 0 0.00 0 9467 17 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 10698 23 0 0
v_our_firm 10236 10236 0 0.00 0 15 1 0 0
v_reserve_write_off 20002 20002 0 0.00 0 3829 5 0 0
x_lock_depdocs_on_canc_invoice 11232 11232 0 0.00 0 12530 114 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 16816 355 0 0
x_make_qstorno_1000_1200 5580 5580 0 0.00 2 20877 1281 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 3 75390 3167 0 0
x_make_qstorno_2100_3300 453318 453318 0 0.00 0 64841 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 07:49:52 to 10-12-2021 07:49:56.

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:18:37 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:38 21202 8192000000 9717525392 10017177600 797750544 8.874 100 84 167 0 826015328 7711568 20553216
05:21:05 20627 8192000000 9772041408 10081701888 845887632 9.359 100 85 170 0 877757344 12048832 19820880
05:22:31 25440 8192000000 9810760320 10127179776 872310192 9.623 100 89 178 1 914680080 21187904 21181984
05:24:00 10261 8192000000 9867868320 10202529792 914214048 10.039 100 77 155 0 979226384 47973888 17038448
05:25:17 9977 8192000000 9913978544 10258202624 959914000 10.488 100 80 160 0 1029329808 52330208 17085600
05:26:34 4869 8192000000 9954180064 10307530752 989726384 10.779 100 77 152 1 1069794192 65001040 15066768
05:27:41 5501 8192000000 9988653648 10359377920 1015269680 11.026 100 72 142 0 1108442192 79339120 13833392
05:28:50 1274 8192000000 10022862272 10387357696 1046942608 11.331 100 94 186 3 1139414320 73611312 18860400
05:29:52 1500 8192000000 10048356512 10417958912 1062282912 11.478 100 91 180 1 1154263376 73782656 18197808
05:30:54 18946 8192000000 9988307968 10384650240 1069501936 11.547 100 35 70 0 1112358784 35987776 6869072
05:32:15 3810 8192000000 9978420192 10378964992 1073046912 11.581 100 32 62 2 1098696576 18503936 7145728
05:33:22 10081 8192000000 9987528080 10382610432 1074880464 11.599 100 30 60 0 1101565840 19786432 6898944
05:34:35 5112 8192000000 9999786896 10396626944 1079595824 11.644 100 41 82 2 1119218784 31017664 8605296
05:35:42 31540 8192000000 10005858432 10394238976 1084031760 11.686 100 57 114 1 1110960720 14693968 12234992
05:37:18 7017 8192000000 9996352544 10402189312 1076798208 11.617 99 50 102 3 1103628592 16198576 10631808
05:38:30 9563 8192000000 9995284240 10387382272 1083404240 11.680 100 37 72 1 1115713248 24726992 7582016
05:39:40 11248 8192000000 9971257568 10373521408 1017113776 11.044 95 26 52 0 1036945312 14569184 5262352
05:40:54 35644 8192000000 9968163152 10375024640 993461344 10.815 94 23 46 0 1039523360 41091280 4970736
05:42:39 7776 8192000000 9942023520 10322104320 1003708416 10.914 98 53 106 1 1032752528 16802352 12241760
05:43:48 4620 8192000000 9941797536 10316079104 1019181568 11.064 99 59 119 2 1041830240 9207408 13441264
05:44:54 72949 8192000000 9955309408 10357178368 1037990080 11.245 99 24 44 3 1059132992 16480784 4662128
05:47:16 7753 8192000000 9969409312 10372382720 1066564288 11.519 100 39 79 1 1087087088 12013456 8509344
05:48:27 15451 8192000000 9999726816 10400661504 1086786032 11.712 100 25 50 0 1108317168 15529296 6001840
05:49:54 39240 8192000000 10068252880 10471280640 1108310656 11.916 100 48 96 1 1174506240 56713488 9482096
05:51:38 64321 8192000000 10096549392 10500382720 1128176272 12.104 100 33 66 0 1186619856 51378512 7065072
05:53:50 13560 8192000000 10155573888 10565439488 1140522576 12.220 100 47 94 0 1258815984 108484064 9809344
05:55:35 13066 8192000000 10201875024 10612424704 1155065488 12.357 100 57 114 1 1304452192 136125632 13261072
05:56:55 15773 8192000000 10226651712 10643992576 1165543968 12.455 100 49 96 0 1331117648 154857936 10715744
05:58:18 15907 8192000000 10256774368 10682863616 1175944912 12.552 100 53 105 2 1359107824 171177008 11985904
05:59:41 8136 8192000000 10291610512 10710269952 1190301504 12.686 100 59 117 0 1390467712 186767024 13399184
06:00:56 2319 8192000000 10365406464 10782203904 1195072432 12.731 100 69 138 0 1465903792 255439216 15392144
06:02:05 1835 8192000000 10420725904 10826547200 1187071088 12.656 99 90 180 0 1510339872 302912752 20356032
06:03:14 1486 8192000000 10406026416 10824679424 1194020976 12.721 100 82 160 0 1502368800 289917872 18429952
06:04:21 5812 8192000000 10450419552 10866081792 1189850400 12.682 100 74 147 0 1550438352 342833776 17754176
06:05:34 2201 8192000000 10441164464 10853081088 1191780272 12.700 100 82 160 0 1537058800 325941040 19337488
06:06:42 2571 8192000000 10450957792 10857586688 1189554928 12.679 100 87 173 0 1544925792 335478992 19891872
06:07:49 1155 8192000000 10471091136 10897764352 1179685872 12.587 100 95 190 2 1566765680 364356656 22723152
06:08:52 9823 8192000000 10443393344 10917810176 1187484448 12.660 100 39 77 1 1540660352 343000912 10174992
06:10:13 17842 8192000000 10400748448 10866057216 1167250176 12.471 99 33 64 0 1502700800 326933600 8517024
06:12:00 22704 8192000000 10300721936 10764279808 1106323856 11.898 97 19 38 0 1405269904 293393984 5552064
06:13:25 55410 8192000000 10124840256 10633658368 1083180240 11.678 96 8 16 1 1268509648 183034256 2295152
06:15:27 29716 8192000000 10061069872 10492522496 1088922496 11.732 98 36 72 3 1150575808 54069440 7583872
06:17:27 71720 8192000000 10073041296 10532708352 1067758592 11.531 96 34 68 0 1119886704 45131712 6996400
06:19:43 72992 8192000000 10100447056 10545369088 1068197616 11.535 95 31 62 2 1150565136 76086384 6281136
06:22:26 41230 8192000000 10105327984 10558869504 1104374880 11.879 98 23 46 0 1217515232 108536480 4603872
06:24:08 49273 8192000000 10152413136 10595172352 1136079008 12.179 100 18 36 0 1242311648 102527376 3705264
06:25:58 46416 8192000000 10188273472 10651844608 1140092048 12.216 100 24 48 2 1301184800 155380736 5712016
06:27:55 55781 8192000000 10099496800 10539315200 1118473312 12.013 98 35 70 1 1206972768 81087840 7411616
06:29:51 56765 8192000000 10063573200 10506559488 1108271008 11.916 97 15 28 1 1152115680 40289216 3555456
06:31:50 101113 8192000000 10080659232 10529091584 1088926640 11.732 96 13 26 2 1135210624 43712912 2571072
06:34:34 61376 8192000000 10138148624 10607771648 1130388192 12.125 99 17 34 0 1234762896 100671520 3703184
06:36:40 183882 8192000000 10071749024 10515845120 1112904208 11.960 97 11 22 2 1178366544 63518800 1943536
06:40:44 98412 8192000000 10091825440 10526015488 1141320848 12.228 100 60 119 0 1192806304 38266912 13218544
06:43:27 6118 8192000000 10084130416 10511171584 1133815024 12.157 99 37 71 3 1177184416 35873648 7495744
06:44:34 41900 8192000000 10121581104 10574471168 1116837760 11.997 98 16 32 0 1216178672 95979344 3361568
06:46:17 73846 8192000000 10075205072 10534817792 1130581808 12.127 99 15 30 0 1174328912 40378336 3368768
06:48:31 106210 8192000000 10093090864 10542841856 1152153888 12.330 100 21 42 0 1182095072 25609984 4331200
06:51:29 38032 8192000000 10148727808 10592415744 1164658992 12.447 99 25 50 0 1241384320 71640704 5084624
06:53:24 68999 8192000000 10138097504 10639663104 1147048192 12.282 98 17 34 1 1271472496 120740080 3684224
06:55:40 35187 8192000000 10117921056 10565431296 1139380160 12.210 99 23 46 1 1204358160 59986112 4991888
06:57:16 24980 8192000000 10195507872 10635386880 1145083552 12.263 99 45 90 2 1272248176 117671360 9493264
06:59:59 69073 8192000000 10064114000 10558291968 1086058544 11.705 96 30 60 0 1188627536 96393968 6175024
07:02:08 66973 8192000000 10040137440 10494943232 1100208640 11.840 98 19 38 0 1136910448 32540464 4161344
07:04:38 53336 8192000000 10047743056 10502750208 1101475008 11.852 98 9 18 1 1143428464 39905488 2047968
07:06:33 47392 8192000000 10010779664 10462162944 1060229264 11.459 95 5 10 0 1097509968 36111728 1168976
07:08:21 55013 8192000000 10042436272 10509316096 1117822976 12.006 100 13 26 0 1144103136 23687952 2592208
07:10:32 43750 8192000000 10107379696 10540593152 1126279824 12.086 100 25 50 1 1198256384 66325600 5650960
07:12:37 49571 8192000000 10118012704 10564423680 1116133488 11.990 98 27 54 0 1205397760 83542240 5722032
07:14:34 59452 8192000000 10063972048 10488029184 1112186592 11.953 99 39 78 1 1152202816 31716224 8300000
07:16:35 26143 8192000000 10073767024 10506842112 1108148224 11.915 99 44 88 0 1185962368 68820480 8993664
07:18:20 75498 8192000000 10071992512 10508967936 1104504720 11.880 98 34 68 0 1194284016 82758624 7020672
07:20:35 94624 8192000000 10052124304 10483965952 1123755792 12.062 99 14 28 1 1149304208 23387216 2161200
07:23:17 51637 8192000000 10049232752 10530414592 1132081152 12.141 100 4 8 0 1165878128 33262512 534464
07:25:09 54632 8192000000 10090716976 10539642880 1135407360 12.172 100 18 36 0 1165313952 26304928 3601664
07:27:08 55473 8192000000 10115057360 10570911744 1127541056 12.098 98 29 57 1 1201972240 67763536 6667648
07:29:05 84047 8192000000 10113811936 10578989056 1140953776 12.225 99 19 38 0 1205618048 60601184 4063088
07:31:38 52187 8192000000 10105242048 10556309504 1146232896 12.274 100 15 29 0 1217702320 68203216 3266208
07:33:35 44811 8192000000 10101459104 10526236672 1102171936 11.858 96 30 60 0 1143098832 34205424 6721472
07:35:20 40613 8192000000 10069522304 10500259840 1128350784 12.106 99 26 51 1 1175118336 41257728 5509824
07:37:04 129878 8192000000 10067094640 10508767232 1109008848 11.923 97 12 24 0 1158081584 46573184 2499552
07:41:05 63784 8192000000 10075879728 10570739712 1144119840 12.254 100 11 22 0 1199631232 52569616 2941776
07:43:09 64837 8192000000 10079368528 10521600000 1135953008 12.177 99 16 31 0 1170505888 31135664 3417216
07:45:18 101247 8192000000 10106771824 10569687040 1078824960 11.636 94 15 30 0 1151673680 69528048 3320672
07:48:09 36156 8192000000 9226243952 9581293568 342390592 4.011 31 3 6 0 393984144 51026512 567040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2021 07:49:57 to 10-12-2021 07:50:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2021 07:50:13 to 10-12-2021 07:50:15.

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          232682
MON$OLDEST_ACTIVE               233165
MON$OLDEST_SNAPSHOT             233165
MON$NEXT_TRANSACTION            233165
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                       2022592
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2680 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2680 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2680 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2680 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Dec 10 07:48:50 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Dec 10 07:48:51 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Dec 10 07:48:51 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

10.12.21 07:50:16. 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-10 07:50:18 No severe PSQL-related problems occured
10.12.21 07:50:19. End of report.