Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2022 01: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 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 15430188 kB
MemAvailable 30511000 kB
Buffers 0 kB
Cached 16264340 kB
SwapCached 2132 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-10 01:38:30
DTS_END                         2022-10-10 01:38:30.7870
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(57:17)
IP                              ::1/54502
TRN_ID                          249918
ATT_ID                          682
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: 9482.10 19854 1137852
customer order: creation 183.51 339 22021
customer order: refuse 15.83 358 1899
order to supplier: creation 49.94 1248 5993
order to supplier: removal 3.46 107121 415
invoice (draft): creation 49.58 3322 5950
invoice (draft): removal 52.47 898 6296
invoice accept: apply 44.73 17417 5367
invoice accept: cancel 46.94 792 5633
customer reserve: creation 4114.73 173 493767
customer reserve: removal 4613.60 62 553632
realization accept: apply 89.30 169 10716
realization accept: cancel 80.51 113 9661
payment from customer: creation 62.48 118 7498
payment from customer: removal 5.84 65 701
payment to supplier: creation 51.95 116 6234
payment to supplier: removal 3.83 104 460
service: total inventory turnovers 4.86 1074 583
service: total monetary turnovers 7.51 40 901
service: aggregate perf. data 1.01 15689 121
service: refresh index statistics 0.03 247860 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2022 01:39:27 to 10-10-2022 01:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1822
TEST_TIME 2 5724
TEST_TIME 3 6600
TEST_TIME 4 6684
TEST_TIME 5 5986
TEST_TIME 6 6296
TEST_TIME 7 6079
TEST_TIME 8 6148
TEST_TIME 9 5911
TEST_TIME 10 5797
TEST_TIME 11 5821
TEST_TIME 12 6115
TEST_TIME 13 6369
TEST_TIME 14 6645
TEST_TIME 15 6791
TEST_TIME 16 7033
TEST_TIME 17 7271
TEST_TIME 18 7575
TEST_TIME 19 7746
TEST_TIME 20 8235
TEST_TIME 21 8146
TEST_TIME 22 8762
TEST_TIME 23 8737
TEST_TIME 24 8587
TEST_TIME 25 8500
TEST_TIME 26 8460
TEST_TIME 27 8644
TEST_TIME 28 8801
TEST_TIME 29 9014
TEST_TIME 30 9883
TEST_TIME 31 10567
TEST_TIME 32 10576
TEST_TIME 33 10632
TEST_TIME 34 10485
TEST_TIME 35 10283
TEST_TIME 36 10185
TEST_TIME 37 10352
TEST_TIME 38 10189
TEST_TIME 39 10163
TEST_TIME 40 10147
TEST_TIME 41 9936
TEST_TIME 42 9866
TEST_TIME 43 9792
TEST_TIME 44 9889
TEST_TIME 45 9895
TEST_TIME 46 9944
TEST_TIME 47 9829
TEST_TIME 48 9779
TEST_TIME 49 9728
TEST_TIME 50 9654
TEST_TIME 51 9551
TEST_TIME 52 9493
TEST_TIME 53 9469
TEST_TIME 54 9561
TEST_TIME 55 9533
TEST_TIME 56 9631
TEST_TIME 57 9588
TEST_TIME 58 9639
TEST_TIME 59 9667
TEST_TIME 60 9621
TEST_TIME 61 9784
TEST_TIME 62 9889
TEST_TIME 63 9905
TEST_TIME 64 9917
TEST_TIME 65 9833
TEST_TIME 66 9773
TEST_TIME 67 9736
TEST_TIME 68 9672
TEST_TIME 69 9652
TEST_TIME 70 9654
TEST_TIME 71 9616
TEST_TIME 72 9554
TEST_TIME 73 9531
TEST_TIME 74 9464
TEST_TIME 75 9494
TEST_TIME 76 9494
TEST_TIME 77 9571
TEST_TIME 78 9519
TEST_TIME 79 9616
TEST_TIME 80 9576
TEST_TIME 81 9642
TEST_TIME 82 9607
TEST_TIME 83 9629
TEST_TIME 84 9680
TEST_TIME 85 9762
TEST_TIME 86 9747
TEST_TIME 87 9665
TEST_TIME 88 9669
TEST_TIME 89 9617
TEST_TIME 90 9594
TEST_TIME 91 9555
TEST_TIME 92 9509
TEST_TIME 93 9545
TEST_TIME 94 9524
TEST_TIME 95 9512
TEST_TIME 96 9502
TEST_TIME 97 9529
TEST_TIME 98 9538
TEST_TIME 99 9522
TEST_TIME 100 9523
TEST_TIME 101 9542
TEST_TIME 102 9572
TEST_TIME 103 9620
TEST_TIME 104 9647
TEST_TIME 105 9631
TEST_TIME 106 9653
TEST_TIME 107 9629
TEST_TIME 108 9581
TEST_TIME 109 9526
TEST_TIME 110 9495
TEST_TIME 111 9470
TEST_TIME 112 9453
TEST_TIME 113 9402
TEST_TIME 114 9477
TEST_TIME 115 9493
TEST_TIME 116 9481
TEST_TIME 117 9478
TEST_TIME 118 9500
TEST_TIME 119 9526
TEST_TIME 120 9482

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-10-2022 01:39:30 to 10-10-2022 01:39:34.

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 1594328 1594328 0 0.00 0 16322 6 0 0
doc_list_biud 1594328 1594328 0 0.00 0 58626 28 0 0
sp_add_invoice_to_stock 5477 5367 110 2.00 36 170834 17418 0 110
sp_cancel_adding_invoice 5633 5633 0 0.00 3 79705 792 0 0
sp_cancel_client_order 1899 1899 0 0.00 4 9124 358 0 0
sp_cancel_customer_reserve 553632 553632 0 0.00 0 58627 63 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 1641 65 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 6884 104 0 0
sp_cancel_supplier_invoice 6307 6296 11 0.17 2 22239 899 0 11
sp_cancel_supplier_order 415 415 0 0.00 6 1076408 107121 0 0
sp_cancel_write_off 9661 9661 0 0.00 1 7232 113 0 0
sp_client_order 22021 22021 0 0.00 3 15346 339 0 0
sp_customer_reserve 493767 493767 0 0.00 0 44331 173 0 0
sp_fill_shopping_cart 33950 33950 0 0.00 1 16251 39 0 0
sp_fill_shopping_cart_clo_res 493764 493764 0 0.00 0 12963 15 0 0
sp_get_clo_for_invoice 5364 5364 0 0.00 0 41072 304 0 0
sp_kill_cost_storno 16455 16455 0 0.00 0 6846 54 0 0
sp_kill_qstorno_ret_qs2qd 1123636 1123636 0 0.00 0 58625 34 0 0
sp_kill_qty_storno 593616 593616 0 0.00 0 58626 74 0 0
sp_lock_selected_doc 22246 22246 0 0.00 0 6932 5 0 0
sp_make_cost_storno 29812 29812 0 0.00 0 13944 49 0 0
sp_multiply_rows_for_pdistr 29812 29812 0 0.00 0 7090 26 0 0
sp_multiply_rows_for_qdistr 22021 22021 0 0.00 0 10814 153 0 0
sp_pay_from_customer 7498 7498 0 0.00 3 18480 119 0 0
sp_pay_to_supplier 6234 6234 0 0.00 4 12955 117 0 0
sp_payment_common 13732 13732 0 0.00 1 18473 22 0 0
sp_qd_handle_on_cancel_clo 1899 1899 0 0.00 0 9117 324 0 0
sp_qd_handle_on_invoice_upd_sts 10997 10997 0 0.00 0 33266 344 0 0
sp_qd_handle_on_reserve_upd_sts 10716 10716 0 0.00 0 10911 53 0 0
sp_reserve_write_off 10716 10716 0 0.00 6 12970 170 0 0
sp_supplier_invoice 5950 5950 0 0.00 6 85769 3323 0 0
sp_supplier_order 5993 5993 0 0.00 5 43139 1249 0 0
srv_aggregate_perf_data 121 121 0 0.00 2643 128980 15690 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1329 122712 40872 0 0
srv_find_qd_qs_mism 1086533 1086533 0 0.00 0 10579 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 38 44239 1074 0 0
srv_make_money_saldo 901 901 0 0.00 1 8326 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 198180 286831 247860 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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 5367 5367 0 0.00 0 13469 162 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3802 114 0 0
t$perf-norm:sp_cancel_client_order 1899 1899 0 0.00 0 8293 22 0 0
t$perf-norm:sp_cancel_customer_reserve 755 755 0 0.00 0 1241 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 761 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 390 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 1047 17 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 25300 1546 0 0
t$perf-norm:sp_cancel_write_off 1099 1099 0 0.00 0 2790 16 0 0
t$perf-norm:sp_client_order 22021 22021 0 0.00 0 5883 15 0 0
t$perf-norm:sp_customer_reserve 1108 1108 0 0.00 0 4882 13 0 0
t$perf-norm:sp_pay_from_customer 7498 7498 0 0.00 0 10513 12 0 0
t$perf-norm:sp_pay_to_supplier 6234 6234 0 0.00 0 6578 8 0 0
t$perf-norm:sp_reserve_write_off 10716 10716 0 0.00 0 6282 10 0 0
t$perf-norm:sp_supplier_invoice 5950 5950 0 0.00 0 4334 15 0 0
t$perf-norm:sp_supplier_order 5993 5993 0 0.00 0 4841 15 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 554 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 84 2 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1303 25 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 4712 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 113 29 0 0
v_add_invoice_to_stock 10946 10726 220 2.00 0 7432 19 0 220
v_all_customers 39558 39558 0 0.00 0 28 1 0 0
v_all_suppliers 32678 32678 0 0.00 0 20 1 0 0
v_all_wares 44038 44038 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1314 9 0 0
v_cancel_client_order 3798 3798 0 0.00 0 3307 9 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1633 7 0 0
v_cancel_customer_reserve 1510 1510 0 0.00 0 2093 6 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 3849 15 0 0
v_cancel_supplier_invoice 888 866 22 2.47 0 10660 35 0 22
v_cancel_supplier_order 830 830 0 0.00 0 2692 11 0 0
v_cancel_write_off 2198 2198 0 0.00 0 2888 20 0 0
v_max_id_clo_ord 5993 5993 0 0.00 0 2197 2 0 0
v_max_id_clo_res 1108 1108 0 0.00 0 1841 13 0 0
v_max_id_ord_sup 5938 5938 0 0.00 0 4500 4 0 0
v_max_non_paid_invoice 1826 1826 0 0.00 0 760 3 0 0
v_max_non_paid_realizn 4167 4167 0 0.00 0 5634 6 0 0
v_min_id_clo_ord 5993 5993 0 0.00 0 4139 7 0 0
v_min_id_clo_res 1108 1108 0 0.00 0 10479 17 0 0
v_min_id_ord_sup 5938 5938 0 0.00 0 6507 8 0 0
v_min_non_paid_invoice 6234 6234 0 0.00 0 11121 14 0 0
v_min_non_paid_realizn 7498 7498 0 0.00 0 6748 14 0 0
v_our_firm 11142 11142 0 0.00 0 17 1 0 0
v_reserve_write_off 21432 21432 0 0.00 0 6185 8 0 0
x_lock_depdocs_on_canc_invoice 11496 11496 0 0.00 0 20509 120 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8232 364 0 0
x_make_qstorno_1000_1200 5993 5993 0 0.00 1 43130 1177 0 0
x_make_qstorno_1200_2000 5950 5950 0 0.00 2 79579 3169 0 0
x_make_qstorno_2100_3300 493767 493767 0 0.00 0 44329 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2022 01:39:35 to 10-10-2022 01:39:39.

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
23:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:35 20163 8192000000 9897011184 10207318016 983216800 10.716 100 88 175 0 1011934960 5993280 22724880
23:10:58 8786 8192000000 9941750160 10263830528 1026175232 11.132 100 90 180 0 1057251024 7608576 23467216
23:12:14 17575 8192000000 9965173824 10292879360 1053698368 11.396 100 87 174 0 1085304000 9892352 21713280
23:13:39 12463 8192000000 10023898640 10367287296 1105689168 11.892 100 81 162 0 1135680960 10419584 19572208
23:14:56 8380 8192000000 10072847344 10430803968 1159727024 12.401 100 84 168 0 1191346544 12241072 19378448
23:16:08 6322 8192000000 10094252880 10464321536 1195477360 12.734 100 64 127 0 1219881584 10109088 14295136
23:17:15 1863 8192000000 10154978192 10526052352 1238774272 13.135 100 88 176 0 1272341328 14343600 19223456
23:18:19 1509 8192000000 10185816064 10565877760 1272949152 13.449 100 79 155 0 1301639088 12077568 16612368
23:19:22 1002 8192000000 10213786000 10598420480 1297824368 13.675 100 89 174 0 1330814224 13410592 19579264
23:20:24 5159 8192000000 10215159056 10609451008 1307057472 13.759 100 62 121 1 1329433504 7727312 14648720
23:21:30 5545 8192000000 10209957232 10620551168 1319500112 13.872 100 38 76 0 1334917392 7558400 7858880
23:22:36 11189 8192000000 10212212352 10628759552 1325241248 13.924 100 19 38 0 1335277072 6070448 3965376
23:23:48 9221 8192000000 10216274752 10635800576 1332728976 13.992 100 20 40 0 1342053104 4563872 4760256
23:24:58 13715 8192000000 10247365920 10654638080 1336578320 14.027 100 69 138 1 1360036912 5932320 17526272
23:26:12 5737 8192000000 10227999296 10644185088 1330332960 13.970 100 35 71 0 1345561328 7170352 8058016
23:27:20 11868 8192000000 10198798768 10616270848 1265785408 13.383 95 14 28 1 1273856416 4922912 3148096
23:28:32 54065 8192000000 10170199584 10579288064 1217596800 12.939 93 16 32 0 1226092096 4895120 3600176
23:30:27 6169 8192000000 10146550112 10544222208 1208131856 12.852 94 24 44 1 1217782528 4115392 5535280
23:31:34 5983 8192000000 10138587424 10539352064 1239653664 13.143 99 25 50 0 1251250032 5864896 5731472
23:32:41 14490 8192000000 10158053696 10557739008 1260875072 13.338 100 23 44 2 1270646640 4597664 5173904
23:33:57 23854 8192000000 10168290848 10571468800 1262718320 13.355 99 25 50 0 1274080672 6112160 5250192
23:35:24 12653 8192000000 10174176688 10586042368 1274415344 13.462 99 17 34 0 1283838240 5220096 4202800
23:36:41 6910 8192000000 10194514528 10609508352 1302727744 13.720 100 27 55 1 1315112016 5941616 6442656
23:37:48 86706 8192000000 10219843024 10633101312 1310874640 13.794 100 25 50 0 1321512624 4934064 5703920
23:40:26 16774 8192000000 10249866912 10665529344 1347404288 14.124 100 48 94 1 1365927312 8447568 10075456
23:41:59 60091 8192000000 10262756720 10691227648 1341396272 14.070 98 43 80 1 1359961760 9517024 9048464
23:44:03 7708 8192000000 10293074544 10718408704 1381958304 14.434 100 50 100 0 1408471184 14521936 11990944
23:45:19 6252 8192000000 10315704944 10740953088 1398561680 14.582 100 56 112 0 1426104352 14105472 13437200
23:46:33 25158 8192000000 10317994416 10744786944 1401940960 14.612 100 46 89 0 1428692832 16037088 10714784
23:48:12 8829 8192000000 10329716016 10761224192 1412785584 14.709 100 42 80 1 1440021200 17494416 9741200
23:49:24 16221 8192000000 10329937104 10770075648 1409247968 14.677 99 32 61 1 1439639056 22393200 7997888
23:50:46 3660 8192000000 10341222064 10763100160 1401890912 14.612 99 68 136 1 1441923344 23543904 16488528
23:51:54 1361 8192000000 10354106112 10768379904 1406242384 14.651 100 88 174 0 1457099200 30567488 20289328
23:53:01 3204 8192000000 10324045648 10735468544 1384955264 14.461 100 78 152 1 1431762080 28513808 18293008
23:54:08 1328 8192000000 10346100032 10752057344 1396662592 14.565 100 83 163 1 1450219712 34201296 19355824
23:55:14 2018 8192000000 10310082192 10713178112 1357972096 14.219 100 69 138 0 1416665808 40648416 18045296
23:56:22 5566 8192000000 10327453120 10737836032 1375907024 14.380 100 68 135 0 1433841552 41601520 16333008
23:57:34 2439 8192000000 10341711616 10743447552 1377144688 14.391 100 74 146 0 1441025728 45796176 18084864
23:58:40 10053 8192000000 10332689728 10745200640 1379139984 14.409 100 50 101 0 1437404416 43600896 14663536
23:59:58 3305 8192000000 10365530160 10770657280 1390407136 14.509 100 84 166 1 1464454928 53414048 20633744
00:01:06 16264 8192000000 10320762336 10737950720 1370008336 14.327 99 43 87 1 1428718720 47186912 11523472
00:02:26 7570 8192000000 10329468640 10748784640 1382979136 14.443 100 51 102 0 1438535792 41731744 13824912
00:03:47 22316 8192000000 10341408624 10764664832 1388041232 14.488 100 40 80 1 1448575056 49579920 10953904
00:05:16 7646 8192000000 10343135152 10771660800 1389648896 14.503 100 35 70 0 1448811792 49131872 10031024
00:06:53 16711 8192000000 10322210208 10756546560 1353812480 14.182 100 24 48 0 1422661600 60665136 8183984
00:08:14 38612 8192000000 10312686192 10752180224 1349251056 14.141 99 17 34 0 1409170864 55172592 4747216
00:10:00 8555 8192000000 10302630960 10729361408 1375020240 14.372 100 53 106 0 1395499968 8057024 12422704
00:11:10 91763 8192000000 10313179488 10761199616 1368311200 14.312 98 9 19 0 1383449056 13288896 1848960
00:13:49 98493 8192000000 10311842624 10768150528 1391730080 14.521 100 31 62 0 1415625392 16329280 7566032
00:16:33 38114 8192000000 10309427536 10754494464 1395014400 14.551 100 51 100 0 1414519168 7576064 11928704
00:18:16 26824 8192000000 10297084128 10748751872 1388254528 14.490 100 24 47 1 1403499568 10148784 5096256
00:20:54 37182 8192000000 10309984560 10762186752 1391531008 14.520 99 25 50 0 1411776528 14197664 6047856
00:22:35 28392 8192000000 10321863216 10770538496 1404200656 14.632 100 45 90 2 1429373968 15062096 10111216
00:24:08 64716 8192000000 10302897456 10769264640 1403236704 14.624 100 23 47 1 1420309584 11879776 5193104
00:26:14 44733 8192000000 10300638736 10766000128 1395261424 14.553 99 9 18 0 1406250160 8674768 2313968
00:28:00 49996 8192000000 10292510288 10762579968 1384524000 14.457 99 9 18 0 1401790688 15108416 2158272
00:29:51 59894 8192000000 10292859456 10759032832 1357895264 14.218 97 6 12 0 1367768384 8676368 1196752
00:31:52 113215 8192000000 10285054576 10744799232 1301397488 13.708 93 6 12 0 1317526768 15028416 1100864
00:34:46 64520 8192000000 10299617856 10759909376 1370081904 14.328 98 20 40 2 1388382528 13520016 4780608
00:36:50 58709 8192000000 10291368016 10736234496 1340039456 14.058 97 27 54 0 1359443152 13154032 6249664
00:38:57 56831 8192000000 10286599760 10738069504 1352819008 14.173 99 15 30 0 1372407792 16392848 3195936
00:40:57 45555 8192000000 10289876080 10744070144 1342883120 14.083 97 15 30 1 1359795232 13753280 3158832
00:42:48 65442 8192000000 10283057856 10730938368 1362503712 14.260 99 26 52 0 1378799248 10385392 5910144
00:45:57 60143 8192000000 10268354864 10712576000 1324314496 13.916 97 24 46 0 1336967216 7924016 4728704
00:47:58 27819 8192000000 10277003376 10726440960 1365535184 14.287 100 23 44 1 1382099776 10701776 5862816
00:49:32 54529 8192000000 10246939792 10704293888 1318997952 13.868 97 10 20 0 1328648784 7552464 2098368
00:51:26 72064 8192000000 10265217456 10715303936 1358413312 14.223 100 12 24 0 1369978416 8858848 2706256
00:53:42 66001 8192000000 10266786336 10724057088 1331726816 13.983 97 16 31 0 1347855808 12886944 3242048
00:56:04 8857 8192000000 10261249024 10708013056 1362838768 14.263 100 54 111 0 1382274704 7358976 12076960
00:57:14 52727 8192000000 10253520032 10705129472 1315366752 13.835 97 12 24 1 1329600304 11437904 2795648
00:59:09 50520 8192000000 10259916464 10717188096 1354464896 14.188 100 33 66 0 1374967920 13159904 7343120
01:01:02 24389 8192000000 10292826048 10729713664 1366714704 14.298 100 33 66 0 1384824128 10857632 7251792
01:02:41 68120 8192000000 10270156768 10738806784 1331032128 13.976 97 16 33 0 1350309984 15452880 3824976
01:04:50 89153 8192000000 10268816240 10718724096 1345001792 14.102 98 24 48 1 1359498752 8770928 5726032
01:07:39 21571 8192000000 10260912896 10725531648 1357984352 14.219 100 6 12 0 1376846944 17541264 1321328
01:09:00 49654 8192000000 10280630496 10744418304 1382453920 14.438 100 6 12 0 1391505312 7201168 1850224
01:10:50 76649 8192000000 10285409632 10759323648 1380116880 14.418 99 8 16 0 1393152320 11414688 1620752
01:13:07 13754 8192000000 10290912720 10748628992 1383774352 14.450 99 41 79 0 1397960000 6140320 8045328
01:14:24 32402 8192000000 10281207424 10730016768 1350580032 14.153 97 21 42 0 1364158640 8679616 4898992
01:16:03 54332 8192000000 10283211424 10731274240 1330935040 13.976 96 14 28 0 1341572672 7844192 2793440
01:18:00 65744 8192000000 10310806272 10760617984 1386573856 14.475 99 24 48 0 1406189536 14399520 5216160
01:21:11 59521 8192000000 10286956880 10744348672 1309919968 13.785 94 15 30 0 1328018816 14831360 3267488
01:23:26 40931 8192000000 10292912736 10735915008 1333647552 14.000 96 28 56 0 1348569312 8786416 6135344
01:25:10 122712 8192000000 10273105024 10720514048 1363610880 14.270 99 10 20 0 1375112000 9071696 2429424
01:28:24 65053 8192000000 10268174416 10732765184 1350904384 14.156 98 13 26 0 1369172656 15304912 2963360
01:30:29 61380 8192000000 10280891760 10737176576 1365302848 14.285 99 16 32 0 1380634560 11487456 3844256
01:33:17 48824 8192000000 10266875744 10716966912 1332775072 13.992 97 24 48 0 1347107856 9149952 5182832
01:35:08 34044 8192000000 10270432000 10720567296 1349442848 14.142 99 33 66 0 1369209328 12565808 7200672
01:36:45 53814 8192000000 10240086096 10697998336 1304139648 13.733 95 24 48 1 1318803104 9118320 5545136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2022 01:39:41 to 10-10-2022 01:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 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 10-10-2022 01:39:57 to 10-10-2022 01:39:59.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          249917
MON$OLDEST_ACTIVE               251218
MON$OLDEST_SNAPSHOT             251218
MON$NEXT_TRANSACTION            251218
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-03 16:41:55.3470
MON$PAGES                       2033888
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:113: 
:114: mscs-test-161.budzdorov.ru    Mon Oct 10 01:38:34 2022
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Mon Oct 10 01:38:34 2022
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Mon Oct 10 01:38:39 2022
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:124: 
--- end of diff output ---

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

10.10.22 01:40:00. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-10 01:40:03 No severe PSQL-related problems occured
10.10.22 01:40:04. End of report.