Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2021 07:39
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16010072 kB
MemAvailable 30925268 kB
Buffers 0 kB
Cached 15727752 kB
SwapCached 4524 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-10-25 07:37:06
DTS_END                         2021-10-25 07:37:06.3790
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/42362
TRN_ID                          230224
ATT_ID                          586
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: 9141.52 20742 1096982
customer order: creation 170.33 369 20439
customer order: refuse 14.36 364 1723
order to supplier: creation 45.31 1399 5437
order to supplier: removal 3.40 129048 408
invoice (draft): creation 45.63 3456 5475
invoice (draft): removal 50.36 1065 6043
invoice accept: apply 42.34 20365 5081
invoice accept: cancel 45.43 934 5452
customer reserve: creation 3981.69 197 477803
customer reserve: removal 4456.72 77 534806
realization accept: apply 82.59 214 9911
realization accept: cancel 75.04 141 9005
payment from customer: creation 59.52 118 7142
payment from customer: removal 5.61 93 673
payment to supplier: creation 48.79 138 5855
payment to supplier: removal 3.19 134 383
service: total inventory turnovers 3.99 3979 479
service: total monetary turnovers 6.36 554 763
service: aggregate perf. data 0.83 20918 100
service: refresh index statistics 0.03 231281 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2021 07:39:35 to 25-10-2021 07:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3822
TEST_TIME 2 4914
TEST_TIME 3 3647
TEST_TIME 4 3774
TEST_TIME 5 4271
TEST_TIME 6 4131
TEST_TIME 7 4477
TEST_TIME 8 5146
TEST_TIME 9 5282
TEST_TIME 10 5341
TEST_TIME 11 5833
TEST_TIME 12 5832
TEST_TIME 13 6362
TEST_TIME 14 6232
TEST_TIME 15 6273
TEST_TIME 16 6415
TEST_TIME 17 6649
TEST_TIME 18 7169
TEST_TIME 19 7335
TEST_TIME 20 7871
TEST_TIME 21 7989
TEST_TIME 22 8709
TEST_TIME 23 8585
TEST_TIME 24 8728
TEST_TIME 25 8740
TEST_TIME 26 9065
TEST_TIME 27 9524
TEST_TIME 28 9989
TEST_TIME 29 10962
TEST_TIME 30 10737
TEST_TIME 31 10848
TEST_TIME 32 10595
TEST_TIME 33 10647
TEST_TIME 34 10649
TEST_TIME 35 10703
TEST_TIME 36 10613
TEST_TIME 37 10724
TEST_TIME 38 10499
TEST_TIME 39 10511
TEST_TIME 40 10308
TEST_TIME 41 10147
TEST_TIME 42 10131
TEST_TIME 43 9976
TEST_TIME 44 9953
TEST_TIME 45 9897
TEST_TIME 46 9931
TEST_TIME 47 9932
TEST_TIME 48 9997
TEST_TIME 49 9879
TEST_TIME 50 9893
TEST_TIME 51 9817
TEST_TIME 52 9798
TEST_TIME 53 9873
TEST_TIME 54 9812
TEST_TIME 55 9686
TEST_TIME 56 9669
TEST_TIME 57 9595
TEST_TIME 58 9561
TEST_TIME 59 9598
TEST_TIME 60 9546
TEST_TIME 61 9548
TEST_TIME 62 9679
TEST_TIME 63 9678
TEST_TIME 64 9679
TEST_TIME 65 9700
TEST_TIME 66 9706
TEST_TIME 67 9743
TEST_TIME 68 9685
TEST_TIME 69 9734
TEST_TIME 70 9708
TEST_TIME 71 9784
TEST_TIME 72 9827
TEST_TIME 73 9766
TEST_TIME 74 9694
TEST_TIME 75 9747
TEST_TIME 76 9663
TEST_TIME 77 9625
TEST_TIME 78 9618
TEST_TIME 79 9548
TEST_TIME 80 9477
TEST_TIME 81 9461
TEST_TIME 82 9382
TEST_TIME 83 9413
TEST_TIME 84 9504
TEST_TIME 85 9451
TEST_TIME 86 9478
TEST_TIME 87 9456
TEST_TIME 88 9462
TEST_TIME 89 9460
TEST_TIME 90 9475
TEST_TIME 91 9484
TEST_TIME 92 9527
TEST_TIME 93 9518
TEST_TIME 94 9568
TEST_TIME 95 9562
TEST_TIME 96 9478
TEST_TIME 97 9469
TEST_TIME 98 9417
TEST_TIME 99 9384
TEST_TIME 100 9369
TEST_TIME 101 9355
TEST_TIME 102 9345
TEST_TIME 103 9310
TEST_TIME 104 9305
TEST_TIME 105 9286
TEST_TIME 106 9278
TEST_TIME 107 9295
TEST_TIME 108 9253
TEST_TIME 109 9288
TEST_TIME 110 9306
TEST_TIME 111 9281
TEST_TIME 112 9283
TEST_TIME 113 9361
TEST_TIME 114 9367
TEST_TIME 115 9359
TEST_TIME 116 9322
TEST_TIME 117 9264
TEST_TIME 118 9260
TEST_TIME 119 9214
TEST_TIME 120 9143

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-10-2021 07:39:39 to 25-10-2021 07:39:42.

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 1538386 1538386 0 0.00 0 19725 7 0 0
doc_list_biud 1538384 1538384 0 0.00 0 46893 34 0 0
sp_add_invoice_to_stock 5182 5081 101 1.94 29 171386 20366 0 101
sp_cancel_adding_invoice 5452 5452 0 0.00 6 58589 935 0 0
sp_cancel_client_order 1723 1723 0 0.00 5 13241 365 0 0
sp_cancel_customer_reserve 534806 534806 0 0.00 1 28568 77 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 6013 93 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 6998 135 0 0
sp_cancel_supplier_invoice 6046 6043 3 0.04 2 25767 1065 0 3
sp_cancel_supplier_order 408 408 0 0.00 10 1100231 129049 0 0
sp_cancel_write_off 9005 9005 0 0.00 1 11662 142 0 0
sp_client_order 20439 20439 0 0.00 4 35661 369 0 0
sp_customer_reserve 477803 477803 0 0.00 0 119504 198 0 0
sp_fill_shopping_cart 31343 31343 0 0.00 1 18533 42 0 0
sp_fill_shopping_cart_clo_res 477801 477801 0 0.00 0 21536 16 0 0
sp_get_clo_for_invoice 5079 5079 0 0.00 0 32235 308 0 0
sp_kill_cost_storno 15513 15513 0 0.00 0 19715 78 0 0
sp_kill_qstorno_ret_qs2qd 1085068 1085068 0 0.00 0 28564 42 0 0
sp_kill_qty_storno 572425 572425 0 0.00 0 46893 89 0 0
sp_lock_selected_doc 20713 20713 0 0.00 0 7261 5 0 0
sp_make_cost_storno 27987 27987 0 0.00 0 11805 60 0 0
sp_multiply_rows_for_pdistr 27985 27985 0 0.00 0 6982 33 0 0
sp_multiply_rows_for_qdistr 20439 20439 0 0.00 0 15289 172 0 0
sp_pay_from_customer 7142 7142 0 0.00 4 11923 119 0 0
sp_pay_to_supplier 5855 5855 0 0.00 5 19370 139 0 0
sp_payment_common 12997 12997 0 0.00 1 8678 27 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 9179 326 0 0
sp_qd_handle_on_invoice_upd_sts 10529 10529 0 0.00 0 46892 401 0 0
sp_qd_handle_on_reserve_upd_sts 9911 9911 0 0.00 0 17994 69 0 0
sp_reserve_write_off 9911 9911 0 0.00 7 18019 214 0 0
sp_supplier_invoice 5475 5475 0 0.00 8 76887 3457 0 0
sp_supplier_order 5437 5437 0 0.00 5 46964 1400 0 0
srv_aggregate_perf_data 100 100 0 0.00 3008 251609 20919 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 2296 121309 38842 0 0
srv_find_qd_qs_mism 1048383 1048383 0 0.00 0 19400 6 0 0
srv_make_invnt_saldo 479 479 0 0.00 60 119276 3980 0 0
srv_make_money_saldo 763 763 0 0.00 0 14900 555 0 0
srv_recalc_idx_stat 4 4 0 0.00 183138 275913 231281 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 0 23590 193 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 4986 128 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 5557 21 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 1291 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 704 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 4090 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4612 28 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 44868 2018 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 7132 20 0 0
t$perf-norm:sp_client_order 20440 20440 0 0.00 0 17093 20 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 509 4 0 0
t$perf-norm:sp_pay_from_customer 7142 7142 0 0.00 0 8566 9 0 0
t$perf-norm:sp_pay_to_supplier 5855 5855 0 0.00 0 3617 8 0 0
t$perf-norm:sp_reserve_write_off 9911 9911 0 0.00 0 6698 14 0 0
t$perf-norm:sp_supplier_invoice 5475 5475 0 0.00 0 13828 19 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 17007 22 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 840 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 419 7 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 1839 8 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 1198 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10356 10154 202 1.95 0 6689 19 0 202
v_all_customers 37820 37820 0 0.00 0 24 1 0 0
v_all_suppliers 30800 30800 0 0.00 0 19 1 0 0
v_all_wares 40878 40878 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 3650 15 0 0
v_cancel_client_order 3446 3446 0 0.00 0 13160 9 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3765 9 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2608 8 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 3672 19 0 0
v_cancel_supplier_invoice 834 828 6 0.71 0 2226 19 0 6
v_cancel_supplier_order 816 816 0 0.00 0 3584 20 0 0
v_cancel_write_off 2018 2018 0 0.00 0 6485 16 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 2265 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 2707 9 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 5831 6 0 0
v_max_non_paid_invoice 1359 1359 0 0.00 0 886 3 0 0
v_max_non_paid_realizn 3546 3546 0 0.00 0 5439 4 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 7858 11 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 6688 25 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 8865 11 0 0
v_min_non_paid_invoice 5855 5855 0 0.00 0 8381 18 0 0
v_min_non_paid_realizn 7142 7142 0 0.00 0 6413 18 0 0
v_our_firm 10250 10250 0 0.00 0 20 1 0 0
v_reserve_write_off 19822 19822 0 0.00 0 13158 9 0 0
x_lock_depdocs_on_canc_invoice 11081 11081 0 0.00 0 25263 127 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 16418 401 0 0
x_make_qstorno_1000_1200 5437 5437 0 0.00 2 46937 1312 0 0
x_make_qstorno_1200_2000 5475 5475 0 0.00 4 76876 3303 0 0
x_make_qstorno_2100_3300 477803 477803 0 0.00 0 119502 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2021 07:39:43 to 25-10-2021 07:39:47.

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:09 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 31852 8192000000 9729642384 10031321088 806604352 8.963 100 87 174 0 834871984 7786384 20481248
05:09:55 19850 8192000000 9776345808 10085842944 850887776 9.409 100 91 182 0 886688512 13777184 22023552
05:11:26 9514 8192000000 9808711888 10126917632 875153440 9.651 100 88 176 0 920535568 25279232 20102896
05:12:44 15664 8192000000 9883450160 10216587264 921548832 10.111 100 86 172 0 984930160 45018720 18362608
05:14:02 13432 8192000000 9929522784 10273275904 950634672 10.397 100 86 171 2 1038898128 70230944 18032512
05:15:18 7521 8192000000 9973759904 10328129536 994759520 10.828 100 78 156 1 1082303952 71409040 16135392
05:16:29 2975 8192000000 10012943888 10372395008 1026264688 11.132 100 85 171 1 1121904368 78326944 17312736
05:17:34 2606 8192000000 10035494480 10404216832 1046911936 11.331 100 89 177 0 1145033904 79648800 18473168
05:18:39 1877 8192000000 10062510912 10434797568 1067289568 11.526 100 94 189 1 1171430016 85041600 19098848
05:19:43 18407 8192000000 10026025488 10420043776 1069110912 11.544 100 51 101 2 1157081280 77596128 10374240
05:21:04 4471 8192000000 10003549536 10390016000 1077432208 11.623 100 59 119 2 1116834336 26034240 13367888
05:22:12 6561 8192000000 10002957280 10406756352 1086772560 11.712 100 42 84 1 1120945552 25081680 9091312
05:23:19 5513 8192000000 9999327600 10400686080 1089414944 11.737 100 49 97 0 1117090432 17744816 9930672
05:24:27 39815 8192000000 10008405440 10405109760 1093674912 11.778 100 60 122 0 1116796368 10012480 13108976
05:26:07 7363 8192000000 10008863040 10413740032 1099751264 11.835 100 43 84 0 1138492704 30541184 8200256
05:27:15 7850 8192000000 9988758080 10395226112 1075198976 11.602 98 36 73 1 1091900720 8701088 8000656
05:28:24 6136 8192000000 9994415344 10394988544 1074482592 11.595 99 40 80 1 1098529536 15545872 8501072
05:29:33 37638 8192000000 10018579536 10423222272 1071970832 11.571 99 40 78 1 1095855776 16141056 7743888
05:31:13 7843 8192000000 9937898112 10326982656 995785424 10.838 96 33 64 2 1032309648 29009632 7514592
05:32:21 7418 8192000000 9948197648 10339565568 1022366208 11.095 99 43 85 0 1057557728 26118192 9073328
05:33:32 8110 8192000000 9930427760 10322022400 998316400 10.862 96 39 74 1 1018787584 12029680 8441504
05:34:42 26848 8192000000 9967800624 10373070848 1031115168 11.179 99 35 70 0 1064462144 25030080 8316896
05:36:12 6623 8192000000 9989197120 10392023040 1061290160 11.469 100 54 109 2 1098452432 25074640 12087632
05:37:27 38195 8192000000 10005674608 10395156480 1083664944 11.682 100 45 90 0 1109601408 16759744 9176720
05:39:09 31345 8192000000 10084445488 10490953728 1113640304 11.967 100 54 107 2 1204679104 79130224 11908576
05:40:47 62574 8192000000 10128032304 10545090560 1133184064 12.151 100 34 68 0 1244278272 103376432 7717776
05:42:58 31649 8192000000 10144715520 10556071936 1145535696 12.268 100 37 73 0 1246506832 93078784 7892352
05:44:32 14195 8192000000 10199482464 10603659264 1154245024 12.349 100 59 119 0 1300612048 133627584 12739440
05:45:51 6056 8192000000 10255434064 10671161344 1172164144 12.517 100 63 126 0 1354445440 168662736 13618560
05:47:01 5463 8192000000 10303730208 10709807104 1191554224 12.698 100 71 142 1 1401278928 194162992 15561712
05:48:12 3200 8192000000 10321343776 10755395584 1190291840 12.686 100 61 117 1 1430012784 225495216 14225728
05:49:18 4775 8192000000 10322001152 10748305408 1187060336 12.656 100 52 102 0 1432484112 232609680 12814096
05:50:32 6863 8192000000 10324876112 10736021504 1177222784 12.564 98 58 114 1 1407221952 217146080 12853088
05:51:46 4710 8192000000 10382725344 10791919616 1201645200 12.792 100 81 161 1 1484758416 264428352 18684864
05:52:55 2294 8192000000 10424556912 10845622272 1216588304 12.930 100 68 136 0 1529994624 298167520 15238800
05:54:01 8927 8192000000 10446393936 10871586816 1180276624 12.593 97 78 151 1 1527283408 329070560 17936224
05:55:16 3133 8192000000 10462328016 10881933312 1205501984 12.827 100 70 140 0 1560042768 338284544 16256240
05:56:22 4843 8192000000 10468981520 10893844480 1187027856 12.656 100 79 158 0 1570781904 364655776 19098272
05:57:32 3680 8192000000 10408726496 10843779072 1173678064 12.531 99 87 173 1 1500249760 305811968 20759728
05:58:41 14424 8192000000 10440820544 10866888704 1164239280 12.443 99 45 90 0 1535899120 360327232 11332608
05:59:59 16530 8192000000 10388981408 10829279232 1169154736 12.489 99 35 70 1 1492780656 314677312 8948608
06:01:18 15945 8192000000 10445590080 10898964480 1184984384 12.637 100 36 72 1 1552195472 357702368 9508720
06:02:37 9525 8192000000 10462994592 10893783040 1158670752 12.391 99 42 84 0 1549336416 380236592 10429072
06:04:23 11517 8192000000 10410361328 10879680512 1132795984 12.148 100 27 54 0 1517310016 377034848 7479184
06:06:03 14260 8192000000 10183553152 10706223104 1135873584 12.177 100 29 58 1 1335721136 192805856 7041696
06:07:18 32597 8192000000 10088775376 10520408064 1104776032 11.883 98 27 54 0 1129518096 18486544 6255520
06:09:19 51055 8192000000 10113960528 10569887744 1127125056 12.094 99 21 42 0 1190714480 59106192 4483232
06:11:13 68536 8192000000 10084407536 10569830400 1110788912 11.940 97 8 16 0 1155465856 43196208 1480736
06:13:23 22744 8192000000 10114282688 10565632000 1146347568 12.275 100 30 60 1 1197105952 44612080 6146304
06:14:47 46215 8192000000 10088369424 10510348288 1124448752 12.069 99 25 48 0 1153357424 24241456 4667216
06:16:43 91649 8192000000 10052976240 10481823744 1108518304 11.918 98 27 54 0 1151265808 36804144 5943360
06:19:20 121308 8192000000 10099599392 10541875200 1118278592 12.011 98 31 62 0 1184928848 60202416 6447840
06:22:26 18286 8192000000 10121001552 10554204160 1141486992 12.230 100 37 74 2 1226255008 74939616 9828400
06:23:47 19881 8192000000 10145578192 10571669504 1142706976 12.241 100 55 109 0 1223397472 68522208 12168288
06:25:34 56315 8192000000 10126847888 10604695552 1135334240 12.172 99 10 20 1 1227004656 89372304 2298112
06:27:33 28858 8192000000 10109120992 10605785088 1119526704 12.023 98 6 12 1 1198383936 77432880 1424352
06:29:02 39519 8192000000 10087816864 10579742720 1118516320 12.013 98 12 24 0 1226756432 105420352 2819760
06:30:54 52137 8192000000 10098075536 10589868032 1141444128 12.229 100 12 24 0 1221409968 77119232 2846608
06:32:49 49218 8192000000 10075172112 10533220352 1133114864 12.151 99 31 61 0 1167297200 27333520 6848816
06:34:42 46498 8192000000 10109036672 10603642880 1147659616 12.288 100 8 16 0 1223680992 73990352 2031024
06:36:28 50724 8192000000 10077342192 10531155968 1146893200 12.280 100 10 20 0 1185652064 36769776 1989088
06:38:21 48313 8192000000 10121885408 10602500096 1162673328 12.428 100 22 44 1 1243580112 76418448 4488336
06:40:43 24434 8192000000 10090555104 10542698496 1139676528 12.212 99 26 52 1 1197927184 52907744 5342912
06:42:13 23595 8192000000 10092505776 10533265408 1143281104 12.246 100 40 79 1 1187711520 36997264 7433152
06:43:40 32963 8192000000 10090113616 10548109312 1132471232 12.145 99 27 54 0 1181423536 43661776 5290528
06:45:13 28269 8192000000 10097178288 10541907968 1139852512 12.214 100 29 58 0 1182388976 36687664 5848800
06:46:45 32301 8192000000 10062292240 10509668352 1114502352 11.975 98 20 40 1 1179993856 61558304 3933200
06:48:21 55929 8192000000 10120747648 10601246720 1136684848 12.184 99 20 40 1 1229407456 88038256 4684352
06:50:17 27003 8192000000 10096879552 10519334912 1106714448 11.901 98 46 92 0 1163999792 47180784 10104560
06:51:55 103247 8192000000 10063150576 10573467648 1119898112 12.026 99 16 33 0 1181254656 57807936 3548608
06:55:13 57557 8192000000 10069459104 10542526464 1095297744 11.793 97 20 40 1 1176985072 77728400 3958928
06:57:11 49157 8192000000 10122415232 10567221248 1093986352 11.781 97 19 38 0 1170510640 71717520 4806768
06:59:01 63783 8192000000 10128211712 10569949184 1090410928 11.747 98 17 34 0 1201085088 106436064 4238096
07:01:06 46114 8192000000 10023295232 10501304320 1053403440 11.393 95 10 20 0 1113429760 58028768 1997552
07:03:07 32281 8192000000 10079443760 10527842304 1083191984 11.678 98 46 91 2 1161470608 68816384 9462240
07:04:41 34461 8192000000 10058579040 10480562176 1094551968 11.786 99 43 83 1 1139486944 35787040 9147936
07:06:16 42222 8192000000 10081797024 10529501184 1116282304 11.992 100 30 60 0 1175093376 52754336 6056736
07:08:05 52335 8192000000 10087122688 10517356544 1128292240 12.105 100 24 48 0 1178146528 45448000 4406288
07:09:59 50633 8192000000 10075976400 10527928320 1128394608 12.106 99 29 58 1 1176179440 42530864 5253968
07:11:51 94153 8192000000 10099242816 10531160064 1131920032 12.139 99 38 78 2 1174048144 33773824 8354288
07:15:46 41095 8192000000 10093758176 10530525184 1130376896 12.125 99 44 87 0 1180466544 41809648 8280000
07:17:29 57772 8192000000 10092582432 10560413696 1135816160 12.176 100 11 23 0 1206858640 68606896 2435584
07:19:28 53381 8192000000 10054087088 10522292224 1132134448 12.141 100 17 34 0 1189715344 53768512 3812384
07:21:31 64065 8192000000 10064542288 10529939456 1136470368 12.182 100 11 22 0 1166506144 27533136 2502640
07:23:40 22135 8192000000 10099908224 10530734080 1140826352 12.223 100 37 70 1 1191446944 43889472 6731120
07:25:12 52220 8192000000 10113868288 10559627264 1103117504 11.867 96 14 28 0 1151391152 45156464 3117184
07:27:06 73554 8192000000 10081234208 10521640960 1095891776 11.799 96 27 54 1 1157938736 56546256 5500704
07:29:34 94121 8192000000 10085512208 10566860800 1117463136 12.003 98 13 26 1 1186030432 65987648 2579648
07:32:18 52783 8192000000 10090791424 10542817280 1118359808 12.011 99 30 60 0 1175165616 50799456 6006352
07:34:11 99944 8192000000 10063990016 10536878080 1101781552 11.855 97 38 73 1 1181508848 72827360 6899936
07:36:54 21723 8192000000 9424835712 9790320640 556558592 6.361 50 5 10 0 591275712 33753616 963504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2021 07:39:49 to 25-10-2021 07:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-10-2021 07:40:05 to 25-10-2021 07:40:07.

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          231037
MON$OLDEST_ACTIVE               231385
MON$OLDEST_SNAPSHOT             231385
MON$NEXT_TRANSACTION            231385
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                       2052064
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2642 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2642 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2642 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 ---
:320: 
:321: mscs-test-161.budzdorov.ru    Mon Oct 25 07:37:23 2021
:322:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:323: 
:324: 
:325: mscs-test-161.budzdorov.ru    Mon Oct 25 07:37:34 2021
:326:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:327: 
:328: 
:329: mscs-test-161.budzdorov.ru    Mon Oct 25 07:37:39 2021
:330:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:331: 
--- end of diff output ---

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

25.10.21 07:40:08. 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-10-25 07:40:11 No severe PSQL-related problems occured
25.10.21 07:40:12. End of report.