Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2022 07:47
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 15231032 kB
MemAvailable 30519784 kB
Buffers 0 kB
Cached 16447028 kB
SwapCached 2036 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.3
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 2022-10-05 07:47:07
DTS_END                         2022-10-05 07:47:07.7060
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/52470
TRN_ID                          209720
ATT_ID                          579
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: 8644.91 23472 1037389
customer order: creation 163.82 468 19659
customer order: refuse 14.11 478 1693
order to supplier: creation 43.41 1441 5209
order to supplier: removal 3.47 175184 416
invoice (draft): creation 43.15 3290 5178
invoice (draft): removal 47.47 1140 5696
invoice accept: apply 39.40 30698 4728
invoice accept: cancel 43.34 1464 5201
customer reserve: creation 3663.54 292 439625
customer reserve: removal 4308.04 110 516965
realization accept: apply 78.34 297 9401
realization accept: cancel 75.49 194 9059
payment from customer: creation 55.41 178 6649
payment from customer: removal 5.28 135 634
payment to supplier: creation 44.94 185 5393
payment to supplier: removal 3.49 135 419
service: total inventory turnovers 4.12 4327 494
service: total monetary turnovers 7.18 451 862
service: aggregate perf. data 0.86 16840 103
service: refresh index statistics 0.04 232140 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-10-2022 07:47:56 to 05-10-2022 07:47:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12210
TEST_TIME 2 8447
TEST_TIME 3 7408
TEST_TIME 4 7307
TEST_TIME 5 6409
TEST_TIME 6 6443
TEST_TIME 7 6809
TEST_TIME 8 6727
TEST_TIME 9 6796
TEST_TIME 10 6873
TEST_TIME 11 6837
TEST_TIME 12 7085
TEST_TIME 13 7201
TEST_TIME 14 7382
TEST_TIME 15 7496
TEST_TIME 16 7544
TEST_TIME 17 7711
TEST_TIME 18 7843
TEST_TIME 19 7857
TEST_TIME 20 8241
TEST_TIME 21 8233
TEST_TIME 22 8325
TEST_TIME 23 8438
TEST_TIME 24 8399
TEST_TIME 25 8367
TEST_TIME 26 8437
TEST_TIME 27 8600
TEST_TIME 28 8720
TEST_TIME 29 8691
TEST_TIME 30 8896
TEST_TIME 31 8928
TEST_TIME 32 8906
TEST_TIME 33 9037
TEST_TIME 34 9320
TEST_TIME 35 9655
TEST_TIME 36 9892
TEST_TIME 37 10244
TEST_TIME 38 10422
TEST_TIME 39 10501
TEST_TIME 40 10335
TEST_TIME 41 10133
TEST_TIME 42 9897
TEST_TIME 43 9816
TEST_TIME 44 9907
TEST_TIME 45 9782
TEST_TIME 46 9820
TEST_TIME 47 9906
TEST_TIME 48 9765
TEST_TIME 49 9634
TEST_TIME 50 9661
TEST_TIME 51 9573
TEST_TIME 52 9613
TEST_TIME 53 9452
TEST_TIME 54 9302
TEST_TIME 55 9249
TEST_TIME 56 9276
TEST_TIME 57 9402
TEST_TIME 58 9351
TEST_TIME 59 9246
TEST_TIME 60 9214
TEST_TIME 61 9237
TEST_TIME 62 9170
TEST_TIME 63 9150
TEST_TIME 64 9134
TEST_TIME 65 9186
TEST_TIME 66 9158
TEST_TIME 67 9191
TEST_TIME 68 9165
TEST_TIME 69 9198
TEST_TIME 70 9213
TEST_TIME 71 9133
TEST_TIME 72 9149
TEST_TIME 73 9230
TEST_TIME 74 9210
TEST_TIME 75 9167
TEST_TIME 76 9079
TEST_TIME 77 9047
TEST_TIME 78 8976
TEST_TIME 79 8967
TEST_TIME 80 8901
TEST_TIME 81 8837
TEST_TIME 82 8785
TEST_TIME 83 8822
TEST_TIME 84 8838
TEST_TIME 85 8791
TEST_TIME 86 8820
TEST_TIME 87 8852
TEST_TIME 88 8812
TEST_TIME 89 8862
TEST_TIME 90 8826
TEST_TIME 91 8876
TEST_TIME 92 8836
TEST_TIME 93 8902
TEST_TIME 94 8886
TEST_TIME 95 8904
TEST_TIME 96 8875
TEST_TIME 97 8911
TEST_TIME 98 8875
TEST_TIME 99 8861
TEST_TIME 100 8793
TEST_TIME 101 8737
TEST_TIME 102 8772
TEST_TIME 103 8732
TEST_TIME 104 8706
TEST_TIME 105 8654
TEST_TIME 106 8607
TEST_TIME 107 8596
TEST_TIME 108 8622
TEST_TIME 109 8607
TEST_TIME 110 8619
TEST_TIME 111 8603
TEST_TIME 112 8633
TEST_TIME 113 8615
TEST_TIME 114 8615
TEST_TIME 115 8649
TEST_TIME 116 8664
TEST_TIME 117 8734
TEST_TIME 118 8729
TEST_TIME 119 8696
TEST_TIME 120 8672

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-10-2022 07:48:00 to 05-10-2022 07:48:04.

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 1442872 1442872 0 0.00 0 34584 15 0 0
doc_list_biud 1442861 1442861 0 0.00 0 31902 46 0 0
sp_add_invoice_to_stock 4815 4728 87 1.80 52 249131 30698 0 87
sp_cancel_adding_invoice 5201 5201 0 0.00 5 72170 1464 0 0
sp_cancel_client_order 1693 1693 0 0.00 6 14310 478 0 0
sp_cancel_customer_reserve 516965 516965 0 0.00 1 31945 111 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 4 3485 136 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 5571 135 0 0
sp_cancel_supplier_invoice 5704 5696 8 0.14 3 23063 1141 0 8
sp_cancel_supplier_order 416 416 0 0.00 9 1418480 175185 0 0
sp_cancel_write_off 9059 9059 0 0.00 2 14725 195 0 0
sp_client_order 19659 19659 0 0.00 6 17342 469 0 0
sp_customer_reserve 439625 439625 0 0.00 0 75208 292 0 0
sp_fill_shopping_cart 30036 30036 0 0.00 1 14397 90 0 0
sp_fill_shopping_cart_clo_res 439580 439580 0 0.00 0 28483 20 0 0
sp_get_clo_for_invoice 4676 4676 0 0.00 0 28880 349 0 0
sp_kill_cost_storno 15313 15313 0 0.00 0 34569 90 0 0
sp_kill_qstorno_ret_qs2qd 1049101 1049101 0 0.00 0 31833 49 0 0
sp_kill_qty_storno 553105 553105 0 0.00 0 31895 111 0 0
sp_lock_selected_doc 19648 19648 0 0.00 0 5603 7 0 0
sp_make_cost_storno 26119 26119 0 0.00 0 16081 80 0 0
sp_multiply_rows_for_pdistr 26117 26117 0 0.00 0 6126 39 0 0
sp_multiply_rows_for_qdistr 19659 19659 0 0.00 0 17282 185 0 0
sp_pay_from_customer 6649 6649 0 0.00 6 14186 179 0 0
sp_pay_to_supplier 5393 5393 0 0.00 5 7820 186 0 0
sp_payment_common 12042 12042 0 0.00 2 7649 43 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 14271 400 0 0
sp_qd_handle_on_invoice_upd_sts 9875 9875 0 0.00 0 13341 575 0 0
sp_qd_handle_on_reserve_upd_sts 9401 9401 0 0.00 0 11388 74 0 0
sp_reserve_write_off 9401 9401 0 0.00 8 11548 298 0 0
sp_supplier_invoice 5178 5178 0 0.00 10 90880 3291 0 0
sp_supplier_order 5209 5209 0 0.00 8 28741 1442 0 0
srv_aggregate_perf_data 103 103 0 0.00 2997 123632 16841 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 789 158292 34813 0 0
srv_find_qd_qs_mism 991452 991452 0 0.00 0 27197 12 0 0
srv_make_invnt_saldo 494 494 0 0.00 23 50094 4328 0 0
srv_make_money_saldo 862 862 0 0.00 1 18599 451 0 0
srv_recalc_idx_stat 5 5 0 0.00 58565 377551 232140 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4728 4728 0 0.00 1 27403 1097 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 5612 584 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 3938 31 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1939 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 1295 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 257 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1630 38 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 99458 8025 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 10176 34 0 0
t$perf-norm:sp_client_order 19659 19659 0 0.00 0 8615 42 0 0
t$perf-norm:sp_customer_reserve 886 886 0 0.00 0 2630 16 0 0
t$perf-norm:sp_pay_from_customer 6649 6649 0 0.00 0 2671 19 0 0
t$perf-norm:sp_pay_to_supplier 5393 5393 0 0.00 0 8289 22 0 0
t$perf-norm:sp_reserve_write_off 9402 9402 0 0.00 0 9510 28 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 10253 37 0 0
t$perf-norm:sp_supplier_order 5209 5209 0 0.00 0 10357 40 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 355 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 673 20 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 4179 28 0 0
t$perf-norm:srv_make_money_saldo 862 862 0 0.00 0 399 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 131 45 0 0
v_add_invoice_to_stock 9518 9344 174 1.82 0 6144 25 0 174
v_all_customers 35863 35863 0 0.00 0 69 4 0 0
v_all_suppliers 28794 28794 0 0.00 0 126 4 0 0
v_all_wares 39310 39310 0 0.00 0 62 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1505 18 0 0
v_cancel_client_order 3386 3386 0 0.00 0 3272 13 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 3284 15 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1041 11 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 3864 21 0 0
v_cancel_supplier_invoice 804 788 16 1.99 0 1319 29 0 16
v_cancel_supplier_order 832 832 0 0.00 0 1679 13 0 0
v_cancel_write_off 1892 1892 0 0.00 0 1513 27 0 0
v_max_id_clo_ord 5209 5209 0 0.00 0 557 5 0 0
v_max_id_clo_res 886 886 0 0.00 0 830 9 0 0
v_max_id_ord_sup 5172 5172 0 0.00 0 5849 10 0 0
v_max_non_paid_invoice 1377 1377 0 0.00 0 499 9 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 1785 8 0 0
v_min_id_clo_ord 5209 5209 0 0.00 0 4072 10 0 0
v_min_id_clo_res 886 886 0 0.00 0 4300 19 0 0
v_min_id_ord_sup 5172 5172 0 0.00 0 5462 12 0 0
v_min_non_paid_invoice 5393 5393 0 0.00 0 7637 14 0 0
v_min_non_paid_realizn 6649 6649 0 0.00 0 4008 15 0 0
v_our_firm 9744 9744 0 0.00 0 52 5 0 0
v_reserve_write_off 18802 18802 0 0.00 0 5678 11 0 0
x_lock_depdocs_on_canc_invoice 10503 10503 0 0.00 0 18800 152 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 2697 376 0 0
x_make_qstorno_1000_1200 5209 5209 0 0.00 2 28348 1263 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 4 90690 3030 0 0
x_make_qstorno_2100_3300 439623 439623 0 0.00 0 75202 257 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2022 07:48:05 to 05-10-2022 07:48:09.

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:17:11 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:18:12 22999 8192000000 9720830640 10017210368 797141456 8.867 100 89 178 0 826494368 7622624 21730288
05:19:40 21267 8192000000 9759137008 10064658432 830138048 9.201 100 90 180 0 864549136 12587888 21823200
05:21:07 8528 8192000000 9789801312 10101342208 857550416 9.476 100 91 182 0 897665472 19064160 21050896
05:22:20 10080 8192000000 9827794656 10154553344 882257376 9.722 100 75 148 0 941802752 42640688 16904688
05:23:33 4157 8192000000 9895945472 10229825536 929368592 10.188 100 85 165 0 1015259360 68465600 17425168
05:24:44 2738 8192000000 9944939424 10294296576 969222528 10.579 100 89 176 0 1062490976 75214368 18054080
05:25:51 2804 8192000000 9989682928 10345844736 1003722400 10.915 100 85 168 1 1110165920 88976048 17467472
05:26:57 1403 8192000000 9996824400 10360651776 1029348752 11.162 100 94 175 1 1113775584 67610256 16816576
05:28:00 1247 8192000000 10019870896 10393067520 1048101488 11.342 100 92 181 0 1144011216 77494288 18415440
05:29:02 1313 8192000000 10033158144 10413166592 1062029440 11.476 100 99 187 6 1152527776 71104352 19393984
05:30:05 1056 8192000000 10053870480 10436222976 1066811136 11.522 100 91 173 0 1178273088 94755504 16706448
05:31:07 8848 8192000000 10042401312 10425135104 1073439280 11.585 100 68 134 1 1165905488 79483216 12982992
05:32:16 2722 8192000000 10010325136 10398650368 1078569584 11.634 100 64 125 1 1132297168 40869408 12858176
05:33:20 1606 8192000000 10048612752 10440704000 1083608592 11.682 100 72 141 1 1168340672 70574624 14157456
05:34:23 1557 8192000000 10051622608 10441564160 1086752880 11.712 100 78 135 3 1175542320 76340432 12449008
05:35:26 1044 8192000000 10066410112 10451091456 1089518720 11.738 100 97 188 1 1186805776 78206640 19080416
05:36:28 1111 8192000000 10076963152 10467262464 1088483632 11.728 100 99 183 0 1197674656 91234032 17956992
05:37:30 1017 8192000000 10062639312 10449821696 1094684608 11.787 100 91 174 1 1188629664 76607760 17337296
05:38:32 1066 8192000000 10043025520 10432454656 1092556624 11.767 100 90 171 1 1164966144 55359296 17050224
05:39:34 3837 8192000000 10032760528 10415796224 1081012320 11.657 100 73 133 3 1161447696 67532288 12903088
05:40:39 5296 8192000000 10020065792 10406043648 1056047120 11.419 98 63 115 0 1146891616 79378784 11465712
05:41:45 966 8192000000 10010938720 10397667328 1068510480 11.538 100 79 154 2 1135222848 50737328 15975040
05:42:47 3475 8192000000 10045729264 10429370368 1054264496 11.402 100 70 131 2 1169500560 102195824 13040240
05:43:52 1024 8192000000 9997406448 10368462848 1035732128 11.224 100 93 178 0 1118720912 64738944 18249840
05:44:55 930 8192000000 9963461312 10334531584 1026686016 11.137 100 85 167 3 1085275024 41480240 17108768
05:45:56 2196 8192000000 9943608928 10314579968 1013077520 11.005 100 75 135 3 1068047488 40928304 14041664
05:47:01 1525 8192000000 9983954464 10351255552 1026613152 11.136 100 94 175 3 1103445760 59138240 17694368
05:48:10 3134 8192000000 10020412816 10399903744 1063616384 11.491 100 69 132 1 1134464992 58698032 12150576
05:49:17 2676 8192000000 10024022288 10408038400 1064300448 11.498 100 60 116 0 1146076016 70351488 11424080
05:50:27 4157 8192000000 10061914384 10442264576 1083787856 11.684 100 80 152 2 1168730640 68663600 16279184
05:51:44 3244 8192000000 10093180720 10481618944 1103910416 11.875 100 82 158 0 1207262544 86222432 17129696
05:52:51 4409 8192000000 10133375792 10530500608 1099597856 11.834 99 82 160 1 1240262576 122703888 17960832
05:53:59 5371 8192000000 10151684048 10540896256 1113498544 11.966 100 88 170 2 1264961360 132439040 19023776
05:55:10 925 8192000000 10159934064 10548813824 1121316896 12.039 100 90 177 1 1272490400 131999200 19174304
05:56:14 1088 8192000000 10174620448 10572611584 1130699888 12.128 100 81 151 0 1288417120 142610528 15106704
05:57:21 2164 8192000000 10194918000 10597535744 1141060544 12.226 100 89 173 2 1300482752 140419824 19002384
05:58:28 1155 8192000000 10211478624 10604605440 1142522192 12.239 100 92 177 1 1320722160 158200064 19999904
05:59:32 1212 8192000000 10234577264 10631557120 1155070224 12.357 100 91 177 2 1342023408 166693216 20259968
06:00:39 834 8192000000 10270069264 10673242112 1160358880 12.407 100 99 194 1 1380833296 198132016 22342400
06:01:43 2249 8192000000 10284941328 10707083264 1171066496 12.507 100 91 182 3 1399263344 207941872 20254976
06:02:54 847 8192000000 10316485584 10727219200 1178224432 12.574 100 95 188 1 1425808096 225195200 22388464
06:04:01 1100 8192000000 10331117280 10753400832 1177556304 12.567 100 94 184 1 1442565968 243854640 21155024
06:05:06 1302 8192000000 10356464128 10773340160 1182078432 12.610 100 95 181 1 1460291600 257479024 20734144
06:06:11 1199 8192000000 10366192320 10803064832 1191117088 12.694 100 97 190 1 1471684144 259019936 21547120
06:07:16 1049 8192000000 10403251872 10853945344 1190011088 12.683 100 98 187 1 1509709728 297051264 22647376
06:08:20 2236 8192000000 10378072384 10796507136 1173235696 12.527 99 80 155 2 1485853808 293827920 18790192
06:09:27 4072 8192000000 10369905264 10810761216 1181265488 12.602 100 68 133 1 1479627936 282286896 16075552
06:10:37 2109 8192000000 10389851440 10814746624 1184680128 12.634 100 75 146 2 1501588160 299618320 17289712
06:11:47 1080 8192000000 10431168320 10858106880 1193476752 12.716 100 95 179 1 1532692624 318246048 20969824
06:12:53 864 8192000000 10428217136 10858807296 1183658768 12.624 100 97 186 1 1529367888 323365952 22343168
06:13:57 784 8192000000 10462222768 10900037632 1177883216 12.570 100 100 196 1 1564020512 361911536 24225760
06:15:01 4243 8192000000 10483900768 10937933824 1196069360 12.740 100 59 117 1 1585254160 374772176 14412624
06:16:22 7846 8192000000 10484781504 10919837696 1184744752 12.634 100 50 97 0 1584395216 387364032 12286432
06:17:34 8937 8192000000 10447183104 10912505856 1161725232 12.419 99 45 88 0 1542730864 369936752 11068880
06:18:58 24842 8192000000 10477521712 10923692032 1163816560 12.439 99 43 84 2 1574175600 399839952 10519088
06:20:26 7161 8192000000 10497702016 10951041024 1175645968 12.550 100 58 112 0 1599249088 409540240 14062880
06:21:42 6972 8192000000 10440660368 10935848960 1172443648 12.520 100 44 88 0 1533585056 350331968 10809440
06:22:57 10877 8192000000 10355751520 10800017408 1131401872 12.135 99 30 60 0 1461266800 321602320 8262608
06:24:25 24570 8192000000 10208663024 10696871936 1135058832 12.169 99 11 22 0 1309232976 171065760 3108384
06:25:52 31864 8192000000 10111721840 10549669888 1142987776 12.244 99 29 54 0 1205357072 56272128 6097168
06:27:31 158289 8192000000 10168927504 10612408320 1123818992 12.063 97 42 82 1 1224192624 91734672 8638960
06:31:13 64021 8192000000 10132592208 10596704256 1130525120 12.126 99 18 34 0 1262720288 128440464 3754704
06:33:18 37601 8192000000 10167817616 10667147264 1148168432 12.292 100 15 29 0 1272859888 121245264 3446192
06:35:04 55085 8192000000 10122874832 10579156992 1134924688 12.168 99 17 34 1 1222180208 83176736 4078784
06:37:22 104234 8192000000 10125613248 10571784192 1107633616 11.910 97 25 50 0 1193886048 81065584 5186848
06:41:28 57970 8192000000 10178885776 10637279232 1125579952 12.080 98 47 88 2 1269757536 134681488 9496096
06:43:28 55601 8192000000 10161588160 10661203968 1110009600 11.933 98 20 39 0 1241526768 127207392 4309776
06:46:43 84096 8192000000 10088914496 10597437440 1137258224 12.190 100 13 26 0 1208078352 68432384 2387744
06:49:17 37868 8192000000 10054054752 10501984256 1080195536 11.649 95 38 74 0 1114960496 27800672 6964288
06:50:55 124492 8192000000 10073712160 10584510464 1088097792 11.725 96 14 28 0 1151293184 59882144 3313248
06:54:24 80750 8192000000 10070895568 10517929984 1087824272 11.722 96 21 42 0 1150929792 58685664 4419856
06:56:52 99342 8192000000 10089537520 10524434432 1118691072 12.015 99 21 41 0 1154471600 31915296 3865232
06:59:34 53219 8192000000 10109320368 10610208768 1129423760 12.116 99 30 58 0 1225246384 89459936 6362688
07:01:36 28197 8192000000 10103338176 10548723712 1127632368 12.099 99 45 90 2 1185636288 49214240 8789680
07:03:31 63033 8192000000 10121726880 10560925696 1130111488 12.122 99 50 98 0 1208940224 69002000 9826736
07:05:37 6297 8192000000 10078882080 10498666496 1116033456 11.990 99 64 115 1 1173191312 45965392 11192464
07:06:44 55644 8192000000 10123789168 10598764544 1106673248 11.901 97 14 28 0 1204179328 94325392 3180688
07:08:47 77373 8192000000 10149027696 10619551744 1129005440 12.112 99 20 40 0 1268487248 135094320 4387488
07:11:06 63398 8192000000 10097416752 10574536704 1117841856 12.007 98 17 31 1 1183968608 62438080 3688672
07:13:11 68892 8192000000 10067785792 10553950208 1132408512 12.144 99 18 36 0 1198897488 62649760 3839216
07:15:23 18730 8192000000 10101776128 10530713600 1106524624 11.900 98 44 85 0 1170647920 55572688 8550608
07:16:42 67322 8192000000 10093800112 10543349760 1081305024 11.660 96 25 48 2 1126825264 40278144 5242096
07:18:53 45254 8192000000 10104564976 10537308160 1119924288 12.026 99 33 66 0 1203549568 77257600 6367680
07:20:53 14475 8192000000 10080033184 10522234880 1136815808 12.186 100 46 87 1 1181749536 35197344 9736384
07:22:16 59924 8192000000 10071980352 10518114304 1136515280 12.183 100 31 61 1 1180913472 38533536 5864656
07:24:18 47300 8192000000 10117899888 10570469376 1133930688 12.158 100 38 75 0 1213094880 71971856 7192336
07:26:08 128019 8192000000 10080189664 10539585536 1118661808 12.014 98 35 69 1 1175704000 49718448 7323744
07:29:18 69201 8192000000 10034585072 10489520128 1097925760 11.818 97 19 37 1 1121490928 19722992 3842176
07:31:32 52599 8192000000 10067066304 10538074112 1135131392 12.170 100 11 22 0 1183422416 45620240 2670784
07:33:24 110242 8192000000 10106236384 10585776128 1131375472 12.134 99 6 12 0 1197744624 65145088 1224064
07:37:05 19405 8192000000 10070332112 10522255360 1096878416 11.808 96 35 68 1 1146890128 42795136 7216576
07:38:25 22291 8192000000 10103386192 10550689792 1140984688 12.225 100 31 59 0 1195821472 48887824 5948960
07:39:49 31802 8192000000 10101016976 10535321600 1114148064 11.972 98 36 70 0 1186283552 64779344 7356144
07:41:21 36663 8192000000 10119130848 10565058560 1092731872 11.769 97 36 70 1 1183040256 82552368 7756016
07:42:59 29717 8192000000 10133406160 10568601600 1107307264 11.907 99 46 92 3 1211281264 94293328 9680672
07:44:33 96916 8192000000 10087413760 10528542720 1085982560 11.704 97 23 46 0 1168067360 77475344 4609456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-10-2022 07:48:11 to 05-10-2022 07:48:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2022 07:48:28 to 05-10-2022 07:48:30.

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          207134
MON$OLDEST_ACTIVE               210494
MON$OLDEST_SNAPSHOT             210494
MON$NEXT_TRANSACTION            210494
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                       2067040
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2855 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2855 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2855 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Oct  5 07:47:14 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

05.10.22 07:48:31. 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
2022-10-05 07:48:34 No severe PSQL-related problems occured
05.10.22 07:48:35. End of report.