Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.12.2021 10: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 16620040 kB
MemAvailable 31516068 kB
Buffers 0 kB
Cached 15067712 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-03 10:37:10
DTS_END                         2021-12-03 10:37:10.9480
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/57416
TRN_ID                          242878
ATT_ID                          629
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: 9196.77 25384 1103612
customer order: creation 176.15 415 21138
customer order: refuse 15.08 305 1810
order to supplier: creation 47.17 1398 5660
order to supplier: removal 3.41 113287 409
invoice (draft): creation 47.18 3256 5661
invoice (draft): removal 50.38 935 6046
invoice accept: apply 42.68 19020 5121
invoice accept: cancel 45.58 850 5469
customer reserve: creation 3994.91 184 479389
customer reserve: removal 4479.28 68 537514
realization accept: apply 85.43 185 10252
realization accept: cancel 77.09 122 9251
payment from customer: creation 60.33 106 7239
payment from customer: removal 6.46 103 775
payment to supplier: creation 50.93 120 6111
payment to supplier: removal 3.42 81 410
service: total inventory turnovers 3.98 3167 477
service: total monetary turnovers 6.41 492 769
service: aggregate perf. data 0.89 20299 107
service: refresh index statistics 0.03 343291 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-12-2021 10:38:34 to 03-12-2021 10:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7230
TEST_TIME 2 7233
TEST_TIME 3 6920
TEST_TIME 4 7256
TEST_TIME 5 6530
TEST_TIME 6 6338
TEST_TIME 7 6573
TEST_TIME 8 6600
TEST_TIME 9 7140
TEST_TIME 10 7535
TEST_TIME 11 7487
TEST_TIME 12 7900
TEST_TIME 13 7983
TEST_TIME 14 8065
TEST_TIME 15 8640
TEST_TIME 16 8552
TEST_TIME 17 8557
TEST_TIME 18 8594
TEST_TIME 19 8925
TEST_TIME 20 9122
TEST_TIME 21 9274
TEST_TIME 22 9471
TEST_TIME 23 9621
TEST_TIME 24 9911
TEST_TIME 25 10515
TEST_TIME 26 11867
TEST_TIME 27 11761
TEST_TIME 28 11598
TEST_TIME 29 11463
TEST_TIME 30 11166
TEST_TIME 31 10891
TEST_TIME 32 11061
TEST_TIME 33 10801
TEST_TIME 34 10804
TEST_TIME 35 10703
TEST_TIME 36 10713
TEST_TIME 37 10677
TEST_TIME 38 10511
TEST_TIME 39 10462
TEST_TIME 40 10390
TEST_TIME 41 10355
TEST_TIME 42 10361
TEST_TIME 43 10476
TEST_TIME 44 10493
TEST_TIME 45 10394
TEST_TIME 46 10399
TEST_TIME 47 10612
TEST_TIME 48 10647
TEST_TIME 49 10555
TEST_TIME 50 10649
TEST_TIME 51 10605
TEST_TIME 52 10721
TEST_TIME 53 10633
TEST_TIME 54 10442
TEST_TIME 55 10360
TEST_TIME 56 10251
TEST_TIME 57 10173
TEST_TIME 58 10140
TEST_TIME 59 10089
TEST_TIME 60 10119
TEST_TIME 61 10040
TEST_TIME 62 9925
TEST_TIME 63 9873
TEST_TIME 64 9930
TEST_TIME 65 9935
TEST_TIME 66 9939
TEST_TIME 67 9920
TEST_TIME 68 9928
TEST_TIME 69 9973
TEST_TIME 70 9972
TEST_TIME 71 9941
TEST_TIME 72 9987
TEST_TIME 73 10023
TEST_TIME 74 10042
TEST_TIME 75 10069
TEST_TIME 76 10006
TEST_TIME 77 9921
TEST_TIME 78 9883
TEST_TIME 79 9848
TEST_TIME 80 9840
TEST_TIME 81 9793
TEST_TIME 82 9794
TEST_TIME 83 9742
TEST_TIME 84 9717
TEST_TIME 85 9722
TEST_TIME 86 9717
TEST_TIME 87 9723
TEST_TIME 88 9726
TEST_TIME 89 9688
TEST_TIME 90 9674
TEST_TIME 91 9733
TEST_TIME 92 9740
TEST_TIME 93 9718
TEST_TIME 94 9687
TEST_TIME 95 9739
TEST_TIME 96 9675
TEST_TIME 97 9625
TEST_TIME 98 9610
TEST_TIME 99 9572
TEST_TIME 100 9533
TEST_TIME 101 9512
TEST_TIME 102 9468
TEST_TIME 103 9472
TEST_TIME 104 9506
TEST_TIME 105 9519
TEST_TIME 106 9474
TEST_TIME 107 9490
TEST_TIME 108 9511
TEST_TIME 109 9515
TEST_TIME 110 9506
TEST_TIME 111 9506
TEST_TIME 112 9498
TEST_TIME 113 9465
TEST_TIME 114 9412
TEST_TIME 115 9377
TEST_TIME 116 9338
TEST_TIME 117 9293
TEST_TIME 118 9287
TEST_TIME 119 9275
TEST_TIME 120 9205

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-12-2021 10:38:37 to 03-12-2021 10:38:40.

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 1545241 1545241 0 0.00 0 28194 7 0 0
doc_list_biud 1545240 1545240 0 0.00 0 27301 30 0 0
sp_add_invoice_to_stock 5255 5121 134 2.54 39 162334 19020 0 134
sp_cancel_adding_invoice 5469 5469 0 0.00 6 68869 851 0 0
sp_cancel_client_order 1810 1810 0 0.00 5 10660 306 0 0
sp_cancel_customer_reserve 537514 537514 0 0.00 1 27305 69 0 0
sp_cancel_pay_from_customer 775 775 0 0.00 3 4199 103 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3173 81 0 0
sp_cancel_supplier_invoice 6057 6046 11 0.18 3 23129 936 0 11
sp_cancel_supplier_order 409 409 0 0.00 10 1111677 113288 0 0
sp_cancel_write_off 9251 9251 0 0.00 1 9070 122 0 0
sp_client_order 21138 21138 0 0.00 4 26488 415 0 0
sp_customer_reserve 479389 479389 0 0.00 0 52719 185 0 0
sp_fill_shopping_cart 32441 32441 0 0.00 1 15643 41 0 0
sp_fill_shopping_cart_clo_res 479376 479376 0 0.00 0 22317 15 0 0
sp_get_clo_for_invoice 5102 5102 0 0.00 0 18418 297 0 0
sp_kill_cost_storno 15905 15905 0 0.00 0 6138 58 0 0
sp_kill_qstorno_ret_qs2qd 1090734 1090734 0 0.00 0 27294 37 0 0
sp_kill_qty_storno 575853 575853 0 0.00 0 27301 80 0 0
sp_lock_selected_doc 21279 21279 0 0.00 0 7460 4 0 0
sp_make_cost_storno 28704 28704 0 0.00 0 20681 54 0 0
sp_multiply_rows_for_pdistr 28704 28704 0 0.00 0 6563 29 0 0
sp_multiply_rows_for_qdistr 21138 21138 0 0.00 0 22351 210 0 0
sp_pay_from_customer 7239 7239 0 0.00 4 9403 107 0 0
sp_pay_to_supplier 6111 6111 0 0.00 5 12325 121 0 0
sp_payment_common 13350 13350 0 0.00 1 8751 19 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 10654 281 0 0
sp_qd_handle_on_invoice_upd_sts 10571 10571 0 0.00 0 22748 346 0 0
sp_qd_handle_on_reserve_upd_sts 10252 10252 0 0.00 0 20007 64 0 0
sp_reserve_write_off 10252 10252 0 0.00 6 20051 186 0 0
sp_supplier_invoice 5661 5661 0 0.00 7 78961 3257 0 0
sp_supplier_order 5660 5660 0 0.00 5 29243 1399 0 0
srv_aggregate_perf_data 107 107 0 0.00 2699 399355 20299 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2789 128725 47947 0 0
srv_find_qd_qs_mism 1053293 1053293 0 0.00 0 28192 7 0 0
srv_make_invnt_saldo 477 477 0 0.00 34 99005 3167 0 0
srv_make_money_saldo 769 769 0 0.00 1 17845 492 0 0
srv_recalc_idx_stat 4 4 0 0.00 148404 547927 343292 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 0 10645 166 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 1323 57 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 4803 14 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1458 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 775 775 0 0.00 0 931 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 3126 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 922 10 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 14061 1441 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 3674 9 0 0
t$perf-norm:sp_client_order 21138 21138 0 0.00 0 20758 20 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 251 2 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 12251 10 0 0
t$perf-norm:sp_pay_to_supplier 6111 6111 0 0.00 0 13772 12 0 0
t$perf-norm:sp_reserve_write_off 10252 10252 0 0.00 0 3971 6 0 0
t$perf-norm:sp_supplier_invoice 5661 5661 0 0.00 0 12812 21 0 0
t$perf-norm:sp_supplier_order 5660 5660 0 0.00 0 9076 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 140 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 771 20 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 5166 22 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 637 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10470 10202 268 2.55 0 6854 15 0 268
v_all_customers 38534 38534 0 0.00 0 83 1 0 0
v_all_suppliers 31494 31494 0 0.00 0 83 1 0 0
v_all_wares 42276 42276 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 727 7 0 0
v_cancel_client_order 3620 3620 0 0.00 0 2218 5 0 0
v_cancel_customer_prepayment 1550 1550 0 0.00 0 3953 17 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 3163 9 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 269 4 0 0
v_cancel_supplier_invoice 836 814 22 2.63 0 1946 15 0 22
v_cancel_supplier_order 818 818 0 0.00 0 1548 7 0 0
v_cancel_write_off 2012 2012 0 0.00 0 4229 15 0 0
v_max_id_clo_ord 5657 5657 0 0.00 0 2573 2 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2742 22 0 0
v_max_id_ord_sup 5645 5645 0 0.00 0 7204 5 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 623 2 0 0
v_max_non_paid_realizn 3980 3980 0 0.00 0 2876 5 0 0
v_min_id_clo_ord 5657 5657 0 0.00 0 2085 6 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 5616 9 0 0
v_min_id_ord_sup 5645 5645 0 0.00 0 6398 6 0 0
v_min_non_paid_invoice 6111 6111 0 0.00 0 8747 14 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 8199 8 0 0
v_our_firm 10260 10260 0 0.00 0 14 1 0 0
v_reserve_write_off 20502 20502 0 0.00 0 5064 6 0 0
x_lock_depdocs_on_canc_invoice 11108 11108 0 0.00 0 18290 120 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 14755 354 0 0
x_make_qstorno_1000_1200 5660 5660 0 0.00 2 29235 1323 0 0
x_make_qstorno_1200_2000 5661 5661 0 0.00 2 78943 3089 0 0
x_make_qstorno_2100_3300 479389 479389 0 0.00 0 52718 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-12-2021 10:38:41 to 03-12-2021 10:38:45.

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
08:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 27097 8192000000 9708954288 10006102016 788865216 8.783 100 88 176 0 818794544 7946832 21982496
08:09:49 12856 8192000000 9763090256 10072850432 844301808 9.343 100 84 168 0 875719168 11943552 19473808
08:11:09 12289 8192000000 9797519312 10114052096 868523568 9.585 100 86 172 0 907560032 19746096 19290368
08:12:34 9389 8192000000 9854978992 10184912896 902572160 9.924 100 78 156 0 960123760 40298176 17253424
08:13:49 8575 8192000000 9914033152 10258993152 948503488 10.376 100 82 164 0 1038701216 73098240 17099488
08:15:01 4814 8192000000 9948859968 10304020480 984342112 10.726 100 72 144 0 1066760816 67620352 14798352
08:16:08 4945 8192000000 9992442624 10356011008 1015369056 11.027 100 76 149 0 1108105616 78484048 14252512
08:17:14 1837 8192000000 10028192752 10394849280 1040316288 11.268 100 87 173 3 1147462144 89398000 17747856
08:18:17 1424 8192000000 10049900560 10420539392 1060921344 11.465 100 88 176 0 1161080032 81773680 18385008
08:19:19 5454 8192000000 10022897184 10412052480 1064915008 11.503 100 55 109 0 1132301104 56366304 11019792
08:20:25 3126 8192000000 10010548048 10400968704 1076896368 11.618 100 48 95 1 1133473472 47197344 9379760
08:21:30 12044 8192000000 10004371856 10411540480 1084213520 11.688 100 40 80 2 1124494784 31241152 9040112
08:22:43 2153 8192000000 10016464096 10411319296 1088473760 11.728 100 75 153 1 1131347408 25989200 16884448
08:23:50 7361 8192000000 10005659392 10412163072 1093421600 11.775 100 44 88 0 1136195376 33207072 9566704
08:25:01 5089 8192000000 10022271568 10424520704 1100531728 11.843 100 49 98 4 1141831072 31095552 10203792
08:26:08 60409 8192000000 10016604624 10433916928 1098521840 11.824 100 42 84 0 1124061840 16125344 9414656
08:28:11 4023 8192000000 9987374800 10377064448 1054290544 11.402 97 35 68 2 1076463376 14244864 7927968
08:29:16 8923 8192000000 9931424128 10309185536 989535280 10.777 95 36 72 0 1010609984 12730432 8344272
08:30:26 8659 8192000000 9931772608 10316922880 990487200 10.786 96 32 61 4 1012219008 15731216 6000592
08:31:40 46449 8192000000 9934628544 10328870912 980047792 10.685 95 34 68 1 1012890672 25492960 7349920
08:33:27 6939 8192000000 9954010752 10335002624 1042433584 11.288 100 39 78 0 1066853168 15502512 8917072
08:34:36 11508 8192000000 9975710064 10379022336 1056705088 11.425 100 22 43 0 1088542496 27854400 3983008
08:35:48 3447 8192000000 9969408688 10360086528 1065329936 11.507 100 42 85 1 1085071712 9865424 9876352
08:36:55 55779 8192000000 10009965280 10398113792 1072844912 11.579 100 49 94 2 1104190352 21916192 9429248
08:39:00 15574 8192000000 10055188160 10453389312 1108567040 11.919 100 51 102 0 1161599024 43119520 9912464
08:40:19 21585 8192000000 10095716000 10501406720 1121886320 12.045 100 42 82 1 1205988432 75748512 8353600
08:41:45 20446 8192000000 10100478864 10514284544 1135800224 12.176 100 38 76 1 1229353264 84531728 9021312
08:43:08 25952 8192000000 10150314256 10566807552 1146657888 12.278 100 34 67 0 1263528448 109327888 7542672
08:44:38 17229 8192000000 10205148288 10608971776 1149006080 12.300 99 65 129 0 1305921408 143447696 13467632
08:46:03 3058 8192000000 10222844896 10650583040 1162228720 12.424 100 71 141 0 1329492560 151850704 15413136
08:47:13 6902 8192000000 10224756576 10630766592 1163284352 12.434 100 56 112 1 1316339280 141586240 11468688
08:48:31 7077 8192000000 10249131136 10650083328 1159755680 12.401 100 67 133 0 1355992400 181696912 14539808
08:49:41 4595 8192000000 10256297728 10657824768 1152012720 12.328 98 65 131 1 1344730816 178479024 14239072
08:50:49 8508 8192000000 10319425728 10725511168 1174929056 12.543 100 68 134 0 1418027488 228275376 14823056
08:52:03 2788 8192000000 10338370368 10746224640 1156864800 12.374 100 81 160 0 1441100112 265883872 18351440
08:53:11 11808 8192000000 10336701904 10768818176 1161872144 12.421 100 43 86 5 1432607456 260267216 10468096
08:54:27 8892 8192000000 10419940400 10830942208 1165066464 12.451 100 67 131 0 1517317280 336085744 16165072
08:55:39 4626 8192000000 10418050624 10819002368 1153316752 12.341 100 67 133 0 1511561664 342617376 15627536
08:56:47 3945 8192000000 10373695776 10775928832 1147073888 12.282 100 73 146 0 1473445728 309915168 16456672
08:57:55 9663 8192000000 10425109056 10837921792 1140579488 12.221 99 53 106 1 1516728944 361441152 14708304
08:59:06 15594 8192000000 10381393600 10823401472 1135922560 12.177 99 27 54 0 1473646912 330679632 7044720
09:00:38 8876 8192000000 10421257456 10874617856 1141935760 12.234 100 33 66 0 1520417824 369617360 8864704
09:01:49 20247 8192000000 10389298496 10864238592 1128977632 12.112 100 30 60 0 1506529024 369222624 8328768
09:04:27 73288 8192000000 10031910512 10476179456 1070576576 11.558 95 19 38 1 1091605248 16650896 4377776
09:06:56 78820 8192000000 10087492336 10510004224 1115214592 11.982 99 35 69 1 1168590800 45512176 7864032
09:09:24 65811 8192000000 10047747984 10506436608 1114884208 11.979 99 20 38 1 1158558800 39681104 3993488
09:11:32 40301 8192000000 10114241936 10547490816 1123803072 12.063 98 19 38 0 1186553312 58698976 4051264
09:13:14 21751 8192000000 10130501792 10573766656 1132068048 12.141 99 32 62 2 1233870544 95204224 6598272
09:14:44 27554 8192000000 10096320480 10542567424 1120763264 12.034 99 9 18 0 1187008560 64598320 1646976
09:16:12 48297 8192000000 10119267904 10560737280 1120347232 12.030 98 37 74 0 1231184208 101926112 8910864
09:18:01 54372 8192000000 10110033824 10589474816 1132019936 12.140 99 14 28 1 1231877840 96376432 3481472
09:20:04 47538 8192000000 10118488208 10579365888 1129676864 12.118 98 19 38 0 1229599664 95728624 4194176
09:22:12 35230 8192000000 10165387072 10597773312 1154806912 12.355 100 46 91 1 1244281776 79483616 9991248
09:23:55 69880 8192000000 10098099456 10693488640 1135152624 12.170 98 11 20 2 1312286432 174408016 2725792
09:26:18 42146 8192000000 10117992880 10552225792 1125559536 12.079 98 39 78 1 1181851808 48544544 7747728
09:28:04 45878 8192000000 10155430016 10599145472 1141664048 12.231 99 21 42 0 1217117920 70914688 4539184
09:29:51 110367 8192000000 10076169360 10536181760 1120231312 12.029 97 15 30 0 1169186256 45636976 3317968
09:32:54 75897 8192000000 10100885808 10523725824 1147128064 12.283 100 45 91 1 1190019216 33595056 9296096
09:35:10 64599 8192000000 10098335104 10572685312 1109253200 11.925 97 21 42 0 1193697296 79968528 4475568
09:37:16 64636 8192000000 10072704800 10508836864 1064940208 11.504 94 18 36 0 1098137264 29685392 3511664
09:39:21 68361 8192000000 10144283488 10601390080 1108249600 11.916 98 16 32 0 1210140304 98229008 3661696
09:41:30 128725 8192000000 10069109168 10514825216 971829296 10.605 86 14 28 0 1032234864 57755184 2650384
09:44:40 68406 8192000000 10059849856 10498789376 1097750496 11.816 98 14 28 0 1161607920 61019120 2838304
09:46:49 83756 8192000000 10110091104 10533232640 1114717104 11.977 99 18 36 0 1150682080 32566320 3398656
09:49:16 49835 8192000000 10114254672 10556297216 1125428288 12.078 99 37 74 0 1219369312 86521728 7419296
09:51:40 114583 8192000000 10092268416 10558918656 1109315360 11.926 97 23 46 1 1172118064 58148800 4653904
09:54:34 98148 8192000000 10137497024 10604367872 1143790592 12.251 100 24 48 2 1250352480 101617344 4944544
09:57:13 39115 8192000000 10071367216 10551513088 1128667024 12.109 99 21 42 0 1207187344 73752224 4768096
09:59:12 45068 8192000000 10052274128 10525646848 1125726432 12.081 99 16 32 0 1192247648 62516576 4004640
10:00:59 76435 8192000000 10092022192 10553933824 1119558560 12.023 98 12 24 0 1178602304 56409504 2634240
10:03:28 84347 8192000000 10085503488 10562691072 1127993184 12.102 98 22 43 2 1172983648 39774864 5215600
10:05:53 33622 8192000000 10077201280 10514481152 1124058464 12.065 98 28 54 1 1155330448 25670128 5601856
10:07:28 45291 8192000000 10134357600 10568474624 1103287088 11.869 96 37 74 0 1200285600 89502208 7496304
10:09:17 35979 8192000000 10045180000 10478481408 1093028160 11.771 96 12 23 0 1130403584 35583072 1792352
10:10:54 30317 8192000000 10102080656 10539769856 1131492768 12.135 100 40 80 0 1186798320 47345872 7959680
10:12:24 106056 8192000000 10115952752 10578378752 1138067216 12.197 100 19 38 2 1213944624 71666288 4211120
10:15:42 55342 8192000000 10068912688 10560004096 1123351072 12.059 98 7 14 0 1165821696 41337744 1132880
10:17:38 63741 8192000000 10067713456 10534658048 1121307408 12.039 98 13 25 0 1173444608 49146256 2990944
10:19:49 86038 8192000000 10083520416 10577059840 1104094272 11.876 96 26 52 0 1189060272 79265136 5700864
10:22:22 66194 8192000000 10091457056 10537005056 1096227824 11.802 96 17 34 0 1147728448 47724400 3776224
10:24:37 99642 8192000000 10087173120 10549473280 1102897936 11.865 97 19 38 0 1170062224 63027344 4136944
10:27:21 67108 8192000000 10094596112 10527473664 1051601968 11.376 93 32 64 1 1105043872 47011808 6430096
10:29:29 67792 8192000000 10067117152 10515501056 1089015968 11.733 96 19 38 0 1136345056 43478720 3850368
10:32:30 70422 8192000000 10044505616 10491875328 1098312512 11.822 98 6 12 0 1118412608 18955872 1144224
10:34:42 61565 8192000000 10038407792 10516451328 1085400128 11.699 97 5 10 0 1130765584 44200320 1165136
10:36:44 35191 8192000000 9008369536 9501679616 37100224 0.450 4 2 4 0 40019664 2848320 71120

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-12-2021 10:38:47 to 03-12-2021 10:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-12-2021 10:39:03 to 03-12-2021 10:39:05.

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          242680
MON$OLDEST_ACTIVE               243231
MON$OLDEST_SNAPSHOT             243231
MON$NEXT_TRANSACTION            243231
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                       2045728
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             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2673 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2673 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2673 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2673 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 ---
:59: 
:60: mscs-test-161.budzdorov.ru    Fri Dec  3 10:37:21 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Fri Dec  3 10:37:25 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

03.12.21 10:39:06. 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-03 10:39:08 No severe PSQL-related problems occured
03.12.21 10:39:10. End of report.