Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2021 04:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15839468 kB
MemAvailable 30782944 kB
Buffers 20 kB
Cached 15793772 kB
SwapCached 2456 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-11-03 04:37:09
DTS_END                         2021-11-03 04:37:09.4040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40700
TRN_ID                          237971
ATT_ID                          694
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: 8891.42 28866 1066971
customer order: creation 168.23 417 20188
customer order: refuse 15.64 391 1877
order to supplier: creation 45.97 1522 5516
order to supplier: removal 3.33 126216 400
invoice (draft): creation 47.05 4230 5646
invoice (draft): removal 49.79 1091 5975
invoice accept: apply 42.45 22052 5094
invoice accept: cancel 44.24 1145 5309
customer reserve: creation 3832.95 222 459954
customer reserve: removal 4356.02 74 522723
realization accept: apply 82.49 209 9899
realization accept: cancel 74.24 148 8909
payment from customer: creation 60.13 140 7215
payment from customer: removal 5.88 118 705
payment to supplier: creation 48.62 164 5834
payment to supplier: removal 3.43 94 412
service: total inventory turnovers 3.88 4152 465
service: total monetary turnovers 6.23 589 747
service: aggregate perf. data 0.83 20934 99
service: refresh index statistics 0.03 393407 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-11-2021 04:38:14 to 03-11-2021 04:38:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5867
TEST_TIME 2 4595
TEST_TIME 3 4532
TEST_TIME 4 5307
TEST_TIME 5 5470
TEST_TIME 6 5827
TEST_TIME 7 5711
TEST_TIME 8 5902
TEST_TIME 9 5941
TEST_TIME 10 6223
TEST_TIME 11 6470
TEST_TIME 12 6701
TEST_TIME 13 6713
TEST_TIME 14 6812
TEST_TIME 15 6859
TEST_TIME 16 7106
TEST_TIME 17 7026
TEST_TIME 18 7157
TEST_TIME 19 7663
TEST_TIME 20 7650
TEST_TIME 21 7843
TEST_TIME 22 8304
TEST_TIME 23 8138
TEST_TIME 24 8657
TEST_TIME 25 8693
TEST_TIME 26 8756
TEST_TIME 27 8863
TEST_TIME 28 8989
TEST_TIME 29 9238
TEST_TIME 30 10252
TEST_TIME 31 10584
TEST_TIME 32 10597
TEST_TIME 33 10437
TEST_TIME 34 10447
TEST_TIME 35 10329
TEST_TIME 36 10294
TEST_TIME 37 10293
TEST_TIME 38 10160
TEST_TIME 39 10155
TEST_TIME 40 10020
TEST_TIME 41 9809
TEST_TIME 42 9741
TEST_TIME 43 9603
TEST_TIME 44 9614
TEST_TIME 45 9506
TEST_TIME 46 9391
TEST_TIME 47 9308
TEST_TIME 48 9374
TEST_TIME 49 9279
TEST_TIME 50 9432
TEST_TIME 51 9501
TEST_TIME 52 9459
TEST_TIME 53 9426
TEST_TIME 54 9375
TEST_TIME 55 9395
TEST_TIME 56 9452
TEST_TIME 57 9532
TEST_TIME 58 9558
TEST_TIME 59 9559
TEST_TIME 60 9563
TEST_TIME 61 9609
TEST_TIME 62 9609
TEST_TIME 63 9574
TEST_TIME 64 9475
TEST_TIME 65 9465
TEST_TIME 66 9412
TEST_TIME 67 9371
TEST_TIME 68 9334
TEST_TIME 69 9238
TEST_TIME 70 9286
TEST_TIME 71 9230
TEST_TIME 72 9145
TEST_TIME 73 9194
TEST_TIME 74 9134
TEST_TIME 75 9167
TEST_TIME 76 9124
TEST_TIME 77 9095
TEST_TIME 78 9096
TEST_TIME 79 9256
TEST_TIME 80 9202
TEST_TIME 81 9191
TEST_TIME 82 9261
TEST_TIME 83 9317
TEST_TIME 84 9365
TEST_TIME 85 9360
TEST_TIME 86 9416
TEST_TIME 87 9352
TEST_TIME 88 9280
TEST_TIME 89 9216
TEST_TIME 90 9217
TEST_TIME 91 9188
TEST_TIME 92 9178
TEST_TIME 93 9146
TEST_TIME 94 9112
TEST_TIME 95 9098
TEST_TIME 96 9038
TEST_TIME 97 9051
TEST_TIME 98 9023
TEST_TIME 99 8998
TEST_TIME 100 9049
TEST_TIME 101 9109
TEST_TIME 102 9106
TEST_TIME 103 9121
TEST_TIME 104 9113
TEST_TIME 105 9120
TEST_TIME 106 9156
TEST_TIME 107 9134
TEST_TIME 108 9137
TEST_TIME 109 9147
TEST_TIME 110 9088
TEST_TIME 111 9037
TEST_TIME 112 9020
TEST_TIME 113 8979
TEST_TIME 114 8975
TEST_TIME 115 8950
TEST_TIME 116 8909
TEST_TIME 117 8929
TEST_TIME 118 8909
TEST_TIME 119 8925
TEST_TIME 120 8892

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2021 04:38:18 to 03-11-2021 04:38:21.

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 1492012 1492012 0 0.00 0 22275 9 0 0
doc_list_biud 1492000 1492000 0 0.00 0 76805 34 0 0
sp_add_invoice_to_stock 5161 5094 67 1.29 61 295175 22053 0 67
sp_cancel_adding_invoice 5309 5309 0 0.00 5 92691 1146 0 0
sp_cancel_client_order 1877 1877 0 0.00 5 12146 391 0 0
sp_cancel_customer_reserve 522723 522723 0 0.00 1 67502 75 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 5802 119 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 3898 94 0 0
sp_cancel_supplier_invoice 5980 5975 5 0.08 2 76810 1092 0 5
sp_cancel_supplier_order 400 400 0 0.00 6 1059893 126217 0 0
sp_cancel_write_off 8909 8909 0 0.00 1 15925 148 0 0
sp_client_order 20188 20188 0 0.00 4 24535 418 0 0
sp_customer_reserve 459954 459954 0 0.00 0 118243 222 0 0
sp_fill_shopping_cart 31332 31332 0 0.00 1 17578 50 0 0
sp_fill_shopping_cart_clo_res 459952 459952 0 0.00 0 14537 15 0 0
sp_get_clo_for_invoice 5092 5092 0 0.00 0 33813 316 0 0
sp_kill_cost_storno 15335 15335 0 0.00 0 11517 80 0 0
sp_kill_qstorno_ret_qs2qd 1060730 1060730 0 0.00 0 76803 40 0 0
sp_kill_qty_storno 560177 560177 0 0.00 0 76804 88 0 0
sp_lock_selected_doc 20891 20891 0 0.00 0 8586 6 0 0
sp_make_cost_storno 28040 28040 0 0.00 0 14325 63 0 0
sp_multiply_rows_for_pdistr 28036 28036 0 0.00 0 12341 43 0 0
sp_multiply_rows_for_qdistr 20188 20188 0 0.00 0 19717 191 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 12415 140 0 0
sp_pay_to_supplier 5834 5834 0 0.00 5 14507 165 0 0
sp_payment_common 13044 13044 0 0.00 1 12317 31 0 0
sp_qd_handle_on_cancel_clo 1877 1877 0 0.00 0 12137 339 0 0
sp_qd_handle_on_invoice_upd_sts 10398 10398 0 0.00 0 24428 447 0 0
sp_qd_handle_on_reserve_upd_sts 9895 9895 0 0.00 0 17277 56 0 0
sp_reserve_write_off 9899 9899 0 0.00 7 17343 209 0 0
sp_supplier_invoice 5646 5646 0 0.00 6 152510 4230 0 0
sp_supplier_order 5516 5516 0 0.00 6 28068 1523 0 0
srv_aggregate_perf_data 99 99 0 0.00 2686 395054 20934 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1293 158962 40305 0 0
srv_find_qd_qs_mism 1019018 1019018 0 0.00 0 22273 8 0 0
srv_make_invnt_saldo 465 465 0 0.00 30 103498 4153 0 0
srv_make_money_saldo 747 747 0 0.00 1 12499 589 0 0
srv_recalc_idx_stat 4 4 0 0.00 176387 603241 393407 0 0
t$perf-abend:sp_add_invoice_to_stock 67 67 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5094 5094 0 0.00 0 21234 196 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3937 138 0 0
t$perf-norm:sp_cancel_client_order 1877 1877 0 0.00 0 15122 23 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 4017 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 2399 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 455 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 619 13 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 22111 1760 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 2997 10 0 0
t$perf-norm:sp_client_order 20188 20188 0 0.00 0 8210 22 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 3186 10 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 21278 15 0 0
t$perf-norm:sp_pay_to_supplier 5834 5834 0 0.00 0 7009 11 0 0
t$perf-norm:sp_reserve_write_off 9899 9899 0 0.00 0 20960 15 0 0
t$perf-norm:sp_supplier_invoice 5646 5646 0 0.00 0 11470 22 0 0
t$perf-norm:sp_supplier_order 5516 5516 0 0.00 0 17412 21 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 158 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 52 2 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 4004 22 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 2081 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 5 0 0
v_add_invoice_to_stock 10310 10176 134 1.29 0 7032 18 0 134
v_all_customers 37120 37120 0 0.00 0 23 1 0 0
v_all_suppliers 30850 30850 0 0.00 0 104 1 0 0
v_all_wares 40366 40366 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2987 13 0 0
v_cancel_client_order 3754 3754 0 0.00 0 5560 12 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 5792 16 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2236 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3888 11 0 0
v_cancel_supplier_invoice 886 876 10 1.12 0 3350 24 0 10
v_cancel_supplier_order 800 800 0 0.00 0 722 6 0 0
v_cancel_write_off 1990 1990 0 0.00 0 3292 20 0 0
v_max_id_clo_ord 5511 5511 0 0.00 0 3028 2 0 0
v_max_id_clo_res 996 996 0 0.00 0 6272 20 0 0
v_max_id_ord_sup 5638 5638 0 0.00 0 5271 6 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 718 3 0 0
v_max_non_paid_realizn 3819 3819 0 0.00 0 2428 4 0 0
v_min_id_clo_ord 5511 5511 0 0.00 0 4297 11 0 0
v_min_id_clo_res 995 995 0 0.00 0 8654 19 0 0
v_min_id_ord_sup 5638 5638 0 0.00 0 3891 9 0 0
v_min_non_paid_invoice 5831 5831 0 0.00 0 11608 27 0 0
v_min_non_paid_realizn 7213 7213 0 0.00 0 11566 20 0 0
v_our_firm 10034 10034 0 0.00 0 27 1 0 0
v_reserve_write_off 19790 19790 0 0.00 0 5370 10 0 0
x_lock_depdocs_on_canc_invoice 10846 10846 0 0.00 0 21725 145 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 6852 424 0 0
x_make_qstorno_1000_1200 5516 5516 0 0.00 2 27475 1417 0 0
x_make_qstorno_1200_2000 5646 5646 0 0.00 3 152380 4056 0 0
x_make_qstorno_2100_3300 459954 459954 0 0.00 0 118228 203 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2021 04:38:22 to 03-11-2021 04:38:26.

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:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 23418 8192000000 9706604368 10001272832 782707744 8.721 100 83 166 0 810103392 7109744 20285904
02:09:44 25266 8192000000 9753103152 10056192000 824190400 9.141 100 94 188 1 860155632 13514608 22450624
02:11:16 15929 8192000000 9785564784 10101092352 848519312 9.385 100 78 156 1 888169712 22022672 17627728
02:12:36 17319 8192000000 9861875392 10191556608 909621632 9.994 100 79 158 1 968544080 41573056 17349392
02:13:58 9076 8192000000 9910950112 10257809408 955266800 10.443 100 79 158 0 1024400256 52899216 16234240
02:15:10 4896 8192000000 9945920640 10299973632 988338992 10.765 100 83 165 3 1059903904 54627808 16937104
02:16:18 4284 8192000000 9986388736 10344468480 1007806080 10.954 100 87 175 1 1102923200 78439568 16677552
02:17:24 2827 8192000000 10006094944 10369802240 1038194192 11.247 100 93 185 1 1115085808 58650448 18241168
02:18:30 2121 8192000000 10008785648 10379902976 1054625104 11.405 100 85 168 0 1129623328 58246016 16752208
02:19:34 2073 8192000000 10026032320 10395361280 1066809536 11.522 100 92 180 0 1134880672 49696784 18374352
02:20:37 12200 8192000000 9968423104 10366853120 1071798544 11.569 100 14 28 2 1107842144 33026560 3017040
02:21:51 5732 8192000000 10014481216 10409431040 1081522464 11.662 100 31 61 2 1137144832 49093920 6528448
02:22:58 5298 8192000000 9997953920 10392084480 1087868528 11.722 100 46 92 1 1117698288 19570960 10258800
02:24:06 60452 8192000000 10000895072 10396770304 1092460528 11.766 100 34 68 1 1131800016 31808032 7531456
02:26:07 6047 8192000000 10027995392 10435166208 1096557872 11.805 100 44 85 0 1123035760 17050560 9427328
02:27:15 4252 8192000000 10019471328 10406010880 1095228528 11.792 100 52 102 0 1116229456 8472368 12528560
02:28:20 7754 8192000000 9981585856 10379943936 1067968192 11.533 99 32 64 2 1087561072 11895696 7697184
02:29:30 21918 8192000000 9950223408 10351702016 952500768 10.416 89 24 48 1 983381792 25432752 5448272
02:30:58 4571 8192000000 9957200208 10342428672 1035816384 11.224 100 51 101 0 1060142192 13265472 11060336
02:32:04 8601 8192000000 9927541344 10314412032 986339920 10.746 96 31 62 2 1008965648 15862720 6763008
02:33:13 24116 8192000000 9998841440 10395140096 1046972160 11.332 100 51 102 2 1079923616 21237296 11714160
02:34:49 7992 8192000000 9960495472 10363523072 1052291488 11.383 100 26 52 2 1073542592 15785040 5466064
02:35:59 8773 8192000000 9995926368 10398957568 1067753888 11.531 100 44 89 0 1102320944 24930704 9636352
02:37:09 6013 8192000000 10003691120 10394685440 1079921664 11.647 100 41 83 0 1107477440 18520496 9035280
02:38:38 25433 8192000000 10047041920 10447310848 1097873392 11.817 100 41 80 3 1151539824 44724704 8941728
02:40:09 37728 8192000000 10075299536 10470998016 1109012960 11.923 100 47 94 0 1187633120 68955936 9664224
02:41:51 28227 8192000000 10085621152 10497519616 1118493424 12.013 100 41 80 0 1194807200 67846688 8467088
02:43:24 15150 8192000000 10116307888 10535985152 1128497104 12.107 100 51 101 1 1234808640 94827776 11483760
02:44:44 26236 8192000000 10136096144 10543992832 1135833968 12.176 100 55 110 0 1241092688 93065600 12193120
02:46:16 15906 8192000000 10179054064 10587131904 1151301728 12.322 100 58 117 3 1279289920 115701552 12286640
02:47:52 9147 8192000000 10189965408 10601459712 1142989936 12.244 98 54 107 0 1284718128 129706384 12021808
02:49:04 2808 8192000000 10207089168 10611838976 1166529136 12.464 100 74 147 1 1305891760 123457376 15905248
02:50:11 4957 8192000000 10225830544 10625871872 1160524608 12.408 100 74 146 1 1329281392 153019536 15737248
02:51:21 1450 8192000000 10272874112 10668630016 1164459808 12.445 100 87 172 1 1377673088 194845808 18367472
02:52:26 2163 8192000000 10282953504 10683994112 1159749472 12.401 100 79 156 1 1383741808 207143808 16848528
02:53:34 4184 8192000000 10315194112 10715631616 1160408480 12.407 100 82 162 1 1419077712 239355152 19314080
02:54:43 3981 8192000000 10338271776 10736107520 1154833968 12.355 99 89 176 0 1423056224 247593232 20629024
02:55:50 1967 8192000000 10344413296 10765201408 1149674176 12.306 100 87 171 0 1449024800 279142528 20208096
02:56:59 2441 8192000000 10377672432 10775011328 1167595520 12.474 100 94 186 0 1470897264 281664032 21637712
02:58:06 1290 8192000000 10437109120 10832777216 1167857744 12.477 100 81 161 1 1527384704 340258176 19268784
02:59:12 3707 8192000000 10462704736 10875809792 1161482960 12.417 100 73 146 1 1557094080 377919840 17691280
03:00:19 1344 8192000000 10420875520 10825465856 1147967872 12.290 98 81 162 1 1516427824 348811680 19648272
03:01:27 4694 8192000000 10443049344 10873982976 1175005904 12.544 100 76 152 2 1547611984 354577712 18028368
03:02:39 17174 8192000000 10439294848 10887032832 1163064512 12.432 99 41 82 0 1523864608 350213440 10586656
03:03:59 22529 8192000000 10455493264 10924982272 1169559840 12.493 100 52 104 1 1573212752 390900592 12752320
03:05:48 4514 8192000000 10391796704 10850078720 1154642832 12.353 100 36 71 0 1498059792 334160720 9256240
03:06:56 25239 8192000000 10309048896 10798702592 1119070960 12.018 98 22 44 0 1387364576 261741040 6552576
03:08:26 42394 8192000000 10114343296 10590691328 1135042160 12.169 99 7 14 0 1215186016 78605216 1538640
03:10:09 34575 8192000000 10060923360 10488217600 1109499248 11.928 97 45 90 0 1139644336 20357072 9788016
03:12:02 24811 8192000000 10094699584 10529124352 1144584752 12.259 100 29 58 1 1184395648 33896816 5914080
03:13:37 46238 8192000000 10090224640 10521653248 1134674368 12.165 99 28 55 1 1178702048 38647840 5379840
03:15:26 158962 8192000000 10106526768 10590650368 1137333872 12.190 99 28 56 2 1229067280 85838960 5894448
03:19:19 16131 8192000000 10105101632 10534912000 1135796784 12.176 99 47 89 1 1193233600 47838624 9598192
03:20:36 43065 8192000000 10111736848 10569170944 1150464368 12.314 100 36 72 1 1215297520 56642560 8190592
03:22:22 37445 8192000000 10114431520 10558169088 1134822608 12.167 99 18 36 1 1203141520 63990000 4328912
03:24:28 106869 8192000000 10161760976 10616168448 1126828944 12.091 98 23 46 1 1266382224 133763296 5789984
03:27:16 88887 8192000000 10085915808 10616565760 1143738800 12.251 100 19 38 0 1272431136 124341024 4351312
03:29:45 43979 8192000000 10082121616 10512052224 1152113056 12.329 100 50 100 1 1198400688 35065824 11221808
03:31:31 14461 8192000000 10098485264 10528931840 1142679056 12.241 100 47 92 1 1193564208 41076416 9808736
03:32:54 100327 8192000000 10097943952 10629517312 1106684016 11.901 97 13 26 1 1154620032 45316448 2619568
03:35:35 80640 8192000000 10086172240 10550001664 1102346128 11.860 97 19 38 1 1135177616 28280944 4550544
03:38:01 93153 8192000000 10104407568 10591232000 1104544848 11.881 97 12 24 1 1178119936 71135392 2439696
03:40:35 76420 8192000000 10055611280 10498850816 1093279712 11.774 97 29 58 1 1123279216 23274880 6724624
03:43:26 48767 8192000000 10064223616 10502823936 1102063296 11.857 98 29 58 0 1138794992 29592416 7139280
03:45:23 58306 8192000000 10064652048 10519793664 1096289776 11.802 98 18 36 0 1163209776 62690064 4229936
03:47:26 54246 8192000000 10063034176 10542948352 1122422544 12.050 99 10 20 0 1163259264 38639696 2197024
03:50:25 54863 8192000000 10045804048 10539163648 1094386880 11.784 97 10 18 0 1128601456 32577728 1636848
03:52:25 33041 8192000000 10026221776 10468499456 1091596112 11.758 97 9 18 0 1108648896 15058192 1994592
03:53:58 61511 8192000000 10143818256 10579460096 1122630832 12.052 99 24 48 0 1216290240 88440848 5218560
03:56:11 53868 8192000000 10033048400 10484486144 1093650288 11.777 98 13 26 1 1140668944 44303152 2715504
03:58:05 55453 8192000000 10142612288 10599174144 1123302960 12.058 99 31 61 1 1231878656 102125696 6450000
04:00:06 66289 8192000000 10106678112 10595991552 1101601424 11.853 97 22 44 0 1205368000 99326608 4439968
04:02:13 73288 8192000000 10100107520 10552242176 1120469456 12.031 99 17 34 1 1168865120 45008896 3386768
04:04:53 16815 8192000000 10083180832 10506641408 1130638400 12.127 99 47 94 0 1163582416 23240080 9703936
04:06:13 76370 8192000000 10081770368 10527547392 1084914832 11.694 95 31 62 0 1135894752 44312928 6666992
04:08:52 42685 8192000000 10080515888 10557231104 1130371536 12.125 99 16 32 0 1194702416 60834928 3495952
04:10:37 22553 8192000000 10068795760 10569535488 1128694752 12.109 99 6 12 0 1170648544 40435296 1518496
04:12:02 37674 8192000000 10062915568 10531373056 1122947328 12.055 99 20 40 0 1175793184 48337856 4508000
04:13:54 73926 8192000000 10075144352 10564333568 1117114272 12.000 99 10 20 0 1191057488 71778096 2165120
04:16:09 47208 8192000000 10121834208 10560716800 1131838224 12.139 99 19 38 1 1214981808 78238000 4905584
04:17:59 67296 8192000000 10069061792 10519347200 1071400128 11.565 93 21 42 0 1123007376 47701296 3905952
04:20:07 66051 8192000000 10083793712 10543243264 1102006384 11.857 97 25 50 1 1167919088 60820640 5092064
04:22:13 43937 8192000000 10098531616 10543718400 1123400864 12.059 98 39 78 0 1179530416 48688512 7441040
04:23:58 40613 8192000000 10051563792 10479095808 1104894064 11.884 98 26 51 1 1140027184 29996880 5136240
04:25:39 14082 8192000000 10106466688 10548371456 1119787632 12.025 100 52 105 1 1196136368 66309840 10038896
04:26:54 85480 8192000000 10089332752 10521571328 1105750688 11.892 99 61 122 1 1181817440 63930688 12136064
04:29:58 64243 8192000000 10058560384 10512920576 1100111392 11.839 98 6 12 0 1150235280 48670768 1453120
04:33:20 47412 8192000000 10105009824 10540621824 1114767552 11.978 98 29 58 0 1175084960 53766800 6550608
04:35:19 77445 8192000000 10115896912 10582093824 1121746480 12.043 99 20 39 2 1210972512 84838672 4387360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2021 04:38:28 to 03-11-2021 04:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 67 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2021 04:38:44 to 03-11-2021 04:38:46.

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          237344
MON$OLDEST_ACTIVE               238876
MON$OLDEST_SNAPSHOT             238876
MON$NEXT_TRANSACTION            238876
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                       2050976
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 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 ---
:201: 
:202: mscs-test-161.budzdorov.ru    Wed Nov  3 04:37:22 2021
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Wed Nov  3 04:37:23 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Wed Nov  3 04:37:25 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:212: 
--- end of diff output ---

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

03.11.21 04:38:47. 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-11-03 04:38:49 No severe PSQL-related problems occured
03.11.21 04:38:50. End of report.