Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2021 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16568420 kB
MemAvailable 31499004 kB
Buffers 0 kB
Cached 15150236 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-12 07:37:08
DTS_END                         2021-12-12 07:37:08.1040
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/54472
TRN_ID                          233956
ATT_ID                          653
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: 9229.24 19468 1107509
customer order: creation 165.73 403 19887
customer order: refuse 14.73 327 1768
order to supplier: creation 43.62 1471 5234
order to supplier: removal 3.36 111609 403
invoice (draft): creation 44.43 3551 5331
invoice (draft): removal 53.91 872 6469
invoice accept: apply 41.11 18491 4933
invoice accept: cancel 48.73 875 5848
customer reserve: creation 3758.63 185 451035
customer reserve: removal 4769.63 63 572355
realization accept: apply 81.84 166 9821
realization accept: cancel 79.94 120 9593
payment from customer: creation 56.08 118 6729
payment from customer: removal 5.69 82 683
payment to supplier: creation 48.37 128 5804
payment to supplier: removal 3.30 69 396
service: total inventory turnovers 3.73 3860 447
service: total monetary turnovers 5.69 599 683
service: aggregate perf. data 0.71 23617 85
service: refresh index statistics 0.04 222760 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2021 07:38:45 to 12-12-2021 07:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5566
TEST_TIME 2 5377
TEST_TIME 3 5424
TEST_TIME 4 5477
TEST_TIME 5 5335
TEST_TIME 6 5412
TEST_TIME 7 5199
TEST_TIME 8 5314
TEST_TIME 9 5503
TEST_TIME 10 6126
TEST_TIME 11 6420
TEST_TIME 12 6642
TEST_TIME 13 7054
TEST_TIME 14 7212
TEST_TIME 15 7224
TEST_TIME 16 7864
TEST_TIME 17 7887
TEST_TIME 18 8070
TEST_TIME 19 8569
TEST_TIME 20 8570
TEST_TIME 21 9232
TEST_TIME 22 9582
TEST_TIME 23 9430
TEST_TIME 24 9591
TEST_TIME 25 10141
TEST_TIME 26 11641
TEST_TIME 27 11329
TEST_TIME 28 11205
TEST_TIME 29 10916
TEST_TIME 30 10841
TEST_TIME 31 10576
TEST_TIME 32 10638
TEST_TIME 33 10540
TEST_TIME 34 10391
TEST_TIME 35 10362
TEST_TIME 36 10167
TEST_TIME 37 10094
TEST_TIME 38 10015
TEST_TIME 39 9881
TEST_TIME 40 9907
TEST_TIME 41 9833
TEST_TIME 42 9733
TEST_TIME 43 9853
TEST_TIME 44 9824
TEST_TIME 45 9835
TEST_TIME 46 9980
TEST_TIME 47 9844
TEST_TIME 48 9932
TEST_TIME 49 9922
TEST_TIME 50 9953
TEST_TIME 51 9962
TEST_TIME 52 10048
TEST_TIME 53 9965
TEST_TIME 54 10079
TEST_TIME 55 10095
TEST_TIME 56 9955
TEST_TIME 57 9846
TEST_TIME 58 9724
TEST_TIME 59 9687
TEST_TIME 60 9667
TEST_TIME 61 9652
TEST_TIME 62 9640
TEST_TIME 63 9580
TEST_TIME 64 9516
TEST_TIME 65 9473
TEST_TIME 66 9439
TEST_TIME 67 9436
TEST_TIME 68 9438
TEST_TIME 69 9457
TEST_TIME 70 9636
TEST_TIME 71 9649
TEST_TIME 72 9717
TEST_TIME 73 9648
TEST_TIME 74 9646
TEST_TIME 75 9682
TEST_TIME 76 9685
TEST_TIME 77 9681
TEST_TIME 78 9607
TEST_TIME 79 9531
TEST_TIME 80 9498
TEST_TIME 81 9465
TEST_TIME 82 9391
TEST_TIME 83 9367
TEST_TIME 84 9433
TEST_TIME 85 9379
TEST_TIME 86 9406
TEST_TIME 87 9410
TEST_TIME 88 9363
TEST_TIME 89 9446
TEST_TIME 90 9418
TEST_TIME 91 9468
TEST_TIME 92 9490
TEST_TIME 93 9471
TEST_TIME 94 9517
TEST_TIME 95 9489
TEST_TIME 96 9513
TEST_TIME 97 9511
TEST_TIME 98 9445
TEST_TIME 99 9389
TEST_TIME 100 9379
TEST_TIME 101 9325
TEST_TIME 102 9304
TEST_TIME 103 9290
TEST_TIME 104 9247
TEST_TIME 105 9260
TEST_TIME 106 9236
TEST_TIME 107 9217
TEST_TIME 108 9271
TEST_TIME 109 9286
TEST_TIME 110 9270
TEST_TIME 111 9242
TEST_TIME 112 9360
TEST_TIME 113 9362
TEST_TIME 114 9322
TEST_TIME 115 9346
TEST_TIME 116 9389
TEST_TIME 117 9377
TEST_TIME 118 9378
TEST_TIME 119 9302
TEST_TIME 120 9231

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-12-2021 07:38:49 to 12-12-2021 07:38:52.

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 1524357 1524357 0 0.00 0 38474 7 0 0
doc_list_biud 1524357 1524357 0 0.00 0 29202 30 0 0
sp_add_invoice_to_stock 5049 4933 116 2.29 33 241489 18492 0 116
sp_cancel_adding_invoice 5848 5848 0 0.00 4 73116 876 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 14150 328 0 0
sp_cancel_customer_reserve 572355 572355 0 0.00 1 29076 63 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 5259 82 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 3632 70 0 0
sp_cancel_supplier_invoice 6481 6469 12 0.18 2 20892 873 0 12
sp_cancel_supplier_order 403 403 0 0.00 5 1090698 111609 0 0
sp_cancel_write_off 9593 9593 0 0.00 1 7239 120 0 0
sp_client_order 19887 19887 0 0.00 4 31486 404 0 0
sp_customer_reserve 451035 451035 0 0.00 0 104809 186 0 0
sp_fill_shopping_cart 30431 30431 0 0.00 1 17942 39 0 0
sp_fill_shopping_cart_clo_res 451030 451030 0 0.00 0 17905 14 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 31460 375 0 0
sp_kill_cost_storno 16520 16520 0 0.00 0 38464 57 0 0
sp_kill_qstorno_ret_qs2qd 1161175 1161175 0 0.00 0 29065 34 0 0
sp_kill_qty_storno 611185 611185 0 0.00 0 29202 74 0 0
sp_lock_selected_doc 20466 20466 0 0.00 0 8911 5 0 0
sp_make_cost_storno 27282 27282 0 0.00 0 17345 56 0 0
sp_multiply_rows_for_pdistr 27282 27282 0 0.00 0 18355 30 0 0
sp_multiply_rows_for_qdistr 19887 19887 0 0.00 0 13244 204 0 0
sp_pay_from_customer 6729 6729 0 0.00 4 18368 118 0 0
sp_pay_to_supplier 5804 5804 0 0.00 4 11770 128 0 0
sp_payment_common 12533 12533 0 0.00 1 12133 18 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 14110 303 0 0
sp_qd_handle_on_invoice_upd_sts 10776 10776 0 0.00 0 29201 326 0 0
sp_qd_handle_on_reserve_upd_sts 9821 9821 0 0.00 0 9103 53 0 0
sp_reserve_write_off 9821 9821 0 0.00 7 11420 166 0 0
sp_supplier_invoice 5331 5331 0 0.00 7 80870 3552 0 0
sp_supplier_order 5234 5234 0 0.00 6 43110 1472 0 0
srv_aggregate_perf_data 85 85 0 0.00 2752 368700 23617 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2050 156994 48757 0 0
srv_find_qd_qs_mism 1060975 1060975 0 0.00 0 14550 6 0 0
srv_make_invnt_saldo 447 447 0 0.00 67 97260 3861 0 0
srv_make_money_saldo 683 683 0 0.00 0 18062 600 0 0
srv_recalc_idx_stat 5 5 0 0.00 157211 317865 222760 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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 4933 4933 0 0.00 0 14674 182 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 11232 152 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 2211 9 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 8086 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 299 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1687 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 7781 30 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 25302 1544 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 2147 5 0 0
t$perf-norm:sp_client_order 19887 19887 0 0.00 0 7692 17 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 5097 12 0 0
t$perf-norm:sp_pay_from_customer 6729 6729 0 0.00 0 5172 8 0 0
t$perf-norm:sp_pay_to_supplier 5804 5804 0 0.00 0 9613 17 0 0
t$perf-norm:sp_reserve_write_off 9821 9821 0 0.00 0 5786 10 0 0
t$perf-norm:sp_supplier_invoice 5331 5331 0 0.00 0 6161 18 0 0
t$perf-norm:sp_supplier_order 5234 5234 0 0.00 0 9420 18 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 428 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 60 2 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 3336 22 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 519 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 2 0 0
v_add_invoice_to_stock 10082 9850 232 2.30 0 8377 19 0 232
v_all_customers 35742 35742 0 0.00 0 109 1 0 0
v_all_suppliers 29684 29684 0 0.00 0 22 1 0 0
v_all_wares 39772 39772 0 0.00 0 96 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 8194 15 0 0
v_cancel_client_order 3536 3536 0 0.00 0 5545 7 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2439 5 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 439 3 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2190 9 0 0
v_cancel_supplier_invoice 814 790 24 2.94 0 1407 14 0 24
v_cancel_supplier_order 806 806 0 0.00 0 4028 10 0 0
v_cancel_write_off 1956 1956 0 0.00 0 7209 20 0 0
v_max_id_clo_ord 5234 5234 0 0.00 0 3091 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 8066 16 0 0
v_max_id_ord_sup 5311 5311 0 0.00 0 4509 4 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 2264 3 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 4739 4 0 0
v_min_id_clo_ord 5234 5234 0 0.00 0 5942 7 0 0
v_min_id_clo_res 956 956 0 0.00 0 3264 13 0 0
v_min_id_ord_sup 5311 5311 0 0.00 0 6624 9 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 4322 10 0 0
v_min_non_paid_realizn 6729 6729 0 0.00 0 6315 11 0 0
v_our_firm 10156 10156 0 0.00 0 15 1 0 0
v_reserve_write_off 19640 19640 0 0.00 0 6343 5 0 0
x_lock_depdocs_on_canc_invoice 11922 11922 0 0.00 0 24429 100 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 13536 318 0 0
x_make_qstorno_1000_1200 5234 5234 0 0.00 2 43095 1403 0 0
x_make_qstorno_1200_2000 5331 5331 0 0.00 3 80803 3397 0 0
x_make_qstorno_2100_3300 451035 451035 0 0.00 0 104807 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2021 07:38:53 to 12-12-2021 07:38:57.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:13 22330 8192000000 9717969008 10013941760 792975232 8.825 100 85 169 1 820792800 7712768 20104800
05:09:40 28042 8192000000 9762921040 10070601728 839070128 9.290 100 90 180 2 873437552 13297312 21070112
05:11:11 15686 8192000000 9801028832 10120507392 867745024 9.578 100 81 162 0 907708864 21332912 18630928
05:12:30 13412 8192000000 9877070976 10207436800 913607232 10.033 100 84 168 0 979748944 47225696 18916016
05:13:52 6433 8192000000 9907168384 10251018240 960230064 10.491 100 78 156 0 1015267584 38972192 16065328
05:15:01 5621 8192000000 9968749024 10320809984 994493104 10.825 100 77 151 3 1084794832 74914112 15387616
05:16:09 3447 8192000000 10005763600 10368708608 1022636400 11.097 100 95 189 1 1120190640 78937792 18616448
05:17:14 1356 8192000000 10020279776 10387709952 1046709328 11.329 100 89 177 1 1135189024 70903488 17576208
05:18:16 28201 8192000000 10034326720 10418003968 1058581376 11.443 100 68 134 1 1151174416 78347936 14245104
05:19:45 7517 8192000000 9998234592 10394972160 1067242528 11.526 100 42 84 0 1108182176 31985776 8953872
05:20:53 7039 8192000000 10006306816 10406326272 1075339920 11.603 100 40 80 1 1132068528 48190272 8538336
05:22:00 5740 8192000000 10004454192 10402877440 1085520400 11.700 100 43 84 2 1119790368 24864720 9405248
05:23:10 24360 8192000000 10019805952 10407374848 1089974048 11.742 100 63 126 0 1119781904 15184240 14623616
05:24:35 5938 8192000000 10023399136 10426028032 1094460176 11.785 100 44 90 0 1139301520 35813280 9028064
05:25:43 6269 8192000000 10011390784 10413985792 1100456432 11.842 100 37 74 0 1127978512 18557088 8964992
05:26:50 64491 8192000000 9997341568 10404671488 1043293600 11.296 96 25 50 0 1072463200 23709888 5459712
05:28:57 10864 8192000000 9976921280 10366935040 1021060992 11.082 97 40 80 1 1045788464 15955456 8772016
05:30:08 4900 8192000000 9934190912 10321436672 1002302336 10.901 98 19 38 0 1032048768 25440400 4306032
05:31:15 5714 8192000000 9940566000 10324197376 1029864608 11.167 100 38 75 2 1053223248 15558624 7800016
05:32:22 28544 8192000000 9930721776 10325938176 989756400 10.779 96 28 56 0 1014473232 18534704 6182128
05:33:51 4037 8192000000 9960333808 10347732992 1042812096 11.292 99 38 75 1 1058497792 7608656 8077040
05:34:57 2532 8192000000 9981091616 10367946752 1058373920 11.441 100 50 97 4 1087518672 18790720 10354032
05:36:01 8568 8192000000 9980912480 10391764992 1065121440 11.505 100 34 64 1 1089135248 15800624 8213184
05:37:09 78483 8192000000 9993903584 10393030656 1085264944 11.698 100 39 78 0 1105070416 11716560 8088912
05:39:36 4956 8192000000 10086520720 10475307008 1113639344 11.967 100 72 141 1 1187740384 60268960 13832080
05:40:46 24591 8192000000 10116257296 10518192128 1130323440 12.124 100 52 104 1 1224174528 82946464 10904624
05:42:15 10612 8192000000 10126720016 10543374336 1141663216 12.231 100 40 80 0 1239809024 89278160 8867648
05:43:34 20435 8192000000 10177500992 10590834688 1146464704 12.276 100 46 93 2 1294119664 137303024 10351936
05:45:09 7935 8192000000 10233668176 10639601664 1166724608 12.466 100 57 114 1 1343939856 164853936 12361312
05:46:22 6330 8192000000 10264759072 10675335168 1168724048 12.485 100 54 108 0 1367168960 185680128 12764784
05:47:32 2048 8192000000 10302233824 10706530304 1174334288 12.537 100 75 150 0 1402552256 211737520 16480448
05:48:43 3043 8192000000 10327454496 10734059520 1178944720 12.580 100 71 142 1 1438289392 243584592 15760080
05:49:50 3900 8192000000 10369363984 10774999040 1185961152 12.646 99 78 155 1 1471868992 268101232 17806608
05:50:58 4643 8192000000 10322863584 10745823232 1153517824 12.343 99 64 125 2 1431465904 262757536 15190544
05:52:08 4358 8192000000 10388732144 10795819008 1165182304 12.452 100 75 149 1 1484828320 302481360 17164656
05:53:16 7874 8192000000 10392817200 10843828224 1162642608 12.428 99 42 82 1 1496338368 323263328 10432432
05:54:33 19658 8192000000 10410153744 10821570560 1116412512 11.993 97 45 90 0 1478162368 350425440 11324416
05:56:02 5996 8192000000 10381104976 10838568960 1142835616 12.242 100 40 79 1 1479866928 327313728 9717584
05:57:13 5706 8192000000 10378913536 10814599168 1149000784 12.300 100 44 88 1 1482651520 322691744 10958992
05:58:25 6864 8192000000 10360051520 10819227648 1125819920 12.082 100 52 101 0 1462898672 324416128 12662624
05:59:33 14115 8192000000 10323828352 10752638976 1135401584 12.172 100 30 59 2 1417629872 274850176 7378112
06:00:50 21452 8192000000 10379917728 10815557632 1133874592 12.158 100 24 48 1 1480861392 340082816 6903984
06:02:12 39565 8192000000 10323616608 10764648448 1103301280 11.869 99 17 34 0 1435259920 327277808 4680832
06:03:52 58884 8192000000 10069065616 10495840256 1103598192 11.872 98 12 24 1 1160208896 54048320 2562384
06:05:53 66450 8192000000 10120645776 10553278464 1119678688 12.024 99 25 50 0 1188166464 63808704 4679072
06:08:15 156993 8192000000 10078000480 10519326720 1090872496 11.751 96 24 49 1 1159602224 63996944 4732784
06:11:52 137580 8192000000 10107305040 10537312256 1094898880 11.789 96 37 73 0 1153218400 50244624 8074896
06:15:10 33496 8192000000 10154939184 10589310976 1135868912 12.177 99 46 91 0 1224428224 77939936 10619376
06:16:45 59123 8192000000 10179621504 10626781184 1144067616 12.254 100 19 37 0 1277987792 129447696 4472480
06:18:45 66775 8192000000 10122949312 10581196800 1146054672 12.272 100 18 36 2 1242387328 91999344 4333312
06:20:54 69041 8192000000 10120125600 10557018112 1109887792 11.931 97 19 38 0 1190648112 75577104 5183216
06:23:03 65183 8192000000 10087557408 10522488832 1117226880 12.001 98 21 43 1 1181124960 59450608 4447472
06:25:16 68069 8192000000 10125448640 10631475200 1137418528 12.191 99 20 40 0 1262272592 120427296 4426768
06:27:29 66905 8192000000 10103850880 10590670848 1114309648 11.973 97 20 40 1 1192622528 73656112 4656768
06:29:39 56899 8192000000 10087475104 10529120256 1143436416 12.248 100 15 30 0 1178874176 32574592 2863168
06:31:37 53567 8192000000 10102400896 10602684416 1153140448 12.339 100 13 26 0 1219659712 63841696 2677568
06:33:31 81949 8192000000 10073316528 10514661376 1131191424 12.133 98 27 54 0 1147114064 9921200 6001440
06:35:56 6810 8192000000 10071049776 10499727360 1114114336 11.971 97 33 66 0 1145505504 24534160 6857008
06:37:05 55479 8192000000 10075885040 10525630464 1097171568 11.811 96 21 42 0 1154526720 52942752 4412400
06:39:13 48611 8192000000 10065280960 10534760448 1130147648 12.123 100 20 40 0 1195802144 61552000 4102496
06:41:03 54413 8192000000 10055197920 10532179968 1085140336 11.696 95 16 32 0 1112017952 23599584 3278032
06:43:11 34247 8192000000 10100220704 10536603648 1105665472 11.891 97 18 36 1 1174038688 64700784 3672432
06:44:54 77828 8192000000 10203402848 10671071232 1101234480 11.849 96 22 44 0 1261880512 155432768 5213264
06:47:12 63334 8192000000 10060685440 10535391232 1061895008 11.475 95 15 29 0 1131039376 65591888 3552480
06:49:28 31194 8192000000 10052537424 10528051200 1066536432 11.519 97 32 64 2 1159811936 86519120 6756384
06:51:03 13224 8192000000 10082447664 10515320832 1105697344 11.892 100 59 118 0 1173616144 56285552 11633248
06:52:20 40630 8192000000 10092160752 10538512384 1093017760 11.771 98 47 94 0 1183673616 81576608 9079248
06:54:03 99035 8192000000 10110224016 10582495232 1127070896 12.094 100 45 90 0 1207721184 71602752 9047536
06:56:52 26825 8192000000 10105917392 10560491520 1122127920 12.047 99 28 56 0 1193140256 65133856 5878480
06:58:21 51840 8192000000 10137474736 10622554112 1132178320 12.142 100 12 25 0 1237151504 102479040 2494144
07:00:15 83958 8192000000 10116087520 10630733824 1124063552 12.065 99 16 33 0 1234073744 106272160 3738032
07:03:11 89554 8192000000 10061342080 10527428608 1117270752 12.001 98 12 24 0 1152416192 32403504 2741936
07:05:44 78112 8192000000 10087531456 10571988992 1094856896 11.789 96 11 22 0 1147716032 50818400 2040736
07:08:02 62887 8192000000 10075174064 10535002112 1111753504 11.949 98 13 26 0 1188381584 74121856 2506224
07:10:05 99810 8192000000 10088935072 10572185600 1122934512 12.055 98 14 28 0 1196754912 71153232 2667168
07:12:54 71223 8192000000 10070316992 10552270848 1085647600 11.701 95 19 38 0 1157415632 68519088 3248944
07:15:09 89185 8192000000 10066280032 10512846848 1133444512 12.154 99 19 39 0 1156092864 18181904 4466448
07:17:44 61785 8192000000 10094407728 10561208320 1140949008 12.224 99 15 30 1 1177698704 33499744 3249952
07:19:47 48588 8192000000 10103987264 10583310336 1141956784 12.234 100 10 20 0 1219781936 75588240 2236912
07:21:42 94073 8192000000 10072594336 10530910208 1121714688 12.043 98 11 22 0 1221657344 96942576 3000080
07:24:24 71658 8192000000 10088353984 10643247104 1112724784 11.958 97 8 16 1 1221839520 107272688 1842048
07:26:46 36933 8192000000 10089896512 10535182336 1134234400 12.161 99 27 54 1 1158027040 18432224 5360416
07:28:32 80876 8192000000 10080905904 10633310208 1143934976 12.253 99 18 37 0 1275488032 127365840 4187216
07:31:03 41855 8192000000 10085424736 10520416256 1107453440 11.908 97 34 68 0 1151752096 37167472 7131184
07:32:49 33779 8192000000 10115921984 10548649984 1122898592 12.054 98 39 78 0 1182498624 52335152 7264880
07:34:23 90422 8192000000 10060632128 10545258496 1099989008 11.838 97 12 24 0 1139764736 37553904 2221824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-12-2021 07:38:59 to 12-12-2021 07:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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 12-12-2021 07:39:14 to 12-12-2021 07:39:16.

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          233161
MON$OLDEST_ACTIVE               234076
MON$OLDEST_SNAPSHOT             234076
MON$NEXT_TRANSACTION            234076
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                       2047008
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2682 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2682 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2682 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2682 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sun Dec 12 07:37:11 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sun Dec 12 07:37:12 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

12.12.21 07:39:17. 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-12 07:39:20 No severe PSQL-related problems occured
12.12.21 07:39:21. End of report.