Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.06.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 16052996 kB
MemAvailable 31342588 kB
Buffers 0 kB
Cached 15672524 kB
SwapCached 2448 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.2
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-06-06 07:37:17
DTS_END                         2022-06-06 07:37:17.0940
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/51190
TRN_ID                          217635
ATT_ID                          656
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: 9325.06 26070 1119007
customer order: creation 165.99 455 19919
customer order: refuse 13.85 385 1662
order to supplier: creation 44.73 1331 5368
order to supplier: removal 3.26 188293 391
invoice (draft): creation 44.63 3002 5356
invoice (draft): removal 52.48 966 6298
invoice accept: apply 41.39 24400 4967
invoice accept: cancel 47.56 1171 5707
customer reserve: creation 3937.34 227 472481
customer reserve: removal 4682.09 104 561851
realization accept: apply 81.32 254 9758
realization accept: cancel 83.62 165 10034
payment from customer: creation 58.88 155 7066
payment from customer: removal 5.87 142 704
payment to supplier: creation 46.68 174 5601
payment to supplier: removal 2.93 110 352
service: total inventory turnovers 4.36 3387 523
service: total monetary turnovers 7.20 296 864
service: aggregate perf. data 0.84 16817 101
service: refresh index statistics 0.03 279573 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-06-2022 07:39:08 to 06-06-2022 07:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5258
TEST_TIME 2 4702
TEST_TIME 3 4676
TEST_TIME 4 4854
TEST_TIME 5 4836
TEST_TIME 6 5402
TEST_TIME 7 5448
TEST_TIME 8 5652
TEST_TIME 9 5820
TEST_TIME 10 5902
TEST_TIME 11 6002
TEST_TIME 12 6397
TEST_TIME 13 6552
TEST_TIME 14 6766
TEST_TIME 15 6771
TEST_TIME 16 6826
TEST_TIME 17 6804
TEST_TIME 18 7001
TEST_TIME 19 7355
TEST_TIME 20 7699
TEST_TIME 21 7528
TEST_TIME 22 7406
TEST_TIME 23 7944
TEST_TIME 24 8628
TEST_TIME 25 8744
TEST_TIME 26 9088
TEST_TIME 27 9237
TEST_TIME 28 9448
TEST_TIME 29 9870
TEST_TIME 30 10327
TEST_TIME 31 10984
TEST_TIME 32 11276
TEST_TIME 33 11000
TEST_TIME 34 10967
TEST_TIME 35 10822
TEST_TIME 36 10948
TEST_TIME 37 10838
TEST_TIME 38 10628
TEST_TIME 39 10577
TEST_TIME 40 10547
TEST_TIME 41 10439
TEST_TIME 42 10293
TEST_TIME 43 10269
TEST_TIME 44 10252
TEST_TIME 45 10364
TEST_TIME 46 10299
TEST_TIME 47 10164
TEST_TIME 48 10114
TEST_TIME 49 10179
TEST_TIME 50 10122
TEST_TIME 51 9963
TEST_TIME 52 9809
TEST_TIME 53 9767
TEST_TIME 54 9691
TEST_TIME 55 9583
TEST_TIME 56 9529
TEST_TIME 57 9496
TEST_TIME 58 9507
TEST_TIME 59 9478
TEST_TIME 60 9504
TEST_TIME 61 9488
TEST_TIME 62 9554
TEST_TIME 63 9454
TEST_TIME 64 9595
TEST_TIME 65 9593
TEST_TIME 66 9576
TEST_TIME 67 9567
TEST_TIME 68 9623
TEST_TIME 69 9628
TEST_TIME 70 9676
TEST_TIME 71 9703
TEST_TIME 72 9766
TEST_TIME 73 9716
TEST_TIME 74 9768
TEST_TIME 75 9728
TEST_TIME 76 9713
TEST_TIME 77 9702
TEST_TIME 78 9628
TEST_TIME 79 9626
TEST_TIME 80 9575
TEST_TIME 81 9508
TEST_TIME 82 9483
TEST_TIME 83 9430
TEST_TIME 84 9419
TEST_TIME 85 9450
TEST_TIME 86 9427
TEST_TIME 87 9349
TEST_TIME 88 9327
TEST_TIME 89 9297
TEST_TIME 90 9315
TEST_TIME 91 9364
TEST_TIME 92 9362
TEST_TIME 93 9390
TEST_TIME 94 9382
TEST_TIME 95 9414
TEST_TIME 96 9427
TEST_TIME 97 9474
TEST_TIME 98 9470
TEST_TIME 99 9513
TEST_TIME 100 9526
TEST_TIME 101 9451
TEST_TIME 102 9397
TEST_TIME 103 9370
TEST_TIME 104 9324
TEST_TIME 105 9293
TEST_TIME 106 9243
TEST_TIME 107 9206
TEST_TIME 108 9194
TEST_TIME 109 9175
TEST_TIME 110 9147
TEST_TIME 111 9120
TEST_TIME 112 9103
TEST_TIME 113 9145
TEST_TIME 114 9145
TEST_TIME 115 9122
TEST_TIME 116 9165
TEST_TIME 117 9185
TEST_TIME 118 9214
TEST_TIME 119 9336
TEST_TIME 120 9345

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-06-2022 07:39:12 to 06-06-2022 07:39:15.

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 1552491 1552491 0 0.00 0 29002 13 0 0
doc_list_biud 1552480 1552480 0 0.00 0 36777 43 0 0
sp_add_invoice_to_stock 5138 4967 171 3.32 19 184267 24401 0 171
sp_cancel_adding_invoice 5707 5707 0 0.00 7 75233 1171 0 0
sp_cancel_client_order 1662 1662 0 0.00 5 15010 385 0 0
sp_cancel_customer_reserve 561851 561851 0 0.00 1 20669 105 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 4 10239 142 0 0
sp_cancel_pay_to_supplier 352 352 0 0.00 4 4194 110 0 0
sp_cancel_supplier_invoice 6312 6298 14 0.22 3 36781 966 0 14
sp_cancel_supplier_order 391 391 0 0.00 11 1464922 188293 0 0
sp_cancel_write_off 10034 10034 0 0.00 2 12403 166 0 0
sp_client_order 19919 19919 0 0.00 6 22591 456 0 0
sp_customer_reserve 472481 472481 0 0.00 1 120790 228 0 0
sp_fill_shopping_cart 30638 30638 0 0.00 2 10596 77 0 0
sp_fill_shopping_cart_clo_res 472465 472465 0 0.00 0 18457 19 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 30021 296 0 0
sp_kill_cost_storno 16797 16797 0 0.00 0 19991 72 0 0
sp_kill_qstorno_ret_qs2qd 1140425 1140425 0 0.00 0 36775 45 0 0
sp_kill_qty_storno 600645 600645 0 0.00 0 36775 103 0 0
sp_lock_selected_doc 20280 20280 0 0.00 0 9887 7 0 0
sp_make_cost_storno 27370 27370 0 0.00 0 28998 75 0 0
sp_multiply_rows_for_pdistr 27368 27368 0 0.00 0 7844 32 0 0
sp_multiply_rows_for_qdistr 19919 19919 0 0.00 0 22499 187 0 0
sp_pay_from_customer 7066 7066 0 0.00 5 19035 155 0 0
sp_pay_to_supplier 5601 5601 0 0.00 6 11561 174 0 0
sp_payment_common 12666 12666 0 0.00 2 19028 38 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 1 14998 316 0 0
sp_qd_handle_on_invoice_upd_sts 10652 10652 0 0.00 0 21453 484 0 0
sp_qd_handle_on_reserve_upd_sts 9757 9757 0 0.00 0 10693 54 0 0
sp_reserve_write_off 9758 9758 0 0.00 8 29012 254 0 0
sp_supplier_invoice 5356 5356 0 0.00 9 77256 3002 0 0
sp_supplier_order 5368 5368 0 0.00 7 32826 1331 0 0
srv_aggregate_perf_data 101 101 0 0.00 2492 165409 16818 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 752 172398 41932 0 0
srv_find_qd_qs_mism 1069714 1069714 0 0.00 0 13705 10 0 0
srv_make_invnt_saldo 523 523 0 0.00 86 65740 3387 0 0
srv_make_money_saldo 864 864 0 0.00 1 8436 297 0 0
srv_recalc_idx_stat 4 4 0 0.00 203245 437273 279573 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4972 4972 0 0.00 0 11441 797 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 6646 430 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 5042 27 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 3569 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 2497 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 352 352 0 0.00 0 403 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 5705 40 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 1 105805 7000 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2316 18 0 0
t$perf-norm:sp_client_order 19919 19919 0 0.00 0 23907 34 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 2729 14 0 0
t$perf-norm:sp_pay_from_customer 7066 7066 0 0.00 0 6289 18 0 0
t$perf-norm:sp_pay_to_supplier 5601 5601 0 0.00 0 8130 20 0 0
t$perf-norm:sp_reserve_write_off 9758 9758 0 0.00 0 5160 20 0 0
t$perf-norm:sp_supplier_invoice 5356 5356 0 0.00 0 8214 27 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 8043 29 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 346 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 2304 67 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 674 14 0 0
t$perf-norm:srv_make_money_saldo 864 864 0 0.00 0 769 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 192 55 0 0
v_add_invoice_to_stock 10232 9890 342 3.34 0 2767 24 0 342
v_all_customers 36662 36662 0 0.00 0 163 3 0 0
v_all_suppliers 30260 30260 0 0.00 0 224 4 0 0
v_all_wares 39836 39836 0 0.00 0 210 3 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1522 15 0 0
v_cancel_client_order 3324 3324 0 0.00 0 4840 12 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 1318 15 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 840 10 0 0
v_cancel_payment_to_supplier 704 704 0 0.00 0 1115 11 0 0
v_cancel_supplier_invoice 808 780 28 3.46 0 1047 25 0 28
v_cancel_supplier_order 782 782 0 0.00 0 1365 14 0 0
v_cancel_write_off 2002 2002 0 0.00 0 6637 32 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 1817 5 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 1182 8 0 0
v_max_id_ord_sup 5352 5352 0 0.00 0 4797 7 0 0
v_max_non_paid_invoice 1191 1191 0 0.00 0 262 7 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 1291 7 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 1887 8 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 1430 8 0 0
v_min_id_ord_sup 5352 5352 0 0.00 0 4506 8 0 0
v_min_non_paid_invoice 5601 5601 0 0.00 0 3778 12 0 0
v_min_non_paid_realizn 7065 7065 0 0.00 0 19022 14 0 0
v_our_firm 10118 10118 0 0.00 0 167 4 0 0
v_reserve_write_off 19512 19512 0 0.00 0 4000 11 0 0
x_lock_depdocs_on_canc_invoice 11615 11615 0 0.00 0 14101 119 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3910 362 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 2 32814 1175 0 0
x_make_qstorno_1200_2000 5356 5356 0 0.00 4 77095 2791 0 0
x_make_qstorno_2100_3300 472481 472481 0 0.00 0 120759 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-06-2022 07:39:16 to 06-06-2022 07:39:20.

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:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 31555 8192000000 9726188416 10026577920 801362800 8.910 100 85 169 0 829484864 7652144 20469920
05:09:57 17360 8192000000 9761008496 10068570112 834909296 9.249 100 85 170 0 868362176 12515680 20937200
05:11:23 16770 8192000000 9804437792 10127237120 874189264 9.642 100 79 158 1 913229840 20988080 18052496
05:12:44 10709 8192000000 9869846688 10206117888 923368336 10.129 100 85 166 0 987367456 46508240 17490880
05:14:00 5526 8192000000 9928999168 10272722944 967293008 10.560 100 87 170 0 1043378192 58477088 17608096
05:15:08 2608 8192000000 9965649120 10317873152 991067472 10.792 100 95 185 0 1080735328 70421040 19246816
05:16:13 6406 8192000000 10000617984 10364411904 1017140864 11.044 100 84 152 1 1120008640 87881840 14985936
05:17:23 1239 8192000000 10010952592 10381156352 1045792048 11.320 100 94 183 0 1130252064 65219152 19240864
05:18:26 1522 8192000000 10031454384 10407272448 1065445568 11.509 100 95 186 0 1152184768 68313616 18425584
05:19:29 1012 8192000000 10054151376 10432282624 1074310704 11.593 100 96 193 3 1173477552 79627008 19539840
05:20:31 1410 8192000000 10034759840 10421444608 1075563984 11.605 100 78 152 0 1158473456 67645280 15264192
05:21:34 1236 8192000000 10062196992 10450059264 1079866640 11.646 100 94 185 0 1185294624 86074432 19353552
05:22:36 1128 8192000000 10043213312 10428002304 1089566688 11.739 100 94 182 0 1165318336 56725120 19026528
05:23:38 1417 8192000000 10068367472 10456498176 1093253424 11.774 100 99 187 2 1189873760 77880784 18739552
05:24:40 1264 8192000000 10081314048 10471530496 1091520288 11.757 100 93 178 2 1201266448 91971552 17774608
05:25:42 1070 8192000000 10082108016 10472214528 1093822544 11.779 100 95 184 3 1204775680 90795248 20157888
05:26:46 3213 8192000000 10066498336 10451890176 1104510656 11.880 100 85 163 0 1182537872 62516352 15510864
05:27:49 1151 8192000000 10048219792 10445701120 1090165840 11.744 100 75 148 1 1170509744 64627792 15716112
05:28:51 1432 8192000000 10055809056 10445713408 1093006416 11.771 100 80 156 1 1177993744 68188192 16799136
05:29:53 2228 8192000000 10043600544 10440069120 1092793456 11.769 100 77 141 6 1173126080 63874464 16458160
05:30:56 1038 8192000000 10045978496 10433699840 1084832304 11.693 100 86 160 4 1166397056 64473072 17091680
05:31:57 2375 8192000000 9985010928 10363912192 1052077792 11.381 99 76 142 3 1105979840 40562432 13339616
05:33:01 9244 8192000000 9996906672 10374893568 1041456064 11.279 100 67 126 1 1115336768 61198800 12681904
05:34:11 7224 8192000000 10004975888 10388070400 1040172720 11.266 99 68 135 0 1119518192 66318176 13027296
05:35:19 1000 8192000000 9989847104 10360229888 1034198000 11.209 100 93 179 0 1109323504 56358432 18767072
05:36:21 3918 8192000000 9991967472 10364370944 1031000608 11.178 100 74 142 4 1120120400 74737728 14382064
05:37:29 1389 8192000000 9971115744 10345775104 1036938528 11.235 100 76 132 5 1087647024 37863392 12845104
05:38:39 32415 8192000000 9994193152 10389331968 1055487024 11.413 100 60 111 2 1120176048 53883680 10805344
05:40:16 3706 8192000000 10059720112 10443292672 1066433520 11.518 100 88 169 0 1182612528 99055440 17123568
05:41:31 9114 8192000000 10074731232 10468552704 1081197888 11.659 100 60 116 2 1202221216 108507888 12515440
05:42:51 3020 8192000000 10140184800 10525741056 1099448832 11.832 100 79 151 2 1258471584 143086592 15936160
05:44:00 8062 8192000000 10154110896 10552377344 1106028384 11.895 100 56 112 0 1278542464 160709968 11804112
05:45:15 1670 8192000000 10197406496 10591866880 1125340512 12.077 100 89 171 2 1314967824 171306464 18320848
05:46:19 1680 8192000000 10283297584 10670940160 1138464800 12.201 100 94 188 0 1383053648 224405584 20183264
05:47:28 2939 8192000000 10301375424 10698620928 1162942224 12.431 100 81 161 0 1409319696 229243808 17133664
05:48:34 2478 8192000000 10326599360 10735521792 1174356400 12.538 100 78 151 1 1435752256 244846048 16549808
05:49:40 745 8192000000 10373006368 10775703552 1181595440 12.605 100 92 178 0 1484927184 283104128 20227616
05:50:44 4609 8192000000 10385311200 10799710208 1182604016 12.614 100 84 163 0 1498092864 297147760 18341088
05:51:50 1361 8192000000 10427960272 10832166912 1192433728 12.706 100 91 179 3 1535670880 323151696 20085456
05:52:54 2285 8192000000 10469820016 10878447616 1202141680 12.796 100 93 181 0 1575776400 352855840 20778880
05:53:59 1702 8192000000 10526349504 10936631296 1209380000 12.863 100 90 175 0 1632082784 400500112 22202672
05:55:05 1042 8192000000 10554610464 11000401920 1211932000 12.887 100 96 189 0 1661469328 427197344 22339984
05:56:08 1567 8192000000 10542896688 11009523712 1213643312 12.903 100 93 186 0 1652192080 415910656 22638112
05:57:14 3500 8192000000 10575397968 11017801728 1215638976 12.921 100 74 139 3 1681410864 447734432 18037456
05:58:25 1254 8192000000 10613596720 11023589376 1203401456 12.808 100 98 192 0 1711611552 484641568 23568528
05:59:30 811 8192000000 10660360544 11118870528 1211590640 12.884 100 95 187 1 1763373584 527872112 23910832
06:00:33 825 8192000000 10648177728 11181764608 1212917472 12.896 100 96 191 0 1749438432 512283888 24237072
06:01:40 4385 8192000000 10618766288 11078963200 1208668944 12.857 100 58 114 0 1724908832 501277440 14962448
06:02:48 23041 8192000000 10590675008 11084505088 1204332608 12.817 100 42 84 0 1731803456 516010288 11460560
06:04:48 13719 8192000000 10519833552 11028697088 1194485792 12.725 100 37 72 1 1656451104 452618672 9346640
06:06:35 32662 8192000000 10384919952 10894434304 1156438576 12.370 99 24 48 0 1511910512 348780048 6691888
06:08:08 52264 8192000000 10106811360 10671865856 1120732880 12.034 97 16 32 0 1288691408 163805632 4152896
06:10:04 50709 8192000000 10109055408 10535059456 1110432064 11.937 97 60 108 0 1185574688 63026208 12116416
06:11:57 29757 8192000000 10143249504 10631516160 1143078064 12.244 100 11 22 1 1255454096 109813104 2562928
06:13:27 98438 8192000000 10068315504 10509766656 1117647904 12.005 98 7 14 0 1154756176 35035936 2072336
06:16:07 108427 8192000000 10082247136 10556395520 1124268432 12.067 98 11 22 0 1181995648 55796256 1930960
06:18:56 116629 8192000000 10110559904 10588745728 1066109552 11.515 92 12 24 1 1123624944 55368976 2146416
06:21:54 172393 8192000000 10063982960 10482831360 986650976 10.749 87 11 21 0 1023991792 35411776 1929040
06:25:51 88247 8192000000 10042873360 10465263616 1073496576 11.585 96 15 28 0 1104050368 27626352 2927440
06:28:21 104066 8192000000 10104376352 10539855872 1122235936 12.048 100 40 78 0 1184240816 54584224 7420656
06:31:16 30388 8192000000 10154218704 10591100928 1139184624 12.208 100 53 101 0 1223931056 74070176 10676256
06:32:48 68962 8192000000 10156923344 10586300416 1101125472 11.848 96 38 73 0 1206634400 97692624 7816304
06:34:59 28671 8192000000 10182568128 10660323328 1154091072 12.348 100 51 93 1 1321033568 156676480 10266016
06:36:41 45652 8192000000 10171506112 10633359360 1145647648 12.269 100 27 54 0 1291176976 139261024 6268304
06:38:28 70382 8192000000 10130603872 10582138880 1131513408 12.136 99 12 24 0 1239435856 105356640 2565808
06:40:40 41981 8192000000 10089813632 10561847296 1102841296 11.865 97 16 31 0 1207399952 101117312 3441344
06:42:23 51261 8192000000 10093640880 10581712896 1131297680 12.134 100 13 26 0 1194483040 60353072 2832288
06:44:18 41448 8192000000 10144224384 10587299840 1123615968 12.061 99 48 94 1 1204139248 70826688 9696592
06:46:09 69159 8192000000 10116834496 10593263616 1138573616 12.202 99 20 38 1 1229094656 86351952 4169088
06:48:31 124498 8192000000 10100263472 10560737280 1118767616 12.015 97 11 20 2 1177772704 56769600 2235488
06:51:36 64115 8192000000 10099643088 10551123968 1136907776 12.186 99 15 30 1 1184887872 44464304 3515792
06:53:40 140668 8192000000 10136978608 10619904000 1123751696 12.062 97 25 50 0 1213360192 84357568 5250928
06:57:02 96362 8192000000 10104875792 10545127424 1128271040 12.105 98 31 62 0 1174205632 39805072 6129520
06:59:39 51958 8192000000 10120452528 10581233664 1113258640 11.963 97 17 34 2 1198467296 81148112 4060544
07:01:31 54426 8192000000 10070288736 10563125248 1108682080 11.920 98 16 32 1 1178370752 66245232 3443440
07:03:35 45263 8192000000 10066598304 10526679040 1106906768 11.903 98 16 32 1 1147336320 36737088 3692464
07:05:23 60955 8192000000 10092971920 10558271488 1090461728 11.747 96 13 27 0 1162881904 69418064 3002112
07:07:24 42835 8192000000 10112403536 10562551808 1096234256 11.802 97 18 36 0 1186607792 86345840 4027696
07:09:07 30776 8192000000 10079235600 10518917120 1074150432 11.592 96 19 39 0 1151560464 73354976 4055056
07:10:42 41448 8192000000 10119556480 10547462144 1092638880 11.768 98 41 80 1 1172634704 72035200 7960624
07:12:25 57438 8192000000 10135212448 10611580928 1102397648 11.860 99 41 82 1 1246543232 136168048 7977536
07:14:24 30920 8192000000 10100827936 10566709248 1119798304 12.025 100 48 96 1 1202105056 72812240 9494512
07:16:16 59280 8192000000 10070788672 10554081280 1086932000 11.713 97 31 62 0 1157343408 64456608 5954800
07:18:18 79209 8192000000 10063904464 10490765312 1118907072 12.017 100 36 69 1 1164605200 38466816 7231312
07:20:38 93024 8192000000 10084441616 10540003328 1111572320 11.947 98 18 36 1 1166017472 50456144 3989008
07:23:12 61714 8192000000 10067027584 10526560256 1118091856 12.009 99 4 8 0 1167088432 48160160 836416
07:25:20 40978 8192000000 10085127056 10528374784 1114077808 11.971 98 28 54 1 1169519920 49257984 6184128
07:27:02 37061 8192000000 10128482864 10575839232 1128288080 12.105 99 22 44 0 1214049040 80952640 4808320
07:28:43 15709 8192000000 10186880464 10612461568 1146213168 12.274 100 52 100 2 1269439168 112315120 10910880
07:30:03 36456 8192000000 10200571776 10678517760 1130800672 12.129 98 38 76 0 1316108352 176045056 9262624
07:31:44 24183 8192000000 10220270400 10648088576 1151722400 12.326 100 47 91 1 1307384912 145085904 10576608
07:33:08 31234 8192000000 10251834928 10695827456 1130072672 12.122 99 48 94 1 1342459584 201660672 10726240
07:34:48 37152 8192000000 10191439744 10667020288 1131069072 12.131 99 33 66 1 1283864848 145253312 7542464
07:36:25 64654 8192000000 9356293328 9755029504 463522240 5.355 41 8 16 0 533878304 68675584 1680480

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-06-2022 07:39:22 to 06-06-2022 07:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 171 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          217912
MON$OLDEST_ACTIVE               217913
MON$OLDEST_SNAPSHOT             217913
MON$NEXT_TRANSACTION            217913
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                       2071264
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:198: 
:199: mscs-test-161.budzdorov.ru    Mon Jun  6 07:37:45 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:201: 
:202: 
:203: mscs-test-161.budzdorov.ru    Mon Jun  6 07:37:45 2022
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:205: 
--- end of diff output ---

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

06.06.22 07:39:42. 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-06-06 07:39:44 No severe PSQL-related problems occured
06.06.22 07:39:46. End of report.