Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2021 13: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 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 16650000 kB
MemAvailable 31610752 kB
Buffers 0 kB
Cached 15015916 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-11-14 13:37:07
DTS_END                         2021-11-14 13:37:07.5020
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/43446
TRN_ID                          238513
ATT_ID                          638
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: 9552.46 20000 1146295
customer order: creation 176.23 397 21148
customer order: refuse 15.28 280 1834
order to supplier: creation 46.73 1498 5607
order to supplier: removal 3.43 111993 411
invoice (draft): creation 46.83 3402 5619
invoice (draft): removal 54.76 802 6571
invoice accept: apply 42.43 17852 5092
invoice accept: cancel 49.43 792 5931
customer reserve: creation 3953.63 177 474436
customer reserve: removal 4870.12 64 584414
realization accept: apply 84.71 175 10165
realization accept: cancel 78.10 109 9372
payment from customer: creation 60.22 104 7226
payment from customer: removal 5.95 75 714
payment to supplier: creation 49.40 125 5928
payment to supplier: removal 3.75 81 450
service: total inventory turnovers 3.61 3311 433
service: total monetary turnovers 6.90 480 828
service: aggregate perf. data 0.93 16087 112
service: refresh index statistics 0.03 242186 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2021 13:38:05 to 14-11-2021 13:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8588
TEST_TIME 2 7332
TEST_TIME 3 6639
TEST_TIME 4 6763
TEST_TIME 5 6423
TEST_TIME 6 6281
TEST_TIME 7 6689
TEST_TIME 8 6486
TEST_TIME 9 6513
TEST_TIME 10 6487
TEST_TIME 11 6695
TEST_TIME 12 7104
TEST_TIME 13 7128
TEST_TIME 14 7215
TEST_TIME 15 7571
TEST_TIME 16 7596
TEST_TIME 17 7883
TEST_TIME 18 8593
TEST_TIME 19 8651
TEST_TIME 20 9097
TEST_TIME 21 9171
TEST_TIME 22 9078
TEST_TIME 23 9730
TEST_TIME 24 9766
TEST_TIME 25 9829
TEST_TIME 26 10514
TEST_TIME 27 11484
TEST_TIME 28 11560
TEST_TIME 29 11329
TEST_TIME 30 11452
TEST_TIME 31 11261
TEST_TIME 32 11158
TEST_TIME 33 10861
TEST_TIME 34 10734
TEST_TIME 35 10531
TEST_TIME 36 10508
TEST_TIME 37 10456
TEST_TIME 38 10466
TEST_TIME 39 10375
TEST_TIME 40 10355
TEST_TIME 41 10367
TEST_TIME 42 10208
TEST_TIME 43 10152
TEST_TIME 44 10123
TEST_TIME 45 10127
TEST_TIME 46 10081
TEST_TIME 47 10062
TEST_TIME 48 10157
TEST_TIME 49 10118
TEST_TIME 50 10150
TEST_TIME 51 10103
TEST_TIME 52 10134
TEST_TIME 53 10192
TEST_TIME 54 10195
TEST_TIME 55 10179
TEST_TIME 56 10230
TEST_TIME 57 10074
TEST_TIME 58 10049
TEST_TIME 59 9978
TEST_TIME 60 9922
TEST_TIME 61 9848
TEST_TIME 62 9850
TEST_TIME 63 9846
TEST_TIME 64 9819
TEST_TIME 65 9771
TEST_TIME 66 9707
TEST_TIME 67 9712
TEST_TIME 68 9771
TEST_TIME 69 9808
TEST_TIME 70 9831
TEST_TIME 71 9948
TEST_TIME 72 9947
TEST_TIME 73 10003
TEST_TIME 74 10051
TEST_TIME 75 9986
TEST_TIME 76 10121
TEST_TIME 77 9998
TEST_TIME 78 9894
TEST_TIME 79 9938
TEST_TIME 80 9867
TEST_TIME 81 9815
TEST_TIME 82 9764
TEST_TIME 83 9679
TEST_TIME 84 9597
TEST_TIME 85 9633
TEST_TIME 86 9657
TEST_TIME 87 9686
TEST_TIME 88 9663
TEST_TIME 89 9674
TEST_TIME 90 9722
TEST_TIME 91 9734
TEST_TIME 92 9733
TEST_TIME 93 9733
TEST_TIME 94 9762
TEST_TIME 95 9758
TEST_TIME 96 9781
TEST_TIME 97 9754
TEST_TIME 98 9720
TEST_TIME 99 9788
TEST_TIME 100 9704
TEST_TIME 101 9664
TEST_TIME 102 9647
TEST_TIME 103 9598
TEST_TIME 104 9569
TEST_TIME 105 9577
TEST_TIME 106 9542
TEST_TIME 107 9525
TEST_TIME 108 9460
TEST_TIME 109 9458
TEST_TIME 110 9425
TEST_TIME 111 9430
TEST_TIME 112 9420
TEST_TIME 113 9440
TEST_TIME 114 9456
TEST_TIME 115 9453
TEST_TIME 116 9494
TEST_TIME 117 9562
TEST_TIME 118 9598
TEST_TIME 119 9601
TEST_TIME 120 9556

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-11-2021 13:38:08 to 14-11-2021 13:38:12.

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 1584633 1584633 0 0.00 0 17546 6 0 0
doc_list_biud 1584633 1584633 0 0.00 0 54059 29 0 0
sp_add_invoice_to_stock 5178 5092 86 1.66 54 170263 17852 0 86
sp_cancel_adding_invoice 5931 5931 0 0.00 6 82669 792 0 0
sp_cancel_client_order 1834 1834 0 0.00 4 6676 281 0 0
sp_cancel_customer_reserve 584414 584414 0 0.00 1 54061 64 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 3722 75 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 4766 82 0 0
sp_cancel_supplier_invoice 6575 6571 4 0.06 2 17008 802 0 4
sp_cancel_supplier_order 411 411 0 0.00 10 948521 111993 0 0
sp_cancel_write_off 9372 9372 0 0.00 1 8580 110 0 0
sp_client_order 21148 21148 0 0.00 4 15858 397 0 0
sp_customer_reserve 474436 474436 0 0.00 0 42529 177 0 0
sp_fill_shopping_cart 32366 32366 0 0.00 1 12511 40 0 0
sp_fill_shopping_cart_clo_res 474427 474427 0 0.00 0 14154 14 0 0
sp_get_clo_for_invoice 5083 5083 0 0.00 0 28905 315 0 0
sp_kill_cost_storno 16467 16467 0 0.00 0 7193 50 0 0
sp_kill_qstorno_ret_qs2qd 1185182 1185182 0 0.00 0 54055 34 0 0
sp_kill_qty_storno 623781 623781 0 0.00 0 54059 73 0 0
sp_lock_selected_doc 21253 21253 0 0.00 0 9433 6 0 0
sp_make_cost_storno 28402 28402 0 0.00 0 14471 53 0 0
sp_multiply_rows_for_pdistr 28402 28402 0 0.00 0 8734 29 0 0
sp_multiply_rows_for_qdistr 21148 21148 0 0.00 0 11748 216 0 0
sp_pay_from_customer 7226 7226 0 0.00 4 18669 104 0 0
sp_pay_to_supplier 5928 5928 0 0.00 5 18774 126 0 0
sp_payment_common 13154 13154 0 0.00 2 15299 23 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 6669 249 0 0
sp_qd_handle_on_invoice_upd_sts 11014 11014 0 0.00 0 26797 316 0 0
sp_qd_handle_on_reserve_upd_sts 10165 10165 0 0.00 0 12300 49 0 0
sp_reserve_write_off 10165 10165 0 0.00 7 19401 175 0 0
sp_supplier_invoice 5619 5619 0 0.00 7 70398 3403 0 0
sp_supplier_order 5607 5607 0 0.00 5 30111 1498 0 0
srv_aggregate_perf_data 112 112 0 0.00 2691 208096 16087 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1487 173275 42027 0 0
srv_find_qd_qs_mism 1097054 1097054 0 0.00 0 11849 6 0 0
srv_make_invnt_saldo 433 433 0 0.00 56 94682 3311 0 0
srv_make_money_saldo 828 828 0 0.00 1 14727 481 0 0
srv_recalc_idx_stat 4 4 0 0.00 117776 367682 242186 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5093 5093 0 0.00 0 9485 145 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 1434 57 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 4225 9 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 820 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 755 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 485 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 681 12 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 21116 1389 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 2291 5 0 0
t$perf-norm:sp_client_order 21148 21148 0 0.00 0 7598 15 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 595 4 0 0
t$perf-norm:sp_pay_from_customer 7226 7226 0 0.00 0 4630 7 0 0
t$perf-norm:sp_pay_to_supplier 5928 5928 0 0.00 0 3157 7 0 0
t$perf-norm:sp_reserve_write_off 10165 10165 0 0.00 0 8992 11 0 0
t$perf-norm:sp_supplier_invoice 5619 5619 0 0.00 0 5516 18 0 0
t$perf-norm:sp_supplier_order 5607 5607 0 0.00 0 3624 13 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 221 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 640 19 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 392 5 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 563 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10338 10166 172 1.66 0 10005 18 0 172
v_all_customers 38030 38030 0 0.00 0 74 1 0 0
v_all_suppliers 30912 30912 0 0.00 0 16 1 0 0
v_all_wares 42292 42292 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 379 5 0 0
v_cancel_client_order 3668 3668 0 0.00 0 4325 7 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 3715 11 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 721 4 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1933 7 0 0
v_cancel_supplier_invoice 842 834 8 0.95 0 3315 19 0 8
v_cancel_supplier_order 822 822 0 0.00 0 2896 6 0 0
v_cancel_write_off 2176 2176 0 0.00 0 5775 19 0 0
v_max_id_clo_ord 5605 5605 0 0.00 0 2296 2 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 4296 22 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 4291 3 0 0
v_max_non_paid_invoice 1692 1692 0 0.00 0 519 2 0 0
v_max_non_paid_realizn 4075 4075 0 0.00 0 2086 4 0 0
v_min_id_clo_ord 5605 5605 0 0.00 0 2906 5 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 4370 15 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 9326 10 0 0
v_min_non_paid_invoice 5928 5928 0 0.00 0 15295 17 0 0
v_min_non_paid_realizn 7226 7226 0 0.00 0 4466 14 0 0
v_our_firm 10564 10564 0 0.00 0 74 1 0 0
v_reserve_write_off 20330 20330 0 0.00 0 3748 5 0 0
x_lock_depdocs_on_canc_invoice 12085 12085 0 0.00 0 21140 92 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3603 284 0 0
x_make_qstorno_1000_1200 5607 5607 0 0.00 2 30103 1431 0 0
x_make_qstorno_1200_2000 5619 5619 0 0.00 3 70383 3248 0 0
x_make_qstorno_2100_3300 474436 474436 0 0.00 0 42495 161 0 0

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

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
11:07:11 5 8192000000 8799383888 8810717184 5260816 0.064 2 1 2 0 5360240 63648 35776
11:08:12 11282 8192000000 9720656000 10022416384 808011264 8.977 100 84 168 0 835470256 7176528 20282464
11:09:30 21921 8192000000 9781701600 10092466176 846174480 9.362 100 87 174 0 877795280 10877040 20743760
11:10:54 13668 8192000000 9806879488 10128633856 877207344 9.672 100 86 172 2 914753168 17744576 19801248
11:12:14 16114 8192000000 9861205024 10195574784 916117344 10.058 100 75 150 0 969855760 37112432 16625984
11:13:35 8098 8192000000 9922304512 10263023616 959245392 10.482 100 79 158 0 1028978912 53129776 16603744
11:14:47 6027 8192000000 9949793200 10303909888 989413504 10.776 100 69 138 1 1067086720 63405216 14268000
11:15:56 3382 8192000000 9999806496 10360012800 1017850000 11.051 100 87 172 1 1111075776 75992096 17233680
11:17:01 1902 8192000000 10025522736 10392657920 1039690912 11.262 100 86 170 3 1141302064 84347792 17263360
11:18:04 1800 8192000000 10023877536 10392936448 1058553872 11.443 100 92 181 0 1136051216 57946608 19550736
11:19:07 1615 8192000000 10023365216 10404270080 1064783296 11.502 100 70 141 0 1137321936 58262752 14275888
11:20:09 6596 8192000000 9996569680 10389319680 1070669360 11.558 100 47 91 1 1132762640 52363488 9729792
11:21:17 8420 8192000000 9980428048 10369986560 1079365200 11.641 100 24 45 1 1097893008 13323952 5203856
11:22:26 30476 8192000000 9999273920 10418237440 1083490752 11.681 100 36 72 1 1113929008 23092352 7345904
11:24:02 6331 8192000000 10010039696 10408898560 1091132032 11.753 100 29 58 1 1118326176 21126352 6067792
11:25:11 6255 8192000000 10019898368 10413658112 1093380512 11.775 100 31 63 1 1132804960 32908304 6516144
11:26:17 13001 8192000000 9997984368 10401820672 1094608816 11.786 100 30 60 2 1123742944 22647728 6486400
11:27:31 20256 8192000000 9988596400 10392268800 1018742144 11.060 93 17 34 2 1056701376 34919152 3040080
11:28:53 5385 8192000000 9981314032 10371584000 1054896784 11.408 98 43 86 3 1086626112 22484448 9244880
11:30:00 4569 8192000000 9960808560 10347147264 1031604048 11.184 100 32 64 0 1065194288 26053744 7536496
11:31:05 64957 8192000000 9928469552 10311360512 969718096 10.584 94 36 73 2 983523424 5736832 8068496
11:33:14 5766 8192000000 9963670976 10348982272 1037488880 11.241 100 39 79 1 1068718912 21341184 9888848
11:34:20 5552 8192000000 9991162144 10382409728 1059575488 11.452 100 46 91 1 1087583024 17384736 10622800
11:35:32 21509 8192000000 9995971808 10406543360 1064392944 11.499 100 38 76 3 1089389888 16333120 8663824
11:37:09 11106 8192000000 9990318688 10387501056 1084029936 11.686 100 30 60 0 1112430864 22002912 6398016
11:38:24 5280 8192000000 10044765088 10438303744 1104815824 11.883 100 54 105 3 1151937648 36539808 10582016
11:39:36 21211 8192000000 10066631616 10467184640 1123825456 12.063 100 44 88 1 1172899696 39772640 9301600
11:41:00 4286 8192000000 10121610080 10521686016 1128154736 12.104 100 57 112 6 1237817248 98201264 11461248
11:42:09 7809 8192000000 10124993088 10530242560 1136373888 12.181 100 48 96 0 1229761024 83168432 10218704
11:43:37 26032 8192000000 10164912768 10582601728 1154731232 12.354 100 46 92 2 1275465232 109911296 10822704
11:45:13 16330 8192000000 10203541184 10617720832 1166186992 12.461 100 55 110 0 1300851680 122649088 12015600
11:46:41 5621 8192000000 10262081408 10671329280 1163287632 12.434 99 64 128 1 1350634528 173616080 13730816
11:47:51 8329 8192000000 10305641616 10715697152 1178351536 12.575 100 73 143 0 1399405296 205051024 16002736
11:49:04 1486 8192000000 10301011264 10708680704 1172399824 12.519 100 75 149 0 1401703856 212940704 16363328
11:50:10 3091 8192000000 10283170320 10688180224 1158589664 12.390 99 66 130 0 1380169072 206868496 14710912
11:51:16 7671 8192000000 10348405584 10770825216 1176055584 12.553 100 57 114 0 1460653328 270722912 13874832
11:52:30 2196 8192000000 10372107488 10801270784 1177823648 12.570 100 66 132 1 1487227920 293847424 15556848
11:53:39 2962 8192000000 10393007696 10797400064 1175021872 12.544 100 85 171 4 1485720128 291444416 19253840
11:54:46 6002 8192000000 10369582768 10835542016 1173375728 12.528 100 47 95 0 1490543696 304539920 12628048
11:55:56 12153 8192000000 10350897696 10803589120 1171981424 12.515 100 40 79 2 1456954384 274832816 10140144
11:57:14 18671 8192000000 10339620336 10805874688 1157877376 12.383 100 37 73 0 1465449952 298219456 9353120
11:58:46 11396 8192000000 10355659184 10807504896 1160900256 12.412 100 31 62 0 1461851408 293506624 7444528
12:00:02 11596 8192000000 10426438608 10860904448 1160869440 12.411 100 33 66 0 1517614160 347666640 9078080
12:01:27 13565 8192000000 10350737504 10808942592 1135630976 12.174 99 33 66 0 1442292496 298336272 8325248
12:03:19 22260 8192000000 10277986688 10829430784 1137344752 12.191 100 17 35 1 1441359920 299444848 4570320
12:05:29 35665 8192000000 10063125344 10519670784 1112190160 11.953 100 16 32 0 1146531392 30976944 3364288
12:07:30 90441 8192000000 10018259328 10434625536 1056746560 11.425 94 15 30 0 1095561296 35752112 3062624
12:10:03 62475 8192000000 10054293568 10473725952 1113566448 11.966 99 54 103 4 1145668384 21168752 10933184
12:12:09 122169 8192000000 10103486768 10570792960 1108179440 11.915 98 36 71 0 1161046272 45093648 7773184
12:16:04 45855 8192000000 10080856896 10520137728 1095147024 11.792 97 14 28 0 1183200512 85355600 2697888
12:17:50 47431 8192000000 10065881648 10544033792 1103104480 11.867 98 9 18 0 1154125200 48989712 2031008
12:21:13 19193 8192000000 10058952912 10563182592 1129320416 12.115 100 10 20 0 1171849024 40345104 2183504
12:22:38 43536 8192000000 10055190912 10476060672 1118126832 12.009 98 26 52 1 1150398880 26781200 5490848
12:24:23 28678 8192000000 10113862608 10542628864 1134149328 12.160 99 49 98 3 1195218560 50734688 10334544
12:25:55 52972 8192000000 10095069184 10541047808 1098280944 11.821 96 22 44 2 1144355424 41484720 4589760
12:27:55 53096 8192000000 10135239760 10592985088 1132194368 12.142 99 18 36 0 1212104608 76083840 3826400
12:29:49 68845 8192000000 10092385600 10558701568 1118942704 12.017 98 19 38 0 1197740000 74887520 3909776
12:32:27 52476 8192000000 10073328688 10529808384 1114709040 11.977 97 20 40 1 1150515936 31924528 3882368
12:34:29 81988 8192000000 10079524368 10546905088 1126627744 12.090 99 35 70 0 1156321248 21803152 7890352
12:36:55 23329 8192000000 10075233792 10514780160 1131830864 12.139 99 29 58 1 1164369856 26600048 5938944
12:38:30 65624 8192000000 10080769936 10570043392 1121691952 12.043 98 9 18 0 1200020688 76474480 1854256
12:40:39 47784 8192000000 10070529328 10528235520 1120384384 12.031 98 12 24 0 1171316432 48985296 1946752
12:42:27 23120 8192000000 10092419408 10533339136 1115905840 11.988 97 25 48 0 1176802720 55453984 5442896
12:43:59 25411 8192000000 10136458208 10564780032 1110247536 11.935 98 36 72 0 1227810560 109831648 7731376
12:45:38 50265 8192000000 10138416096 10620948480 1145377984 12.266 100 24 48 0 1270121200 119708336 5034880
12:47:38 49572 8192000000 10088939552 10590990336 1085054352 11.696 96 19 38 0 1192762704 103036016 4672336
12:49:32 36962 8192000000 10088220464 10506555392 1061613008 11.472 95 25 50 1 1131623120 64598704 5411408
12:51:16 62299 8192000000 10098579680 10534969344 1115806608 11.987 100 18 36 0 1172573824 53243040 3524176
12:53:20 173275 8192000000 10075837104 10516586496 1101425024 11.851 98 18 36 1 1170735216 65587728 3722464
12:57:13 66145 8192000000 10075161568 10543767552 1108828752 11.921 99 13 26 0 1163299392 51284128 3186512
12:59:47 73874 8192000000 10119609552 10553450496 1109525184 11.928 98 16 32 1 1202808528 89560096 3723248
13:02:02 74125 8192000000 10132721584 10589855744 1099431568 11.832 98 15 30 0 1217907328 114639792 3835968
13:04:39 61579 8192000000 10059292288 10493931520 1082842064 11.675 97 18 36 0 1114880608 27957040 4081504
13:06:46 42167 8192000000 10096178496 10544250880 1110062464 11.933 99 31 62 0 1174322096 57992304 6267328
13:08:36 48876 8192000000 10061423968 10536194048 1112999408 11.961 99 18 36 1 1159361360 42872048 3489904
13:10:27 34228 8192000000 10111397216 10556989440 1132651968 12.146 100 43 86 1 1208096176 66346960 9097248
13:12:06 114833 8192000000 10077454896 10548183040 1068953680 11.542 95 14 28 0 1130300416 58434816 2911920
13:15:07 14882 8192000000 10105755888 10526347264 1134717168 12.166 100 60 118 0 1192624704 46057520 11850016
13:16:28 96197 8192000000 10064084976 10505121792 1135256096 12.171 100 16 32 0 1173675248 35037824 3381328
13:19:13 29758 8192000000 10113548576 10549219328 1132112976 12.141 99 28 56 0 1207422656 69699824 5609856
13:20:44 41574 8192000000 10075596848 10518601728 1100649216 11.844 97 9 18 0 1165217456 62647520 1920720
13:22:51 68538 8192000000 10090107360 10542694400 1132112512 12.141 99 11 22 0 1169809024 35194448 2502064
13:25:01 65969 8192000000 10087536912 10534330368 1122289504 12.049 99 10 20 1 1183904144 59530368 2084272
13:27:20 55019 8192000000 10125200256 10569621504 1111523568 11.947 97 24 48 0 1184141312 67578848 5038896
13:29:20 40327 8192000000 10139574016 10584162304 1115629904 11.986 98 22 44 0 1219832416 98990384 5212128
13:31:13 76705 8192000000 10188420832 10628337664 1135296512 12.171 99 22 44 1 1251816848 111570000 4950336
13:33:34 47273 8192000000 10144222816 10587111424 1138764704 12.204 100 22 43 0 1253054816 109832704 4457408
13:35:40 56088 8192000000 10126041152 10607112192 1133729328 12.157 99 43 86 1 1200063024 57868000 8465696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-11-2021 13:38:19 to 14-11-2021 13:38:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-11-2021 13:38:34 to 14-11-2021 13:38:36.

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          234979
MON$OLDEST_ACTIVE               238812
MON$OLDEST_SNAPSHOT             238812
MON$NEXT_TRANSACTION            238812
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                       2055136
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
:68: 
:69: mscs-test-161.budzdorov.ru    Sun Nov 14 13:37:18 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Sun Nov 14 13:37:28 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
--- end of diff output ---

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

14.11.21 13:38:37. 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-14 13:38:40 No severe PSQL-related problems occured
14.11.21 13:38:41. End of report.