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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16431184 kB
MemAvailable 31319880 kB
Buffers 0 kB
Cached 15249716 kB
SwapCached 1220 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 2022-01-06 07:37:08
DTS_END                         2022-01-06 07:37:08.6900
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/50014
TRN_ID                          203691
ATT_ID                          622
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: 8532.35 24291 1023882
customer order: creation 157.57 512 18908
customer order: refuse 13.88 483 1666
order to supplier: creation 41.47 1482 4976
order to supplier: removal 3.29 189281 395
invoice (draft): creation 41.23 3472 4947
invoice (draft): removal 48.70 1155 5844
invoice accept: apply 37.73 28634 4527
invoice accept: cancel 43.84 1346 5261
customer reserve: creation 3570.14 266 428417
customer reserve: removal 4297.63 111 515715
realization accept: apply 77.20 297 9264
realization accept: cancel 81.25 185 9750
payment from customer: creation 53.93 181 6471
payment from customer: removal 5.56 135 667
payment to supplier: creation 44.40 213 5328
payment to supplier: removal 3.03 124 363
service: total inventory turnovers 3.52 5001 422
service: total monetary turnovers 7.18 417 861
service: aggregate perf. data 0.79 19444 95
service: refresh index statistics 0.04 233076 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2022 07:38:37 to 06-01-2022 07:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6836
TEST_TIME 2 5796
TEST_TIME 3 5613
TEST_TIME 4 6157
TEST_TIME 5 6232
TEST_TIME 6 6184
TEST_TIME 7 6022
TEST_TIME 8 6142
TEST_TIME 9 6368
TEST_TIME 10 6401
TEST_TIME 11 6305
TEST_TIME 12 6604
TEST_TIME 13 6579
TEST_TIME 14 6555
TEST_TIME 15 6497
TEST_TIME 16 6559
TEST_TIME 17 6671
TEST_TIME 18 6760
TEST_TIME 19 6734
TEST_TIME 20 6945
TEST_TIME 21 7175
TEST_TIME 22 7153
TEST_TIME 23 7369
TEST_TIME 24 7495
TEST_TIME 25 7527
TEST_TIME 26 7958
TEST_TIME 27 8403
TEST_TIME 28 8665
TEST_TIME 29 8762
TEST_TIME 30 8921
TEST_TIME 31 9282
TEST_TIME 32 9319
TEST_TIME 33 9519
TEST_TIME 34 10435
TEST_TIME 35 10357
TEST_TIME 36 10084
TEST_TIME 37 9896
TEST_TIME 38 10011
TEST_TIME 39 10030
TEST_TIME 40 9937
TEST_TIME 41 9826
TEST_TIME 42 9719
TEST_TIME 43 9568
TEST_TIME 44 9438
TEST_TIME 45 9547
TEST_TIME 46 9498
TEST_TIME 47 9399
TEST_TIME 48 9304
TEST_TIME 49 9249
TEST_TIME 50 9149
TEST_TIME 51 9047
TEST_TIME 52 8976
TEST_TIME 53 8856
TEST_TIME 54 8822
TEST_TIME 55 8779
TEST_TIME 56 8868
TEST_TIME 57 8788
TEST_TIME 58 8746
TEST_TIME 59 8672
TEST_TIME 60 8673
TEST_TIME 61 8680
TEST_TIME 62 8686
TEST_TIME 63 8663
TEST_TIME 64 8728
TEST_TIME 65 8769
TEST_TIME 66 8770
TEST_TIME 67 8725
TEST_TIME 68 8667
TEST_TIME 69 8666
TEST_TIME 70 8796
TEST_TIME 71 8881
TEST_TIME 72 8905
TEST_TIME 73 9063
TEST_TIME 74 9053
TEST_TIME 75 9048
TEST_TIME 76 8971
TEST_TIME 77 8928
TEST_TIME 78 8932
TEST_TIME 79 8861
TEST_TIME 80 8839
TEST_TIME 81 8828
TEST_TIME 82 8787
TEST_TIME 83 8812
TEST_TIME 84 8756
TEST_TIME 85 8702
TEST_TIME 86 8682
TEST_TIME 87 8640
TEST_TIME 88 8655
TEST_TIME 89 8652
TEST_TIME 90 8604
TEST_TIME 91 8775
TEST_TIME 92 8728
TEST_TIME 93 8765
TEST_TIME 94 8792
TEST_TIME 95 8804
TEST_TIME 96 8822
TEST_TIME 97 8823
TEST_TIME 98 8914
TEST_TIME 99 8896
TEST_TIME 100 8897
TEST_TIME 101 8886
TEST_TIME 102 8946
TEST_TIME 103 8878
TEST_TIME 104 8845
TEST_TIME 105 8814
TEST_TIME 106 8796
TEST_TIME 107 8775
TEST_TIME 108 8746
TEST_TIME 109 8749
TEST_TIME 110 8714
TEST_TIME 111 8670
TEST_TIME 112 8654
TEST_TIME 113 8631
TEST_TIME 114 8660
TEST_TIME 115 8636
TEST_TIME 116 8628
TEST_TIME 117 8612
TEST_TIME 118 8613
TEST_TIME 119 8609
TEST_TIME 120 8554

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2022 07:38:40 to 06-01-2022 07:38:44.

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 1417159 1417159 0 0.00 0 32569 14 0 0
doc_list_biud 1417142 1417142 0 0.00 0 24917 47 0 0
sp_add_invoice_to_stock 4626 4527 99 2.14 78 233324 28635 0 99
sp_cancel_adding_invoice 5261 5261 0 0.00 6 122502 1347 0 0
sp_cancel_client_order 1666 1666 0 0.00 6 10042 484 0 0
sp_cancel_customer_reserve 515715 515715 0 0.00 1 24920 112 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 4 9676 135 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 4 1395 124 0 0
sp_cancel_supplier_invoice 5850 5844 6 0.10 2 16985 1155 0 6
sp_cancel_supplier_order 395 395 0 0.00 7 1398380 189282 0 0
sp_cancel_write_off 9750 9750 0 0.00 2 20262 185 0 0
sp_client_order 18908 18908 0 0.00 6 41513 512 0 0
sp_customer_reserve 428417 428417 0 0.00 1 57540 267 0 0
sp_fill_shopping_cart 28821 28821 0 0.00 2 15376 85 0 0
sp_fill_shopping_cart_clo_res 428386 428386 0 0.00 0 14823 21 0 0
sp_get_clo_for_invoice 4487 4487 0 0.00 0 31158 374 0 0
sp_kill_cost_storno 16041 16041 0 0.00 0 18564 88 0 0
sp_kill_qstorno_ret_qs2qd 1047419 1047419 0 0.00 0 24912 49 0 0
sp_kill_qty_storno 552376 552376 0 0.00 0 24917 113 0 0
sp_lock_selected_doc 19150 19150 0 0.00 0 12767 6 0 0
sp_make_cost_storno 25548 25548 0 0.00 0 14030 88 0 0
sp_multiply_rows_for_pdistr 25544 25544 0 0.00 0 8860 44 0 0
sp_multiply_rows_for_qdistr 18907 18907 0 0.00 0 10109 183 0 0
sp_pay_from_customer 6471 6471 0 0.00 5 14161 181 0 0
sp_pay_to_supplier 5328 5328 0 0.00 6 15110 213 0 0
sp_payment_common 11795 11795 0 0.00 2 8196 50 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 9965 403 0 0
sp_qd_handle_on_invoice_upd_sts 9746 9746 0 0.00 0 20292 626 0 0
sp_qd_handle_on_reserve_upd_sts 9260 9260 0 0.00 0 20023 71 0 0
sp_reserve_write_off 9264 9264 0 0.00 8 20092 297 0 0
sp_supplier_invoice 4947 4947 0 0.00 11 85334 3472 0 0
sp_supplier_order 4976 4976 0 0.00 8 35287 1482 0 0
srv_aggregate_perf_data 95 95 0 0.00 2470 246690 19444 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 767 154767 34653 0 0
srv_find_qd_qs_mism 978362 978362 0 0.00 0 32544 10 0 0
srv_make_invnt_saldo 422 422 0 0.00 82 99366 5001 0 0
srv_make_money_saldo 861 861 0 0.00 1 9373 417 0 0
srv_recalc_idx_stat 5 5 0 0.00 83116 433609 233076 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4529 4529 0 0.00 2 12624 1050 0 0
t$perf-norm:sp_cancel_adding_invoice 346 346 0 0.00 1 12426 493 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 4068 31 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2369 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 3909 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 1510 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 18549 125 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 113643 8048 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 1461 22 0 0
t$perf-norm:sp_client_order 18909 18909 0 0.00 0 9128 43 0 0
t$perf-norm:sp_customer_reserve 893 893 0 0.00 0 4194 22 0 0
t$perf-norm:sp_pay_from_customer 6472 6472 0 0.00 0 5836 24 0 0
t$perf-norm:sp_pay_to_supplier 5328 5328 0 0.00 0 2848 20 0 0
t$perf-norm:sp_reserve_write_off 9264 9264 0 0.00 0 7753 26 0 0
t$perf-norm:sp_supplier_invoice 4947 4947 0 0.00 0 6757 37 0 0
t$perf-norm:sp_supplier_order 4976 4976 0 0.00 0 2799 33 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 865 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 876 23 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 8565 50 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 1092 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 205 93 0 0
v_add_invoice_to_stock 9158 8960 198 2.16 0 9772 26 0 198
v_all_customers 34660 34660 0 0.00 0 171 4 0 0
v_all_suppliers 27813 27813 0 0.00 0 170 4 0 0
v_all_wares 37808 37808 0 0.00 0 49 4 0 0
v_cancel_adding_invoice 692 692 0 0.00 0 590 14 0 0
v_cancel_client_order 3332 3332 0 0.00 0 3200 14 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 9623 17 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 891 10 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 494 11 0 0
v_cancel_supplier_invoice 766 754 12 1.56 0 2464 29 0 12
v_cancel_supplier_order 790 790 0 0.00 0 1979 16 0 0
v_cancel_write_off 1874 1874 0 0.00 0 2756 27 0 0
v_max_id_clo_ord 4971 4971 0 0.00 0 978 5 0 0
v_max_id_clo_res 893 893 0 0.00 0 1102 9 0 0
v_max_id_ord_sup 4943 4943 0 0.00 0 3449 6 0 0
v_max_non_paid_invoice 1333 1333 0 0.00 0 1255 9 0 0
v_max_non_paid_realizn 3393 3393 0 0.00 0 2222 8 0 0
v_min_id_clo_ord 4970 4970 0 0.00 0 1588 8 0 0
v_min_id_clo_res 893 893 0 0.00 0 769 8 0 0
v_min_id_ord_sup 4943 4943 0 0.00 0 6789 11 0 0
v_min_non_paid_invoice 5327 5327 0 0.00 0 7448 18 0 0
v_min_non_paid_realizn 6467 6467 0 0.00 0 7665 22 0 0
v_our_firm 9298 9298 0 0.00 0 205 4 0 0
v_reserve_write_off 18520 18520 0 0.00 0 6326 11 0 0
x_lock_depdocs_on_canc_invoice 10728 10728 0 0.00 0 18993 141 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 8498 415 0 0
x_make_qstorno_1000_1200 4976 4976 0 0.00 3 35274 1301 0 0
x_make_qstorno_1200_2000 4947 4947 0 0.00 3 73570 3238 0 0
x_make_qstorno_2100_3300 428414 428414 0 0.00 0 48285 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2022 07:38:45 to 06-01-2022 07:38:49.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:12 12 8192000000 8807046048 8821465088 10639856 0.129 4 3 5 0 10860416 123968 96592
05:08:13 37219 8192000000 9717484816 10013966336 800431408 8.901 100 85 170 2 828726576 7764160 20531008
05:10:00 24507 8192000000 9753798304 10061148160 830128608 9.201 100 82 164 0 862145648 12261088 19755952
05:11:31 25523 8192000000 9807243792 10127118336 868017072 9.580 100 80 160 0 909644192 23056224 18570896
05:12:59 11511 8192000000 9871991552 10206199808 915648128 10.053 100 72 144 0 975790064 44460192 15681744
05:14:15 2783 8192000000 9944102208 10288869376 972133616 10.608 100 87 172 0 1053562832 63313552 18115664
05:15:21 3075 8192000000 9972320016 10325716992 1002700048 10.905 100 84 165 1 1085569616 66201680 16667888
05:16:26 1903 8192000000 10016886032 10374832128 1020933216 11.081 100 95 188 0 1126849328 86694416 19221696
05:17:29 964 8192000000 10035800736 10403254272 1042547088 11.289 100 98 191 0 1153342864 91726816 19068960
05:18:33 1252 8192000000 10015202640 10387320832 1051211104 11.372 100 95 187 2 1136881280 67589104 18081072
05:19:36 1144 8192000000 10042644448 10421542912 1063660032 11.491 100 98 191 0 1162283120 79461312 19161776
05:20:38 1136 8192000000 10035567584 10412515328 1069072768 11.543 100 97 188 1 1162984384 74154560 19757056
05:21:41 1154 8192000000 10044866352 10421870592 1075113088 11.601 100 98 188 2 1165464160 71448464 18902608
05:22:43 1095 8192000000 10049156960 10435084288 1082615872 11.672 100 96 187 0 1169712816 68420496 18676448
05:23:47 1132 8192000000 10054645392 10436161536 1081294496 11.660 100 92 178 1 1173490048 75257984 16937568
05:24:49 1021 8192000000 10052317904 10434699264 1084442784 11.690 100 89 175 0 1171819584 68611984 18764816
05:25:51 957 8192000000 10041953616 10428747776 1090403104 11.746 100 86 166 0 1163775264 56130080 17242080
05:26:54 1606 8192000000 10055738864 10443653120 1093854848 11.779 100 76 150 0 1177138640 66877840 16405952
05:27:56 988 8192000000 10064502608 10454089728 1096269584 11.802 100 88 174 1 1186494032 72805040 17419408
05:28:59 1269 8192000000 10070361424 10456137728 1091929040 11.761 100 92 176 1 1193218336 82870640 18418656
05:30:01 1091 8192000000 10035944864 10418679808 1082961744 11.676 100 99 193 1 1157201024 55536320 18702960
05:31:02 1306 8192000000 10003402064 10385686528 1061699984 11.473 99 78 141 0 1126627984 50287984 14640016
05:32:05 1082 8192000000 9996968416 10375430144 1044510176 11.308 99 91 165 3 1124771456 62935632 17325648
05:33:07 1191 8192000000 9987387456 10357100544 1041962032 11.284 99 90 172 4 1109553632 50298976 17292624
05:34:08 1072 8192000000 9971817168 10344673280 1039607472 11.261 100 92 166 0 1098364256 41209520 17547264
05:35:10 1170 8192000000 10012632432 10383101952 1038692304 11.252 100 96 179 4 1136416592 78705120 19019168
05:36:13 1086 8192000000 10016006512 10390175744 1042342416 11.287 100 84 163 2 1137373040 79209072 15821552
05:37:16 30156 8192000000 9999071744 10372087808 1047756368 11.339 100 76 139 1 1116113440 55575104 12781968
05:38:53 8204 8192000000 10042719264 10434142208 1071521328 11.567 100 64 126 0 1163292864 79427968 12343568
05:40:05 997 8192000000 10062474576 10439016448 1084072080 11.686 100 99 181 0 1184698128 81870240 18755808
05:41:11 2907 8192000000 10093027376 10479296512 1098961440 11.828 100 77 149 1 1215919232 101371760 15586032
05:42:17 1237 8192000000 10151306672 10532577280 1109285088 11.926 100 87 169 0 1270797856 143581488 17931280
05:43:22 1133 8192000000 10173479920 10553831424 1120192976 12.029 100 94 178 0 1285259936 145912432 19154528
05:44:27 1677 8192000000 10226016880 10611216384 1131958224 12.140 100 87 172 0 1341914512 191138864 18817424
05:45:32 1603 8192000000 10224628416 10618146816 1141077968 12.226 100 74 141 1 1340519152 184359344 15081840
05:46:40 10694 8192000000 10236802656 10646294528 1151322336 12.322 100 77 152 1 1352915168 184912592 16680240
05:47:54 2565 8192000000 10274650448 10675933184 1165560272 12.455 100 76 147 0 1388792272 207355744 15876256
05:48:59 1353 8192000000 10307892768 10720849920 1165963632 12.459 100 95 187 0 1421293248 234588656 20740960
05:50:06 1218 8192000000 10344813920 10744287232 1178221168 12.574 100 88 160 1 1451863952 255865216 17777568
05:51:11 863 8192000000 10388804048 10790535168 1185340912 12.640 100 97 187 1 1501548400 294025488 22182000
05:52:18 818 8192000000 10419389536 10823376896 1190820496 12.691 100 96 183 0 1528133744 316235632 21077616
05:53:25 2254 8192000000 10462279952 10887151616 1189213248 12.676 100 89 177 0 1569361504 359271808 20876448
05:54:33 750 8192000000 10480272432 10883145728 1196270160 12.742 100 97 194 0 1583050080 364998384 21781536
05:55:38 910 8192000000 10496387088 10908884992 1187188256 12.657 100 95 183 1 1602383552 393982656 21212640
05:56:41 922 8192000000 10492212016 10933665792 1188359904 12.668 100 93 182 1 1602116032 391830176 21925952
05:57:46 922 8192000000 10527918848 10964791296 1183046160 12.619 99 99 190 3 1631980464 425786944 23147360
05:58:51 1682 8192000000 10576136304 10987806720 1193321728 12.714 100 97 192 0 1684097712 466698048 24077936
05:59:58 970 8192000000 10563866576 11014336512 1183581264 12.624 100 92 182 0 1667803888 462152144 22070480
06:01:02 1328 8192000000 10568597936 11002183680 1169087056 12.488 100 95 190 1 1669554912 477663456 22804400
06:02:06 1005 8192000000 10603263968 11057614848 1189589840 12.680 100 97 194 1 1704450080 491164128 23696112
06:03:10 2486 8192000000 10612040224 11063689216 1190313200 12.686 100 87 168 0 1718036224 507570000 20153024
06:04:19 10325 8192000000 10557117088 11044786176 1189696784 12.681 100 56 110 0 1661949376 457674448 14578144
06:05:34 21008 8192000000 10516568448 10964430848 1179893520 12.589 100 40 80 2 1603241568 412971760 10376288
06:06:56 17983 8192000000 10486049184 10975584256 1166755680 12.466 100 40 80 1 1613174464 435929344 10489440
06:08:17 24768 8192000000 10417852032 10936438784 1146658544 12.278 99 28 56 0 1552169040 398187392 7323104
06:10:25 17779 8192000000 10268422368 10810580992 1131368944 12.134 99 36 72 1 1400323408 260213600 8740864
06:11:52 24693 8192000000 10115034176 10542198784 1151639664 12.325 100 36 71 2 1216960384 58337632 6983088
06:13:25 53801 8192000000 10163300272 10597036032 1137262800 12.190 99 55 107 1 1244403520 95874048 11266672
06:15:20 27432 8192000000 10161934832 10620493824 1147656720 12.287 100 32 65 0 1278268976 122896144 7716112
06:16:50 46271 8192000000 10062328096 10520985600 1140145680 12.217 100 16 33 0 1180671104 36395808 4129616
06:18:50 93403 8192000000 10100091632 10560622592 1110898592 11.941 97 20 40 0 1174068944 59427024 3743328
06:21:29 77234 8192000000 10055324208 10525708288 1140536336 12.221 100 22 40 2 1181193744 36374496 4282912
06:23:48 19195 8192000000 10055634240 10492538880 1101586240 11.853 96 40 72 4 1140068416 30993328 7488848
06:25:08 59156 8192000000 10052283360 10496131072 1101031536 11.847 97 28 56 1 1144488960 37105904 6351520
06:27:12 154767 8192000000 10063808592 10537418752 1097282320 11.812 96 24 48 0 1147516880 45347456 4887104
06:31:39 43810 8192000000 10124569168 10566131712 1143789344 12.251 100 24 48 0 1209450176 60370320 5290512
06:33:23 53561 8192000000 10088036608 10586427392 1135381728 12.172 99 10 19 1 1192798832 55274016 2143088
06:35:19 83209 8192000000 10081948032 10558537728 1103956656 11.875 97 19 36 0 1187768512 79772016 4039840
06:37:43 79174 8192000000 10089808720 10552688640 1124729472 12.072 99 10 19 0 1201922848 75561216 1632160
06:40:17 66486 8192000000 10104409216 10593972224 1142560112 12.240 100 18 36 1 1230742352 84531504 3650736
06:42:24 16611 8192000000 10071149600 10506477568 1131668688 12.137 99 32 64 1 1162172688 24130256 6373744
06:43:56 25273 8192000000 10137277456 10572632064 1120548848 12.032 98 32 64 0 1203408032 76426096 6433088
06:45:22 14089 8192000000 10204256496 10621247488 1140745712 12.223 99 64 126 1 1289165776 135570032 12850032
06:46:42 65822 8192000000 10184632176 10645377024 1134464576 12.163 99 20 40 0 1312076016 173229552 4381888
06:48:56 73916 8192000000 10158441232 10638868480 1127002256 12.093 99 16 32 1 1273670480 143212912 3455312
06:51:35 29736 8192000000 10109468736 10537242624 1126998048 12.093 99 39 71 0 1184286704 50407456 6881200
06:53:05 102417 8192000000 10105718336 10540388352 1133581424 12.155 99 31 62 1 1175087888 34732880 6773584
06:55:50 41495 8192000000 10072990864 10517565440 1137903824 12.196 100 34 63 2 1169759856 24640688 7215344
06:57:36 31572 8192000000 10127876688 10565988352 1153676272 12.344 100 30 60 1 1228557216 68832272 6048672
06:59:15 91814 8192000000 10079360416 10567589888 1112394208 11.955 97 14 28 1 1172031776 56736192 2901376
07:01:48 51790 8192000000 10087463072 10557702144 1121077936 12.037 98 13 26 1 1169371536 45106704 3186896
07:03:50 62739 8192000000 10120659200 10587279360 1120630272 12.033 98 23 45 0 1221052928 95444048 4978608
07:05:53 70204 8192000000 10125273744 10557632512 1086346992 11.708 95 17 33 0 1201715584 111456960 3911632
07:08:07 38076 8192000000 10083404736 10555371520 1122929424 12.055 99 21 43 1 1190847776 63306704 4611648
07:09:47 50313 8192000000 10128822832 10599038976 1118853904 12.016 98 30 60 1 1223892960 98789472 6249584
07:11:38 13515 8192000000 10104559984 10527875072 1133511792 12.154 100 54 102 2 1187994672 44442208 10040672
07:12:54 25055 8192000000 10135336992 10573422592 1132059072 12.141 99 56 108 2 1217754096 74581360 11113664
07:14:56 34658 8192000000 10131508016 10603945984 1120835088 12.035 98 27 54 1 1193992304 67724832 5432384
07:16:31 78098 8192000000 10105801808 10554253312 1136533024 12.183 99 23 46 1 1187308368 45073216 5702128
07:18:50 128247 8192000000 10086299872 10565922816 1125348016 12.077 98 29 57 0 1235118400 103803072 5967312
07:22:25 13302 8192000000 10085482848 10540802048 1140662976 12.222 100 30 60 0 1181465328 34422192 6380160
07:23:42 49575 8192000000 10085636032 10536546304 1120703504 12.034 98 22 44 0 1166987280 42192016 4091760
07:25:34 70915 8192000000 10050683504 10518474752 1114105376 11.971 98 24 47 5 1165090352 46261024 4723952
07:27:45 57659 8192000000 10073097008 10509365248 1102790128 11.864 97 20 40 0 1161184480 53950144 4444208
07:29:44 58272 8192000000 10091309520 10560454656 1126650416 12.090 99 19 39 2 1190696800 59806624 4239760
07:31:43 50850 8192000000 10044832688 10485846016 1107203520 11.906 99 18 36 1 1134843824 23826592 3813712
07:33:39 45805 8192000000 10102767216 10546208768 1120042288 12.027 99 33 66 0 1201003440 73610976 7350176
07:35:26 23908 8192000000 10105819712 10534821888 1112970880 11.961 98 23 46 1 1185415968 67426928 5018160
07:36:59 26277 8192000000 9055119648 9338126336 174183888 2.081 16 7 12 1 222644768 46892064 1568816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2022 07:38:51 to 06-01-2022 07:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2022 07:39:08 to 06-01-2022 07:39:10.

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          203690
MON$OLDEST_ACTIVE               207742
MON$OLDEST_SNAPSHOT             207742
MON$NEXT_TRANSACTION            207742
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                       2042336
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             754
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Thu Jan  6 07:37:28 2022
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
--- end of diff output ---

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

06.01.22 07:39:11. 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-01-06 07:39:13 No severe PSQL-related problems occured
06.01.22 07:39:15. End of report.