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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16471556 kB
MemAvailable 31435312 kB
Buffers 0 kB
Cached 15246860 kB
SwapCached 440 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-24 07:37:07
DTS_END                         2021-12-24 07:37:07.4230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44386
TRN_ID                          241500
ATT_ID                          685
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: 9408.65 21952 1129038
customer order: creation 175.19 419 21023
customer order: refuse 15.58 341 1870
order to supplier: creation 46.94 1454 5633
order to supplier: removal 3.53 103434 424
invoice (draft): creation 47.95 3409 5754
invoice (draft): removal 52.73 843 6328
invoice accept: apply 43.18 17905 5181
invoice accept: cancel 47.49 898 5699
customer reserve: creation 3979.55 178 477546
customer reserve: removal 4695.12 63 563414
realization accept: apply 86.43 193 10372
realization accept: cancel 81.81 117 9817
payment from customer: creation 60.83 123 7300
payment from customer: removal 5.98 104 718
payment to supplier: creation 51.05 130 6126
payment to supplier: removal 3.63 87 435
service: total inventory turnovers 4.18 3023 502
service: total monetary turnovers 6.52 445 782
service: aggregate perf. data 0.91 16827 109
service: refresh index statistics 0.04 289055 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2021 07:38:51 to 24-12-2021 07:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7084
TEST_TIME 2 6585
TEST_TIME 3 6536
TEST_TIME 4 6171
TEST_TIME 5 6092
TEST_TIME 6 6085
TEST_TIME 7 5852
TEST_TIME 8 6437
TEST_TIME 9 6280
TEST_TIME 10 7075
TEST_TIME 11 7116
TEST_TIME 12 7598
TEST_TIME 13 8041
TEST_TIME 14 8326
TEST_TIME 15 8422
TEST_TIME 16 8425
TEST_TIME 17 8353
TEST_TIME 18 8716
TEST_TIME 19 8923
TEST_TIME 20 9087
TEST_TIME 21 9463
TEST_TIME 22 9475
TEST_TIME 23 9877
TEST_TIME 24 11424
TEST_TIME 25 11459
TEST_TIME 26 11333
TEST_TIME 27 11594
TEST_TIME 28 11469
TEST_TIME 29 11349
TEST_TIME 30 11108
TEST_TIME 31 11025
TEST_TIME 32 10851
TEST_TIME 33 10803
TEST_TIME 34 10707
TEST_TIME 35 10661
TEST_TIME 36 10650
TEST_TIME 37 10649
TEST_TIME 38 10554
TEST_TIME 39 10404
TEST_TIME 40 10495
TEST_TIME 41 10388
TEST_TIME 42 10377
TEST_TIME 43 10379
TEST_TIME 44 10435
TEST_TIME 45 10387
TEST_TIME 46 10448
TEST_TIME 47 10510
TEST_TIME 48 10494
TEST_TIME 49 10521
TEST_TIME 50 10457
TEST_TIME 51 10481
TEST_TIME 52 10458
TEST_TIME 53 10382
TEST_TIME 54 10480
TEST_TIME 55 10331
TEST_TIME 56 10259
TEST_TIME 57 10149
TEST_TIME 58 10142
TEST_TIME 59 10077
TEST_TIME 60 10023
TEST_TIME 61 9968
TEST_TIME 62 9891
TEST_TIME 63 9773
TEST_TIME 64 9767
TEST_TIME 65 9750
TEST_TIME 66 9707
TEST_TIME 67 9730
TEST_TIME 68 9704
TEST_TIME 69 9822
TEST_TIME 70 9809
TEST_TIME 71 9905
TEST_TIME 72 9856
TEST_TIME 73 9862
TEST_TIME 74 9835
TEST_TIME 75 9923
TEST_TIME 76 9812
TEST_TIME 77 9807
TEST_TIME 78 9802
TEST_TIME 79 9736
TEST_TIME 80 9693
TEST_TIME 81 9646
TEST_TIME 82 9611
TEST_TIME 83 9567
TEST_TIME 84 9602
TEST_TIME 85 9612
TEST_TIME 86 9686
TEST_TIME 87 9720
TEST_TIME 88 9685
TEST_TIME 89 9784
TEST_TIME 90 9734
TEST_TIME 91 9795
TEST_TIME 92 9863
TEST_TIME 93 9839
TEST_TIME 94 9753
TEST_TIME 95 9703
TEST_TIME 96 9707
TEST_TIME 97 9653
TEST_TIME 98 9591
TEST_TIME 99 9554
TEST_TIME 100 9489
TEST_TIME 101 9504
TEST_TIME 102 9506
TEST_TIME 103 9502
TEST_TIME 104 9480
TEST_TIME 105 9481
TEST_TIME 106 9525
TEST_TIME 107 9499
TEST_TIME 108 9511
TEST_TIME 109 9481
TEST_TIME 110 9607
TEST_TIME 111 9586
TEST_TIME 112 9671
TEST_TIME 113 9663
TEST_TIME 114 9649
TEST_TIME 115 9629
TEST_TIME 116 9564
TEST_TIME 117 9559
TEST_TIME 118 9530
TEST_TIME 119 9496
TEST_TIME 120 9419

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2021 07:38:55 to 24-12-2021 07:38:58.

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 1570817 1570817 0 0.00 0 18886 7 0 0
doc_list_biud 1570817 1570817 0 0.00 0 22502 28 0 0
sp_add_invoice_to_stock 5304 5181 123 2.31 25 189247 17906 0 123
sp_cancel_adding_invoice 5699 5699 0 0.00 6 82491 898 0 0
sp_cancel_client_order 1870 1870 0 0.00 4 10961 342 0 0
sp_cancel_customer_reserve 563414 563414 0 0.00 1 22504 63 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 6265 104 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 4877 87 0 0
sp_cancel_supplier_invoice 6339 6328 11 0.17 2 21945 843 0 11
sp_cancel_supplier_order 424 424 0 0.00 7 1025595 103434 0 0
sp_cancel_write_off 9817 9817 0 0.00 1 10188 118 0 0
sp_client_order 21023 21023 0 0.00 4 35631 420 0 0
sp_customer_reserve 477546 477546 0 0.00 0 94113 178 0 0
sp_fill_shopping_cart 32403 32403 0 0.00 1 24822 40 0 0
sp_fill_shopping_cart_clo_res 477538 477538 0 0.00 0 16376 16 0 0
sp_get_clo_for_invoice 5168 5168 0 0.00 0 25420 317 0 0
sp_kill_cost_storno 16669 16669 0 0.00 0 18878 55 0 0
sp_kill_qstorno_ret_qs2qd 1143397 1143397 0 0.00 0 22361 34 0 0
sp_kill_qty_storno 603092 603092 0 0.00 0 22502 73 0 0
sp_lock_selected_doc 21573 21573 0 0.00 0 8123 6 0 0
sp_make_cost_storno 28966 28966 0 0.00 0 13919 53 0 0
sp_multiply_rows_for_pdistr 28966 28966 0 0.00 0 9239 34 0 0
sp_multiply_rows_for_qdistr 21023 21023 0 0.00 0 23059 215 0 0
sp_pay_from_customer 7300 7300 0 0.00 4 14973 123 0 0
sp_pay_to_supplier 6126 6126 0 0.00 5 15029 130 0 0
sp_payment_common 13426 13426 0 0.00 1 7771 24 0 0
sp_qd_handle_on_cancel_clo 1870 1870 0 0.00 0 10633 294 0 0
sp_qd_handle_on_invoice_upd_sts 10867 10867 0 0.00 0 18781 312 0 0
sp_qd_handle_on_reserve_upd_sts 10372 10372 0 0.00 0 19068 62 0 0
sp_reserve_write_off 10372 10372 0 0.00 7 19137 194 0 0
sp_supplier_invoice 5754 5754 0 0.00 7 92587 3410 0 0
sp_supplier_order 5633 5633 0 0.00 5 38612 1454 0 0
srv_aggregate_perf_data 109 109 0 0.00 2699 328360 16827 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1312 166881 47075 0 0
srv_find_qd_qs_mism 1079672 1079672 0 0.00 0 15652 6 0 0
srv_make_invnt_saldo 502 502 0 0.00 43 95248 3024 0 0
srv_make_money_saldo 782 782 0 0.00 1 18308 445 0 0
srv_recalc_idx_stat 5 5 0 0.00 166147 458685 289056 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5182 5182 0 0.00 0 12327 159 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 5878 95 0 0
t$perf-norm:sp_cancel_client_order 1870 1870 0 0.00 0 4242 11 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 546 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 1279 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 9632 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1460 23 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 22365 1481 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 921 4 0 0
t$perf-norm:sp_client_order 21023 21023 0 0.00 0 4744 15 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 4492 14 0 0
t$perf-norm:sp_pay_from_customer 7300 7300 0 0.00 0 10055 12 0 0
t$perf-norm:sp_pay_to_supplier 6126 6126 0 0.00 0 6305 11 0 0
t$perf-norm:sp_reserve_write_off 10372 10372 0 0.00 0 6854 11 0 0
t$perf-norm:sp_supplier_invoice 5754 5754 0 0.00 0 4088 16 0 0
t$perf-norm:sp_supplier_order 5633 5633 0 0.00 0 2875 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 103 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8299 135 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 1238 11 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 4319 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10582 10336 246 2.32 0 5558 16 0 246
v_all_customers 37792 37792 0 0.00 0 22 1 0 0
v_all_suppliers 31840 31840 0 0.00 0 23 1 0 0
v_all_wares 42046 42046 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 3375 13 0 0
v_cancel_client_order 3740 3740 0 0.00 0 10636 9 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 2612 10 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 245 3 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1417 6 0 0
v_cancel_supplier_invoice 832 810 22 2.64 0 1109 15 0 22
v_cancel_supplier_order 848 848 0 0.00 0 783 5 0 0
v_cancel_write_off 2078 2078 0 0.00 0 1742 13 0 0
v_max_id_clo_ord 5633 5633 0 0.00 0 235 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 2432 14 0 0
v_max_id_ord_sup 5747 5747 0 0.00 0 6883 4 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 3052 5 0 0
v_max_non_paid_realizn 4100 4100 0 0.00 0 2646 4 0 0
v_min_id_clo_ord 5633 5633 0 0.00 0 3890 8 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 3001 9 0 0
v_min_id_ord_sup 5747 5747 0 0.00 0 6894 10 0 0
v_min_non_paid_invoice 6126 6126 0 0.00 0 7766 16 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 7325 17 0 0
v_our_firm 10654 10654 0 0.00 0 19 1 0 0
v_reserve_write_off 20744 20744 0 0.00 0 11172 7 0 0
x_lock_depdocs_on_canc_invoice 11622 11622 0 0.00 0 11501 110 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 3538 322 0 0
x_make_qstorno_1000_1200 5633 5633 0 0.00 2 38502 1374 0 0
x_make_qstorno_1200_2000 5754 5754 0 0.00 2 86360 3261 0 0
x_make_qstorno_2100_3300 477546 477546 0 0.00 0 94110 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2021 07:38:59 to 24-12-2021 07:39:03.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:11 2 8192000000 8798579872 8809734144 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:12 18085 8192000000 9714444992 10010132480 796324208 8.859 100 91 182 0 826462928 7863360 22275360
05:09:32 17372 8192000000 9747689376 10050662400 824168336 9.141 100 84 168 0 855500144 11098416 20233392
05:10:53 13040 8192000000 9783041664 10093592576 851246928 9.413 100 88 176 0 889142624 17233360 20662336
05:12:16 16058 8192000000 9847189552 10177265664 900151104 9.900 100 75 150 0 959767456 43062272 16554080
05:13:34 10903 8192000000 9904787904 10246066176 944008400 10.332 100 79 158 1 1016901136 56211264 16681472
05:14:50 5027 8192000000 9956642784 10304831488 975175712 10.637 100 75 150 1 1061602176 70888032 15538432
05:15:57 6188 8192000000 9971372880 10331385856 1009094560 10.967 100 78 156 0 1085034688 59909856 16030272
05:17:05 2224 8192000000 10022606464 10389676032 1045505728 11.318 100 86 172 0 1135059824 71386064 18168032
05:18:09 1484 8192000000 10034960256 10406260736 1065716768 11.511 100 91 182 2 1144169216 60123600 18328848
05:19:12 912 8192000000 10019840448 10395824128 1072090496 11.572 100 80 159 2 1129713264 40022352 17600416
05:20:13 2958 8192000000 10011779728 10408001536 1081827712 11.665 100 54 109 0 1125186784 32246544 11112528
05:21:20 6369 8192000000 10010488064 10403840000 1087919632 11.723 100 42 83 1 1122113680 25324448 8869600
05:22:29 67993 8192000000 10003447488 10406744064 1093070416 11.772 100 33 65 0 1125102592 24211824 7820352
05:24:39 2390 8192000000 10028593776 10424647680 1100705184 11.844 100 44 84 2 1137525952 27136528 9684240
05:25:42 7870 8192000000 10033074720 10448572416 1106003152 11.895 100 53 104 1 1143567136 25309536 12254448
05:26:50 25329 8192000000 10035087136 10429435904 1093324400 11.774 99 68 131 2 1130010944 22710160 13976384
05:28:17 9161 8192000000 9994110848 10396364800 1056992576 11.428 98 34 68 0 1081272992 17026752 7253664
05:29:29 7424 8192000000 9961397024 10349084672 989292656 10.775 94 19 38 1 1011899104 18768144 3838304
05:30:37 6399 8192000000 9931510032 10301689856 1021261888 11.084 100 35 69 1 1045834128 15650736 8921504
05:31:44 21983 8192000000 9936289184 10334838784 1008235856 10.958 98 19 37 1 1038882176 27170128 3476192
05:33:06 3733 8192000000 9962102224 10342969344 1038027376 11.246 100 41 83 4 1065997120 18384624 9585120
05:34:10 7001 8192000000 9979679424 10377838592 1058432384 11.441 100 46 92 0 1097000912 27925968 10642560
05:35:20 72416 8192000000 9971738016 10377535488 1060390224 11.460 99 39 74 1 1087859664 18666480 8802960
05:37:42 19128 8192000000 9992688144 10391916544 1081299888 11.660 100 34 68 0 1107012448 18265616 7446944
05:39:05 11619 8192000000 10043597488 10440998912 1101050944 11.848 100 47 94 1 1164632176 53249712 10331520
05:40:21 18077 8192000000 10116538176 10514145280 1119820608 12.025 100 57 114 0 1212052448 80203856 12027984
05:41:45 48522 8192000000 10128520608 10538782720 1130967632 12.130 100 38 75 2 1242077200 102431632 8677936
05:44:50 14757 8192000000 10175845616 10596892672 1145441424 12.267 100 42 85 0 1291070912 136001440 9628048
05:46:08 5159 8192000000 10197366240 10602008576 1150870624 12.318 100 76 152 0 1304407296 137022176 16514496
05:47:23 2590 8192000000 10235992480 10637443072 1165819776 12.458 100 77 154 1 1346905552 164966288 16119488
05:48:31 8406 8192000000 10276849920 10687864832 1177827904 12.570 100 79 156 2 1379652880 183930032 17894944
05:49:48 1310 8192000000 10268031728 10664189952 1164903984 12.449 98 85 171 0 1367913136 184118544 18890608
05:50:52 2481 8192000000 10276788224 10681757696 1182538208 12.614 100 77 153 2 1384917216 185948976 16430032
05:52:00 2774 8192000000 10292948512 10691235840 1163542512 12.436 99 75 149 0 1384683552 204336496 16804544
05:53:07 4416 8192000000 10304738032 10713231360 1145368784 12.266 99 67 133 0 1403384480 242902208 15113488
05:54:22 2851 8192000000 10334966544 10738917376 1159402640 12.398 100 66 131 0 1430694736 255878896 15413200
05:55:27 2105 8192000000 10410748240 10812276736 1175112400 12.545 100 91 176 1 1503923280 307252992 21557888
05:56:30 8162 8192000000 10362226016 10801778688 1142618496 12.240 98 42 85 0 1451749520 298476704 10654320
05:57:48 9950 8192000000 10370964592 10813468672 1141743056 12.232 100 47 94 1 1477170160 322582384 12844720
05:58:59 18006 8192000000 10391381744 10821529600 1157276656 12.378 100 35 70 1 1477969072 311956160 8736256
06:00:27 19698 8192000000 10357847856 10809532416 1119393760 12.021 99 30 60 0 1483198672 355344336 8460576
06:01:52 43434 8192000000 10054834688 10474692608 1065877120 11.513 95 18 36 0 1142922304 72928000 4117184
06:03:36 21087 8192000000 10117416736 10552082432 1095882736 11.799 98 15 28 1 1198652016 99985216 2784064
06:04:59 79522 8192000000 10066327680 10552696832 1103934048 11.875 99 22 44 2 1178654160 69891200 4828912
06:08:05 87291 8192000000 10073620656 10488451072 1087169984 11.716 97 38 77 3 1125958384 30432480 8355920
06:10:34 22315 8192000000 10096695200 10516922368 1120816832 12.035 99 37 74 2 1188865520 59860160 8188528
06:11:59 35864 8192000000 10105481792 10568417280 1119013296 12.018 99 24 48 0 1206397040 82348784 5034960
06:13:43 86885 8192000000 10096531056 10563805184 1129349440 12.115 100 18 35 0 1220843056 87405072 4088544
06:16:10 41649 8192000000 10109569216 10549809152 1135423408 12.172 99 24 48 0 1219493104 79270192 4799504
06:17:52 43162 8192000000 10085600080 10569072640 1123435536 12.059 98 11 22 0 1179886368 54592288 1858544
06:19:36 63718 8192000000 10102069648 10563379200 1145659520 12.269 100 19 38 1 1205280512 55531824 4089168
06:21:40 34755 8192000000 10103657792 10565693440 1160478416 12.408 100 18 36 0 1219034544 54865984 3690144
06:23:17 63994 8192000000 10118718928 10620723200 1164059120 12.441 100 17 34 1 1264352880 96472896 3820864
06:25:22 54074 8192000000 10126928416 10566504448 1163370320 12.435 100 25 50 0 1204286016 36110368 4805328
06:27:28 51900 8192000000 10085840144 10547056640 1125824784 12.082 97 21 42 1 1189720080 59257184 4638112
06:30:01 22386 8192000000 10104022176 10536947712 1124541152 12.070 97 31 62 1 1161390576 30403888 6445536
06:31:26 84037 8192000000 10092686464 10546372608 1146025024 12.272 99 25 50 1 1186427776 35145104 5257648
06:34:44 37532 8192000000 10105529776 10541432832 1112773648 11.959 96 32 64 2 1167007728 47804144 6429936
06:36:22 49115 8192000000 10055302160 10492981248 1094080208 11.781 96 15 30 1 1124468096 27320608 3067280
06:38:30 46542 8192000000 10036092304 10498707456 1102861296 11.865 98 11 22 0 1141442240 36157920 2423024
06:40:28 68519 8192000000 10064022528 10532241408 1106838416 11.902 98 17 32 1 1149605824 39778208 2989200
06:42:48 26231 8192000000 10060273568 10492375040 1087275600 11.717 97 22 44 1 1121178112 29164976 4737536
06:44:15 47151 8192000000 10095650784 10549133312 1093184512 11.773 98 18 36 1 1164280816 67284864 3811440
06:46:24 57863 8192000000 10112849200 10601840640 1106249440 11.897 99 20 40 1 1238266160 127435040 4581680
06:48:22 49659 8192000000 10082452352 10573697024 1119135600 12.019 100 18 36 0 1172156096 49335408 3685088
06:50:22 50585 8192000000 10086611040 10551238656 1115959312 11.989 99 29 58 0 1196154672 74584000 5611360
06:52:42 110931 8192000000 10118842448 10559410176 1116929424 11.998 99 26 52 0 1208851632 86290800 5631408
06:55:38 76783 8192000000 10084455872 10535972864 1131063424 12.131 100 24 48 0 1200307360 63920096 5323840
06:57:56 60472 8192000000 10111260560 10566365184 1130250288 12.124 99 17 34 1 1199355792 65755984 3349520
07:00:03 35341 8192000000 10125283600 10563162112 1136469840 12.182 99 21 42 0 1223838960 82934912 4434208
07:01:50 94092 8192000000 10167534224 10641055744 1117097904 12.000 97 22 43 1 1225765536 103179552 5488080
07:05:10 34067 8192000000 10126290928 10568392704 1131496112 12.135 99 33 65 0 1194768032 56596160 6675760
07:06:58 166881 8192000000 10117288640 10672988160 1125001536 12.074 98 20 40 0 1184808848 55687216 4120096
07:10:46 107728 8192000000 10080461664 10547695616 1130309040 12.124 99 24 47 1 1171517696 36658720 4549936
07:13:34 71040 8192000000 10072849824 10536456192 1125392528 12.078 98 9 18 0 1162919856 35436016 2091312
07:15:46 67108 8192000000 10125760784 10584715264 1147589008 12.287 100 10 20 0 1213961168 64101888 2270272
07:17:59 30587 8192000000 10089947392 10591756288 1132249760 12.143 100 10 20 0 1209958752 75375440 2333552
07:19:30 66217 8192000000 10093026512 10564943872 1119005792 12.018 98 13 26 0 1167302304 45416720 2879792
07:21:38 59514 8192000000 10141715360 10593660928 1139175632 12.208 99 21 42 0 1217805392 74392384 4237376
07:23:38 83879 8192000000 10146622992 10604539904 1127545584 12.098 97 22 44 1 1198466400 66468704 4452112
07:26:24 69799 8192000000 10065632048 10554474496 1125248816 12.077 98 17 34 0 1226196464 96868352 4079296
07:28:36 72262 8192000000 10077176304 10602872832 1113018912 11.961 98 17 34 0 1219539488 103002384 3518192
07:30:50 97387 8192000000 10120067584 10559127552 1117668064 12.005 98 30 60 0 1163340768 39732976 5939728
07:33:28 97034 8192000000 10048034480 10519638016 1106553008 11.900 97 28 56 3 1171721600 59360704 5807888
07:36:06 48211 8192000000 10023198576 10484080640 1100155200 11.839 99 8 16 0 1127416320 25547312 1713808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2021 07:39:05 to 24-12-2021 07:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2021 07:39:21 to 24-12-2021 07:39:23.

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          241453
MON$OLDEST_ACTIVE               241741
MON$OLDEST_SNAPSHOT             241741
MON$NEXT_TRANSACTION            241741
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                       2048096
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:16 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Fri Dec 24 07:37:17 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
--- end of diff output ---

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

24.12.21 07:39:24. 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-24 07:39:26 No severe PSQL-related problems occured
24.12.21 07:39:28. End of report.