Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2021 13: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 16576708 kB
MemAvailable 31517760 kB
Buffers 0 kB
Cached 15143320 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-10 13:37:12
DTS_END                         2021-12-10 13:37:12.1150
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/55414
TRN_ID                          213139
ATT_ID                          619
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: 8636.90 31846 1036428
customer order: creation 146.14 431 17537
customer order: refuse 13.17 413 1580
order to supplier: creation 39.10 1610 4692
order to supplier: removal 3.28 144329 394
invoice (draft): creation 40.76 4031 4891
invoice (draft): removal 51.30 1174 6156
invoice accept: apply 35.42 24974 4251
invoice accept: cancel 46.54 1001 5585
customer reserve: creation 3431.68 235 411801
customer reserve: removal 4566.99 78 548039
realization accept: apply 70.64 212 8477
realization accept: cancel 79.80 124 9576
payment from customer: creation 51.83 136 6219
payment from customer: removal 5.17 150 620
payment to supplier: creation 42.14 144 5057
payment to supplier: removal 3.21 129 385
service: total inventory turnovers 3.28 4327 394
service: total monetary turnovers 5.68 699 681
service: aggregate perf. data 0.74 18688 89
service: refresh index statistics 0.03 434026 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2021 13:39:03 to 10-12-2021 13:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 898
TEST_TIME 2 3605
TEST_TIME 3 4090
TEST_TIME 4 4600
TEST_TIME 5 4696
TEST_TIME 6 4880
TEST_TIME 7 5004
TEST_TIME 8 5600
TEST_TIME 9 5755
TEST_TIME 10 6033
TEST_TIME 11 6025
TEST_TIME 12 6116
TEST_TIME 13 6276
TEST_TIME 14 6471
TEST_TIME 15 6433
TEST_TIME 16 6642
TEST_TIME 17 6890
TEST_TIME 18 7210
TEST_TIME 19 7289
TEST_TIME 20 7313
TEST_TIME 21 7645
TEST_TIME 22 8010
TEST_TIME 23 7875
TEST_TIME 24 7900
TEST_TIME 25 8048
TEST_TIME 26 8380
TEST_TIME 27 8709
TEST_TIME 28 9363
TEST_TIME 29 10073
TEST_TIME 30 10920
TEST_TIME 31 10867
TEST_TIME 32 10531
TEST_TIME 33 10433
TEST_TIME 34 10664
TEST_TIME 35 10438
TEST_TIME 36 10360
TEST_TIME 37 10250
TEST_TIME 38 10102
TEST_TIME 39 9995
TEST_TIME 40 9848
TEST_TIME 41 9941
TEST_TIME 42 9932
TEST_TIME 43 9920
TEST_TIME 44 9766
TEST_TIME 45 9727
TEST_TIME 46 9542
TEST_TIME 47 9492
TEST_TIME 48 9386
TEST_TIME 49 9409
TEST_TIME 50 9423
TEST_TIME 51 9324
TEST_TIME 52 9254
TEST_TIME 53 9198
TEST_TIME 54 9189
TEST_TIME 55 9071
TEST_TIME 56 9069
TEST_TIME 57 9019
TEST_TIME 58 9000
TEST_TIME 59 8948
TEST_TIME 60 8932
TEST_TIME 61 8923
TEST_TIME 62 8909
TEST_TIME 63 8934
TEST_TIME 64 8908
TEST_TIME 65 8953
TEST_TIME 66 9050
TEST_TIME 67 8975
TEST_TIME 68 9100
TEST_TIME 69 9061
TEST_TIME 70 9113
TEST_TIME 71 9201
TEST_TIME 72 9107
TEST_TIME 73 9015
TEST_TIME 74 8969
TEST_TIME 75 8930
TEST_TIME 76 8863
TEST_TIME 77 8860
TEST_TIME 78 8834
TEST_TIME 79 8787
TEST_TIME 80 8757
TEST_TIME 81 8745
TEST_TIME 82 8740
TEST_TIME 83 8774
TEST_TIME 84 8940
TEST_TIME 85 8901
TEST_TIME 86 8893
TEST_TIME 87 8919
TEST_TIME 88 8928
TEST_TIME 89 8970
TEST_TIME 90 9027
TEST_TIME 91 9017
TEST_TIME 92 8928
TEST_TIME 93 8874
TEST_TIME 94 8821
TEST_TIME 95 8788
TEST_TIME 96 8790
TEST_TIME 97 8787
TEST_TIME 98 8726
TEST_TIME 99 8717
TEST_TIME 100 8648
TEST_TIME 101 8636
TEST_TIME 102 8720
TEST_TIME 103 8747
TEST_TIME 104 8705
TEST_TIME 105 8726
TEST_TIME 106 8825
TEST_TIME 107 8815
TEST_TIME 108 8818
TEST_TIME 109 8823
TEST_TIME 110 8840
TEST_TIME 111 8854
TEST_TIME 112 8893
TEST_TIME 113 8873
TEST_TIME 114 8824
TEST_TIME 115 8756
TEST_TIME 116 8774
TEST_TIME 117 8793
TEST_TIME 118 8745
TEST_TIME 119 8696
TEST_TIME 120 8639

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-12-2021 13:39:06 to 10-12-2021 13:39:10.

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 1415829 1415829 0 0.00 0 30946 8 0 0
doc_list_biud 1415829 1415829 0 0.00 0 77122 39 0 0
sp_add_invoice_to_stock 4345 4251 94 2.16 43 260808 24974 0 94
sp_cancel_adding_invoice 5585 5585 0 0.00 5 66930 1001 0 0
sp_cancel_client_order 1580 1580 0 0.00 5 9713 414 0 0
sp_cancel_customer_reserve 548039 548039 0 0.00 1 33219 79 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 8190 151 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 7012 130 0 0
sp_cancel_supplier_invoice 6160 6156 4 0.06 2 77129 1175 0 4
sp_cancel_supplier_order 394 394 0 0.00 6 1240391 144330 0 0
sp_cancel_write_off 9576 9576 0 0.00 1 18240 124 0 0
sp_client_order 17537 17537 0 0.00 4 26837 431 0 0
sp_customer_reserve 411801 411801 0 0.00 0 86328 235 0 0
sp_fill_shopping_cart 27113 27113 0 0.00 1 26669 52 0 0
sp_fill_shopping_cart_clo_res 411797 411797 0 0.00 0 19822 17 0 0
sp_get_clo_for_invoice 4247 4247 0 0.00 0 40564 425 0 0
sp_kill_cost_storno 16166 16166 0 0.00 0 30942 77 0 0
sp_kill_qstorno_ret_qs2qd 1112204 1112204 0 0.00 0 77120 43 0 0
sp_kill_qty_storno 584054 584054 0 0.00 0 77122 93 0 0
sp_lock_selected_doc 17942 17942 0 0.00 0 7279 4 0 0
sp_make_cost_storno 24000 24000 0 0.00 0 17449 69 0 0
sp_multiply_rows_for_pdistr 24000 24000 0 0.00 0 12945 35 0 0
sp_multiply_rows_for_qdistr 17537 17537 0 0.00 0 15321 197 0 0
sp_pay_from_customer 6219 6219 0 0.00 4 16990 137 0 0
sp_pay_to_supplier 5057 5057 0 0.00 5 20544 144 0 0
sp_payment_common 11276 11276 0 0.00 1 13423 23 0 0
sp_qd_handle_on_cancel_clo 1580 1580 0 0.00 0 9704 383 0 0
sp_qd_handle_on_invoice_upd_sts 9832 9832 0 0.00 0 39568 480 0 0
sp_qd_handle_on_reserve_upd_sts 8477 8477 0 0.00 0 13518 71 0 0
sp_reserve_write_off 8477 8477 0 0.00 7 18795 212 0 0
sp_supplier_invoice 4891 4891 0 0.00 6 98919 4031 0 0
sp_supplier_order 4692 4692 0 0.00 6 76439 1610 0 0
srv_aggregate_perf_data 89 89 0 0.00 2663 299777 18689 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1310 210937 50669 0 0
srv_find_qd_qs_mism 993806 993806 0 0.00 0 18191 7 0 0
srv_make_invnt_saldo 394 394 0 0.00 65 105768 4328 0 0
srv_make_money_saldo 681 681 0 0.00 1 20358 699 0 0
srv_recalc_idx_stat 4 4 0 0.00 351807 565448 434027 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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 4251 4251 0 0.00 0 16037 270 0 0
t$perf-norm:sp_cancel_adding_invoice 365 365 0 0.00 0 4668 105 0 0
t$perf-norm:sp_cancel_client_order 1580 1580 0 0.00 0 10186 30 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 1035 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 3259 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 7266 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 335 335 0 0.00 0 717 17 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 47603 2292 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 3331 13 0 0
t$perf-norm:sp_client_order 17537 17537 0 0.00 0 11070 24 0 0
t$perf-norm:sp_customer_reserve 855 855 0 0.00 0 393 5 0 0
t$perf-norm:sp_pay_from_customer 6219 6219 0 0.00 0 8051 12 0 0
t$perf-norm:sp_pay_to_supplier 5057 5057 0 0.00 0 9668 15 0 0
t$perf-norm:sp_reserve_write_off 8477 8477 0 0.00 0 10281 17 0 0
t$perf-norm:sp_supplier_invoice 4891 4891 0 0.00 0 9550 24 0 0
t$perf-norm:sp_supplier_order 4692 4692 0 0.00 0 12670 26 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 229 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 617 16 0 0
t$perf-norm:srv_make_invnt_saldo 394 394 0 0.00 0 1747 22 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 1801 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 32 11 0 0
v_add_invoice_to_stock 8682 8494 188 2.16 0 6803 21 0 188
v_all_customers 32370 32370 0 0.00 0 63 1 0 0
v_all_suppliers 26736 26736 0 0.00 0 20 1 0 0
v_all_wares 35070 35070 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 730 730 0 0.00 0 1911 10 0 0
v_cancel_client_order 3160 3160 0 0.00 0 2443 6 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 5291 14 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 2319 9 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 6971 21 0 0
v_cancel_supplier_invoice 678 670 8 1.17 0 2384 19 0 8
v_cancel_supplier_order 788 788 0 0.00 0 3421 11 0 0
v_cancel_write_off 1802 1802 0 0.00 0 1543 14 0 0
v_max_id_clo_ord 4692 4692 0 0.00 0 1568 2 0 0
v_max_id_clo_res 855 855 0 0.00 0 1265 7 0 0
v_max_id_ord_sup 4885 4885 0 0.00 0 8067 6 0 0
v_max_non_paid_invoice 1266 1266 0 0.00 0 2977 5 0 0
v_max_non_paid_realizn 3170 3170 0 0.00 0 547 2 0 0
v_min_id_clo_ord 4692 4692 0 0.00 0 2453 7 0 0
v_min_id_clo_res 855 855 0 0.00 0 1745 8 0 0
v_min_id_ord_sup 4885 4885 0 0.00 0 5554 7 0 0
v_min_non_paid_invoice 5057 5057 0 0.00 0 6744 16 0 0
v_min_non_paid_realizn 6219 6219 0 0.00 0 13416 17 0 0
v_our_firm 8798 8798 0 0.00 0 91 1 0 0
v_reserve_write_off 16954 16954 0 0.00 0 16267 9 0 0
x_lock_depdocs_on_canc_invoice 11406 11406 0 0.00 0 22210 164 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 8468 408 0 0
x_make_qstorno_1000_1200 4692 4692 0 0.00 2 76320 1516 0 0
x_make_qstorno_1200_2000 4891 4891 0 0.00 2 90365 3829 0 0
x_make_qstorno_2100_3300 411801 411801 0 0.00 0 86327 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 13:39:11 to 10-12-2021 13:39:15.

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:15 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 28412 8192000000 9725809248 10025099264 803722816 8.934 100 90 180 1 833393968 7418128 22253024
11:09:50 15731 8192000000 9760584880 10065997824 835375744 9.253 100 86 172 0 868390240 12358000 20656496
11:11:13 16392 8192000000 9792347072 10107842560 860953824 9.510 100 92 184 0 902703760 20280704 21469232
11:12:38 10789 8192000000 9858622896 10183753728 906866192 9.966 100 85 170 0 969777760 43926752 18984816
11:13:57 8580 8192000000 9904821520 10248466432 949037232 10.382 100 80 161 0 1019802624 53814576 16950816
11:15:13 6176 8192000000 9946464800 10299928576 985096896 10.734 100 71 142 0 1060180544 60845488 14238160
11:16:21 5278 8192000000 9979802608 10342125568 1014347776 11.017 100 72 145 0 1099977984 71217840 14412368
11:17:29 2580 8192000000 10004378624 10370301952 1038018176 11.246 100 84 165 1 1129184048 74178496 16987376
11:18:35 1577 8192000000 10042838304 10415284224 1061311440 11.469 100 78 155 0 1150067696 72314432 16441824
11:19:39 4432 8192000000 10028704288 10408398848 1070133232 11.553 100 60 120 0 1145681600 63273696 12274672
11:20:45 2942 8192000000 9998504848 10387533824 1073053344 11.581 100 52 104 0 1108956528 24918128 10985056
11:21:49 7293 8192000000 10008971152 10406273024 1083996256 11.686 100 35 70 1 1118543840 27451248 7096336
11:23:03 9872 8192000000 9998474144 10395250688 1091581184 11.758 100 31 60 2 1107508128 9406672 6520272
11:24:14 29299 8192000000 10009862720 10398269440 1095261376 11.793 100 61 115 5 1120693232 13489104 11942752
11:25:44 6837 8192000000 10019097776 10417590272 1094976688 11.790 100 36 72 0 1132769712 29931728 7861296
11:26:53 10920 8192000000 10013043664 10419249152 1085545056 11.700 99 33 66 1 1118204640 25073840 7585744
11:28:09 9384 8192000000 10014441008 10425294848 1098110912 11.820 100 22 45 1 1114685808 11952416 4622480
11:29:23 5225 8192000000 9983420528 10370715648 1049579536 11.357 98 35 68 3 1083591568 26532272 7479760
11:30:32 7449 8192000000 9969004880 10356527104 1012471904 10.999 95 38 75 2 1039760912 18955552 8333456
11:31:42 68821 8192000000 9927033600 10320146432 950020096 10.391 92 23 46 0 976696544 21897312 4779136
11:33:52 3295 8192000000 9948738944 10312617984 1001838736 10.896 98 44 89 0 1034547664 23125360 9583568
11:34:57 8137 8192000000 9975962176 10371317760 1049556240 11.356 100 31 62 0 1081670976 25436480 6678256
11:36:06 7899 8192000000 9979660448 10375544832 1063047680 11.486 100 24 48 0 1088922624 20422160 5452784
11:37:14 123507 8192000000 10026432544 10424967168 1078154080 11.630 100 27 54 1 1119271808 35657248 5460480
11:40:25 36268 8192000000 10123548896 10525257728 1106444688 11.899 100 62 124 0 1228983968 109749360 12789920
11:42:04 18529 8192000000 10146496224 10553896960 1119233776 12.020 100 49 98 0 1250029584 119683024 11112784
11:43:28 21502 8192000000 10171454832 10576138240 1129054912 12.112 100 52 104 0 1278413552 137752096 11606544
11:44:58 31483 8192000000 10204895696 10649460736 1135952784 12.177 100 42 84 1 1332010528 186570992 9486752
11:46:38 6617 8192000000 10232937824 10637029376 1154809120 12.355 100 63 128 1 1335301232 166465888 14026224
11:47:50 3396 8192000000 10271727776 10679857152 1175249232 12.546 100 66 127 2 1374825552 185455056 14121264
11:49:01 6724 8192000000 10340193776 10746904576 1175973728 12.553 100 75 150 1 1433373168 241250336 16149104
11:50:23 4199 8192000000 10389013280 10794741760 1193328432 12.714 100 85 166 2 1486514288 274766416 18419440
11:51:33 3058 8192000000 10397184736 10809561088 1199389440 12.771 100 70 140 1 1496948576 282172016 15387120
11:52:44 1307 8192000000 10424284640 10834235392 1185780368 12.644 100 91 180 0 1526393856 319379008 21234480
11:53:49 3642 8192000000 10440427456 10846703616 1186813600 12.654 100 83 164 2 1533336608 328519280 18003728
11:55:03 4676 8192000000 10413093808 10850942976 1179498608 12.586 99 76 150 0 1503394304 306496000 17399696
11:56:15 1319 8192000000 10458989824 10866270208 1194006576 12.721 100 88 175 0 1549860816 336256064 19598176
11:57:22 2083 8192000000 10514899200 10919030784 1187995168 12.665 100 96 190 2 1609349200 399339360 22014672
11:58:31 2811 8192000000 10519942816 10956828672 1191955392 12.702 100 64 126 0 1648026720 440369552 15701776
11:59:42 8202 8192000000 10495705616 10957144064 1181270720 12.602 100 48 92 2 1622234576 429152368 11811488
12:00:55 5886 8192000000 10553062272 10975215616 1174033824 12.535 100 65 131 0 1655118256 465022016 16062416
12:02:08 3575 8192000000 10578380560 10994688000 1166291808 12.462 100 68 135 1 1669038784 486266144 16480832
12:03:16 8242 8192000000 10575402656 11064635392 1162460752 12.426 100 39 78 1 1672451520 499662848 10327920
12:04:49 15753 8192000000 10549570208 10989129728 1150928720 12.318 100 34 68 0 1643939072 483451120 9559232
12:06:09 12903 8192000000 10343722128 10880630784 1126090352 12.084 100 24 48 0 1470770096 338283248 6396496
12:07:23 19847 8192000000 10088814416 10531606528 1122474624 12.050 99 20 40 1 1201277456 74514016 4288816
12:08:45 91448 8192000000 10132729424 10555432960 1084442672 11.690 97 43 82 2 1211246112 117664976 9138464
12:11:23 21762 8192000000 10064450752 10568536064 1118395248 12.012 100 9 17 0 1183566416 63337440 1833728
12:12:45 103849 8192000000 10062226992 10496446464 1093509824 11.776 97 27 54 0 1154203200 55366592 5326784
12:15:30 111139 8192000000 10109363152 10551721984 1105893568 11.894 97 16 31 1 1191430096 82217952 3318576
12:18:21 82575 8192000000 10105165296 10541834240 1065531872 11.509 94 13 26 0 1159025728 90538096 2955760
12:20:44 210937 8192000000 10069862480 10503344128 1119148928 12.019 99 31 62 0 1155764240 30189104 6426208
12:25:23 49976 8192000000 10102008544 10583117824 1137630688 12.193 99 35 70 0 1222195328 76533952 8030688
12:27:14 116532 8192000000 10101622256 10550681600 1125133584 12.075 98 21 40 1 1155718800 26360320 4224896
12:30:11 84744 8192000000 10110890080 10605375488 1148793648 12.298 100 16 32 1 1234561216 82393744 3373824
12:32:49 43869 8192000000 10098163952 10544181248 1138293696 12.199 99 40 81 2 1199232672 52096352 8842624
12:34:34 97263 8192000000 10060439456 10557337600 1135883104 12.177 99 3 6 1 1170634544 34198128 553312
12:37:12 69789 8192000000 10082842640 10542133248 1135144928 12.170 99 12 24 0 1179899696 42290592 2464176
12:40:24 64991 8192000000 10126948448 10595155968 1107666304 11.910 97 30 60 0 1171840368 58382544 5791520
12:42:36 51842 8192000000 10143035552 10583961600 1132564608 12.146 99 17 34 0 1208242624 72406800 3271216
12:45:32 32180 8192000000 10103531936 10576986112 1141114976 12.226 100 23 46 0 1209403216 63498976 4789264
12:47:07 73441 8192000000 10098519696 10577022976 1115042992 11.980 97 9 18 1 1181718016 64971056 1703968
12:49:27 79108 8192000000 10070371856 10510716928 1108791744 11.921 97 34 68 0 1140818176 24678720 7347712
12:52:01 31873 8192000000 10117030800 10557394944 1134714016 12.166 100 47 93 1 1197042384 53063744 9264624
12:53:36 87220 8192000000 10080737072 10555240448 1114298640 11.973 98 14 28 0 1181834192 64213216 3322336
12:57:05 61493 8192000000 10091955312 10554232832 1127738016 12.100 99 12 24 0 1204591888 74099872 2754000
12:59:11 77064 8192000000 10200838032 10654154752 1102081984 11.857 96 22 44 0 1184521920 77154912 5285024
13:01:29 52962 8192000000 10053214384 10501836800 1088473856 11.728 97 13 26 0 1169945184 78648800 2822528
13:03:23 41357 8192000000 10083031296 10532925440 1094347840 11.784 98 36 72 0 1149934256 48047840 7538576
13:05:04 39683 8192000000 10072796416 10517422080 1095838816 11.798 98 36 72 1 1166909536 63974592 7096128
13:06:47 89869 8192000000 10059944544 10548514816 1040597280 11.270 93 9 18 0 1113287920 70827648 1862992
13:09:20 88081 8192000000 10029540032 10477686784 1096487440 11.804 99 11 22 0 1125291376 26397680 2406256
13:12:07 66883 8192000000 10080024864 10544779264 1106760432 11.902 99 33 66 0 1181946928 68017920 7168576
13:14:14 55441 8192000000 10063634560 10555924480 1120002064 12.027 100 10 20 0 1185279376 62908672 2368640
13:16:10 76714 8192000000 10100573776 10563244032 1126437888 12.088 99 12 24 0 1193389760 64449712 2502160
13:18:28 44636 8192000000 10093200144 10573541376 1112161920 11.953 98 21 43 0 1182775280 66095872 4517488
13:20:13 97268 8192000000 10109947760 10546937856 1083277392 11.679 96 17 34 1 1143756768 56818704 3660672
13:22:51 98344 8192000000 10076771472 10542596096 1139140880 12.207 100 15 28 2 1258713920 115803760 3769280
13:25:30 47402 8192000000 10107892736 10557456384 1133747568 12.157 99 43 86 2 1186474752 43400880 9326304
13:27:25 32030 8192000000 10089605456 10531012608 1139814624 12.214 100 37 74 0 1213312752 66242208 7255920
13:29:09 106410 8192000000 10090337344 10523402240 1112158848 11.953 97 31 62 0 1171339904 53233056 5948000
13:31:57 91343 8192000000 10160410528 10587987968 1131780240 12.138 99 57 114 1 1244850320 101272880 11797200
13:34:32 54502 8192000000 10082892752 10608316416 1135882352 12.177 100 4 7 0 1185629328 49139872 607104
13:36:29 19154 8192000000 10094984304 10599821312 1141851472 12.233 100 13 26 0 1202012816 57387344 2774000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2021 13:39:16 to 10-12-2021 13:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 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 10-12-2021 13:39:32 to 10-12-2021 13:39:34.

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          211482
MON$OLDEST_ACTIVE               213402
MON$OLDEST_SNAPSHOT             213402
MON$NEXT_TRANSACTION            213402
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                       2052384
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2680 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2680 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2680 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2680 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Dec 10 13:37:26 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Dec 10 13:37:26 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

10.12.21 13:39:35. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-10 13:39:37 No severe PSQL-related problems occured
10.12.21 13:39:38. End of report.