Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2021 01:40
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 16466784 kB
MemAvailable 31414660 kB
Buffers 0 kB
Cached 15232540 kB
SwapCached 444 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.9
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 2021-12-24 01:37:58
DTS_END                         2021-12-24 01:37:58.2050
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/53496
TRN_ID                          244761
ATT_ID                          642
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: 9679.68 17860 1161562
customer order: creation 180.59 347 21671
customer order: refuse 14.79 341 1775
order to supplier: creation 48.28 1287 5794
order to supplier: removal 3.56 92746 427
invoice (draft): creation 47.68 3442 5722
invoice (draft): removal 54.12 734 6494
invoice accept: apply 43.75 17757 5250
invoice accept: cancel 48.84 742 5861
customer reserve: creation 4074.26 174 488911
customer reserve: removal 4863.12 53 583574
realization accept: apply 87.45 162 10494
realization accept: cancel 79.95 104 9594
payment from customer: creation 60.35 98 7242
payment from customer: removal 6.21 65 745
payment to supplier: creation 51.17 113 6140
payment to supplier: removal 3.65 94 438
service: total inventory turnovers 4.43 969 532
service: total monetary turnovers 6.49 28 779
service: aggregate perf. data 0.96 17587 115
service: refresh index statistics 0.03 220364 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2021 01:40:40 to 24-12-2021 01:40:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1357
TEST_TIME 2 2828
TEST_TIME 3 3450
TEST_TIME 4 4347
TEST_TIME 5 4530
TEST_TIME 6 4723
TEST_TIME 7 4800
TEST_TIME 8 4944
TEST_TIME 9 5014
TEST_TIME 10 5772
TEST_TIME 11 5807
TEST_TIME 12 6072
TEST_TIME 13 6417
TEST_TIME 14 6256
TEST_TIME 15 6295
TEST_TIME 16 6660
TEST_TIME 17 6847
TEST_TIME 18 7303
TEST_TIME 19 7554
TEST_TIME 20 7791
TEST_TIME 21 7906
TEST_TIME 22 8037
TEST_TIME 23 8819
TEST_TIME 24 8838
TEST_TIME 25 8973
TEST_TIME 26 9414
TEST_TIME 27 9478
TEST_TIME 28 9313
TEST_TIME 29 9809
TEST_TIME 30 10254
TEST_TIME 31 10089
TEST_TIME 32 9917
TEST_TIME 33 9834
TEST_TIME 34 9827
TEST_TIME 35 9951
TEST_TIME 36 10157
TEST_TIME 37 10216
TEST_TIME 38 10310
TEST_TIME 39 10393
TEST_TIME 40 10289
TEST_TIME 41 10247
TEST_TIME 42 10012
TEST_TIME 43 10018
TEST_TIME 44 10086
TEST_TIME 45 9995
TEST_TIME 46 9933
TEST_TIME 47 9904
TEST_TIME 48 9907
TEST_TIME 49 9842
TEST_TIME 50 9931
TEST_TIME 51 10065
TEST_TIME 52 9961
TEST_TIME 53 9927
TEST_TIME 54 9922
TEST_TIME 55 9815
TEST_TIME 56 9718
TEST_TIME 57 9625
TEST_TIME 58 9700
TEST_TIME 59 9646
TEST_TIME 60 9548
TEST_TIME 61 9611
TEST_TIME 62 9548
TEST_TIME 63 9581
TEST_TIME 64 9541
TEST_TIME 65 9695
TEST_TIME 66 9731
TEST_TIME 67 9694
TEST_TIME 68 9775
TEST_TIME 69 9769
TEST_TIME 70 9861
TEST_TIME 71 9888
TEST_TIME 72 9874
TEST_TIME 73 9869
TEST_TIME 74 9887
TEST_TIME 75 9784
TEST_TIME 76 9766
TEST_TIME 77 9708
TEST_TIME 78 9708
TEST_TIME 79 9686
TEST_TIME 80 9629
TEST_TIME 81 9594
TEST_TIME 82 9505
TEST_TIME 83 9453
TEST_TIME 84 9658
TEST_TIME 85 9618
TEST_TIME 86 9590
TEST_TIME 87 9602
TEST_TIME 88 9630
TEST_TIME 89 9617
TEST_TIME 90 9663
TEST_TIME 91 9720
TEST_TIME 92 9714
TEST_TIME 93 9736
TEST_TIME 94 9766
TEST_TIME 95 9799
TEST_TIME 96 9855
TEST_TIME 97 9821
TEST_TIME 98 9747
TEST_TIME 99 9739
TEST_TIME 100 9711
TEST_TIME 101 9705
TEST_TIME 102 9663
TEST_TIME 103 9652
TEST_TIME 104 9620
TEST_TIME 105 9598
TEST_TIME 106 9586
TEST_TIME 107 9569
TEST_TIME 108 9569
TEST_TIME 109 9607
TEST_TIME 110 9645
TEST_TIME 111 9621
TEST_TIME 112 9642
TEST_TIME 113 9607
TEST_TIME 114 9632
TEST_TIME 115 9646
TEST_TIME 116 9697
TEST_TIME 117 9714
TEST_TIME 118 9793
TEST_TIME 119 9739
TEST_TIME 120 9682
TEST_TIME 121 9602

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2021 01:40:44 to 24-12-2021 01:40:47.

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 1611861 1611861 0 0.00 0 11685 6 0 0
doc_list_biud 1611861 1611861 0 0.00 0 31551 25 0 0
sp_add_invoice_to_stock 5442 5250 192 3.52 40 173830 17758 0 192
sp_cancel_adding_invoice 5861 5861 0 0.00 4 53418 742 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 10310 341 0 0
sp_cancel_customer_reserve 583574 583574 0 0.00 1 31553 53 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 3 2800 65 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 5886 94 0 0
sp_cancel_supplier_invoice 6512 6494 18 0.27 1 27205 735 0 18
sp_cancel_supplier_order 427 427 0 0.00 8 1071776 92746 0 0
sp_cancel_write_off 9594 9594 0 0.00 1 13082 105 0 0
sp_client_order 21671 21671 0 0.00 3 78397 347 0 0
sp_customer_reserve 488911 488911 0 0.00 0 80253 175 0 0
sp_fill_shopping_cart 33183 33183 0 0.00 1 7839 33 0 0
sp_fill_shopping_cart_clo_res 488898 488898 0 0.00 0 21824 13 0 0
sp_get_clo_for_invoice 5236 5236 0 0.00 0 20283 291 0 0
sp_kill_cost_storno 16638 16638 0 0.00 0 6897 54 0 0
sp_kill_qstorno_ret_qs2qd 1183663 1183663 0 0.00 0 31544 29 0 0
sp_kill_qty_storno 623455 623455 0 0.00 0 31550 63 0 0
sp_lock_selected_doc 21753 21753 0 0.00 0 10084 7 0 0
sp_make_cost_storno 29112 29112 0 0.00 0 11672 45 0 0
sp_multiply_rows_for_pdistr 29112 29112 0 0.00 0 7605 26 0 0
sp_multiply_rows_for_qdistr 21671 21671 0 0.00 0 78385 165 0 0
sp_pay_from_customer 7242 7242 0 0.00 4 13562 99 0 0
sp_pay_to_supplier 6140 6140 0 0.00 4 29803 113 0 0
sp_payment_common 13382 13382 0 0.00 2 29793 25 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 6798 310 0 0
sp_qd_handle_on_invoice_upd_sts 11097 11097 0 0.00 0 29337 307 0 0
sp_qd_handle_on_reserve_upd_sts 10494 10494 0 0.00 0 18204 54 0 0
sp_reserve_write_off 10494 10494 0 0.00 7 18222 162 0 0
sp_supplier_invoice 5722 5722 0 0.00 7 80400 3443 0 0
sp_supplier_order 5794 5794 0 0.00 5 24213 1287 0 0
srv_aggregate_perf_data 115 115 0 0.00 2536 212429 17588 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 935 126891 45748 0 0
srv_find_qd_qs_mism 1110384 1110384 0 0.00 0 9671 5 0 0
srv_make_invnt_saldo 532 532 0 0.00 50 20706 970 0 0
srv_make_money_saldo 779 779 0 0.00 0 3312 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 153777 297305 220365 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5250 5250 0 0.00 0 8530 148 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 3155 88 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 872 7 0 0
t$perf-norm:sp_cancel_customer_reserve 760 760 0 0.00 0 2886 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 752 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 1122 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 451 451 0 0.00 0 2073 27 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 20061 1427 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 2646 11 0 0
t$perf-norm:sp_client_order 21671 21671 0 0.00 0 9571 19 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 904 7 0 0
t$perf-norm:sp_pay_from_customer 7242 7242 0 0.00 0 11142 13 0 0
t$perf-norm:sp_pay_to_supplier 6140 6140 0 0.00 0 4698 9 0 0
t$perf-norm:sp_reserve_write_off 10494 10494 0 0.00 0 4283 7 0 0
t$perf-norm:sp_supplier_invoice 5722 5722 0 0.00 0 6344 18 0 0
t$perf-norm:sp_supplier_order 5794 5794 0 0.00 0 12714 16 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 567 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 338 7 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 3227 26 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 1456 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10856 10472 384 3.53 0 3405 18 0 384
v_all_customers 38952 38952 0 0.00 0 13 1 0 0
v_all_suppliers 31588 31588 0 0.00 0 18 1 0 0
v_all_wares 43340 43340 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1812 7 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2488 5 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 2789 6 0 0
v_cancel_customer_reserve 1520 1520 0 0.00 0 3237 9 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 3179 16 0 0
v_cancel_supplier_invoice 938 902 36 3.83 0 818 15 0 36
v_cancel_supplier_order 854 854 0 0.00 0 509 3 0 0
v_cancel_write_off 2026 2026 0 0.00 0 4561 14 0 0
v_max_id_clo_ord 5794 5794 0 0.00 0 940 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 4553 19 0 0
v_max_id_ord_sup 5718 5718 0 0.00 0 3559 4 0 0
v_max_non_paid_invoice 1858 1858 0 0.00 0 1191 3 0 0
v_max_non_paid_realizn 4016 4016 0 0.00 0 2126 3 0 0
v_min_id_clo_ord 5794 5794 0 0.00 0 2517 4 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 2645 5 0 0
v_min_id_ord_sup 5718 5718 0 0.00 0 4551 8 0 0
v_min_non_paid_invoice 6140 6140 0 0.00 0 29790 20 0 0
v_min_non_paid_realizn 7242 7242 0 0.00 0 13552 17 0 0
v_our_firm 10840 10840 0 0.00 0 21 1 0 0
v_reserve_write_off 20988 20988 0 0.00 0 3327 6 0 0
x_lock_depdocs_on_canc_invoice 11904 11904 0 0.00 0 18072 107 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 13065 411 0 0
x_make_qstorno_1000_1200 5794 5794 0 0.00 2 24144 1214 0 0
x_make_qstorno_1200_2000 5722 5722 0 0.00 3 79844 3328 0 0
x_make_qstorno_2100_3300 488911 488911 0 0.00 0 80251 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2021 01:40:48 to 24-12-2021 01:40:52.

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:01 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:02 41938 8192000000 9906025648 10221334528 984506192 10.728 100 90 180 0 1014154400 6320080 23328128
23:10:48 27467 8192000000 9957967632 10283188224 1036985360 11.236 100 90 180 0 1069264048 8916336 23362352
23:12:36 15855 8192000000 10013002912 10352828416 1091466864 11.757 100 81 162 1 1126065216 15059168 19539184
23:13:58 11860 8192000000 10067898448 10424713216 1151589488 12.324 100 81 161 1 1182741184 11966704 19184992
23:15:13 9281 8192000000 10108526880 10475954176 1193768608 12.718 100 76 151 1 1222462192 11663888 17029696
23:16:25 3209 8192000000 10141607760 10514411520 1229425680 13.049 100 82 164 2 1261408736 14060144 17922912
23:17:30 3534 8192000000 10172928640 10551369728 1256516928 13.298 100 86 171 1 1289053232 12683280 19853024
23:18:36 2344 8192000000 10201695440 10582482944 1282096080 13.532 100 86 172 1 1313340352 11963520 19280752
23:19:41 33871 8192000000 10196441712 10590277632 1292852688 13.630 100 58 110 2 1315304720 9323664 13128368
23:21:16 35480 8192000000 10201798896 10609938432 1304572224 13.737 100 31 61 1 1317042912 5492928 6977760
23:22:53 8906 8192000000 10199142960 10622783488 1317018576 13.850 100 15 30 0 1326291520 5630560 3642384
23:24:03 9447 8192000000 10210206800 10631233536 1325685840 13.928 100 16 32 1 1334823680 5280688 3857152
23:25:13 47112 8192000000 10241505488 10649608192 1335449680 14.016 100 44 88 0 1351657184 4939024 11268480
23:27:01 3205 8192000000 10221537200 10632626176 1317903280 13.858 99 32 62 1 1336050160 10568624 7578256
23:28:04 8801 8192000000 10210177776 10626699264 1308752096 13.775 99 17 34 0 1318431920 5748832 3930992
23:29:13 27276 8192000000 10194488320 10617090048 1271815264 13.438 96 24 48 0 1282936656 4761408 6359984
23:30:41 6612 8192000000 10143894576 10548903936 1246493824 13.206 99 32 64 1 1260488016 6789392 7204800
23:31:48 7102 8192000000 10153502544 10552086528 1253526416 13.271 100 23 46 0 1268923520 10467648 4929456
23:32:55 55169 8192000000 10150613136 10558947328 1252474176 13.261 99 30 60 1 1265575872 6085616 7016080
23:34:51 8316 8192000000 10165061632 10579288064 1281290928 13.525 100 22 43 1 1292693472 6077488 5325056
23:36:00 6766 8192000000 10195870000 10603364352 1296787984 13.666 100 24 49 0 1307773120 5214016 5771120
23:37:09 6576 8192000000 10200383472 10614583296 1305840352 13.748 100 20 40 2 1316420784 5558368 5022064
23:38:23 76835 8192000000 10234464128 10646437888 1322728448 13.901 100 49 98 1 1345282496 11416000 11138048
23:40:48 10140 8192000000 10259338112 10673299456 1351644960 14.162 100 54 108 1 1374476848 10470192 12361696
23:42:13 13848 8192000000 10288632992 10701582336 1370808224 14.334 100 67 134 0 1399995168 13809392 15377552
23:43:29 5518 8192000000 10287663344 10708066304 1376368400 14.384 100 43 84 1 1401792064 15209824 10213840
23:44:38 14260 8192000000 10299462144 10726641664 1387751056 14.486 100 49 98 1 1417617488 17985840 11880592
23:45:54 2918 8192000000 10313723408 10733219840 1390064736 14.506 100 53 104 1 1419217232 16894464 12258032
23:47:00 3921 8192000000 10345382160 10761404416 1408280080 14.669 100 74 149 2 1452607376 27091552 17235744
23:48:07 5101 8192000000 10356691648 10775302144 1419599680 14.769 100 65 130 0 1461591696 26243280 15748736
23:49:17 6086 8192000000 10337754432 10761216000 1406258240 14.651 100 50 100 0 1448564400 28610704 13695456
23:50:31 7672 8192000000 10344203648 10768281600 1411831344 14.700 100 52 104 3 1454782736 30173248 12778144
23:51:46 4216 8192000000 10343090880 10758754304 1399972320 14.595 100 81 162 0 1453467200 32795808 20699072
23:52:55 7261 8192000000 10338745584 10748981248 1385770240 14.468 100 69 138 1 1442911792 39273456 17868096
23:54:10 4518 8192000000 10339626704 10750115840 1387881536 14.487 100 71 141 0 1441101616 35560400 17659680
23:55:25 2542 8192000000 10339942560 10758262784 1380140400 14.418 99 73 143 0 1434297248 35967536 18189312
23:56:30 5698 8192000000 10295599664 10709524480 1345848016 14.110 98 47 95 1 1396629408 37848240 12933152
23:57:42 932 8192000000 10342108048 10748145664 1379828720 14.415 100 81 157 0 1438361952 38026352 20506880
23:58:47 6557 8192000000 10322665648 10733932544 1366010864 14.291 100 66 130 2 1421876256 38790592 17074800
23:59:57 4929 8192000000 10320735616 10735054848 1361557616 14.251 99 62 123 4 1419992784 41788080 16647088
00:01:13 9108 8192000000 10292205376 10716073984 1350748800 14.154 99 42 84 3 1390875520 28578144 11548576
00:02:31 15783 8192000000 10290059552 10722766848 1344155888 14.095 99 41 82 2 1394328464 38416464 11756112
00:03:48 27409 8192000000 10294243488 10728857600 1363978112 14.273 100 34 68 3 1403338448 29929552 9430784
00:05:46 36829 8192000000 10286397280 10730741760 1358055840 14.220 100 13 26 0 1397038208 35386144 3596224
00:07:24 77236 8192000000 10290936064 10711449600 1350130432 14.149 99 32 58 0 1368117584 11240448 6746704
00:09:47 46500 8192000000 10301805488 10737270784 1364488816 14.278 99 31 62 1 1388839776 17050816 7300144
00:11:42 57974 8192000000 10316693488 10768248832 1377816080 14.397 99 16 33 0 1402760992 21351536 3593376
00:13:55 66156 8192000000 10297934512 10754056192 1360319328 14.240 98 3 6 0 1376649648 15778192 552128
00:16:01 109035 8192000000 10308375776 10760925184 1388855296 14.496 100 10 20 0 1415717376 24447760 2414320
00:18:54 80677 8192000000 10321297040 10769563648 1394177984 14.543 100 34 68 0 1412312816 10458288 7676544
00:21:19 49100 8192000000 10304228048 10753486848 1367386656 14.304 98 30 60 2 1385413104 11384592 6641856
00:23:10 71524 8192000000 10301384336 10758561792 1386503376 14.475 99 12 24 0 1396948240 7719728 2725136
00:25:23 27099 8192000000 10304602384 10762018816 1391899136 14.523 100 15 30 1 1406852352 11448272 3504944
00:27:01 77621 8192000000 10311935376 10787835904 1390720272 14.512 99 35 68 2 1418030400 18617504 8692624
00:29:32 44582 8192000000 10293907232 10755698688 1370153856 14.328 98 12 24 1 1381108080 8214432 2739792
00:31:22 51718 8192000000 10308843328 10771931136 1399295584 14.589 100 11 22 0 1408750096 6668624 2785888
00:33:27 69156 8192000000 10300310304 10771939328 1399722704 14.593 100 6 12 2 1411453728 10248448 1482576
00:36:23 60212 8192000000 10297676240 10772766720 1400551456 14.600 100 8 16 0 1411493632 9011216 1930960
00:38:23 76717 8192000000 10294199248 10749378560 1310436352 13.790 94 7 14 0 1321891120 10419616 1035152
00:40:43 59330 8192000000 10295642208 10755641344 1361411872 14.250 99 22 44 1 1380251744 14087824 4752048
00:43:04 52448 8192000000 10282082624 10736979968 1322960096 13.904 96 25 50 1 1338028928 9287504 5781328
00:45:01 48314 8192000000 10290936320 10754408448 1355405440 14.196 98 29 58 1 1377970240 16092544 6472256
00:46:51 65633 8192000000 10269531984 10724618240 1338213664 14.041 98 19 38 0 1358209792 15310528 4685600
00:48:57 49015 8192000000 10288390960 10727497728 1327897168 13.948 97 21 42 0 1342652992 9809376 4946448
00:50:47 56762 8192000000 10290989168 10734665728 1339554496 14.053 98 27 54 1 1358540240 12881152 6104592
00:52:46 67474 8192000000 10272300208 10727165952 1356868128 14.209 99 34 68 2 1380047520 15560576 7618816
00:54:58 11655 8192000000 10261203168 10717351936 1358396496 14.223 100 27 54 0 1374950192 10688512 5865184
00:56:11 87579 8192000000 10266469488 10730106880 1361199216 14.248 100 11 22 0 1378579760 15218976 2161568
00:58:54 79573 8192000000 10281164112 10733486080 1333383904 13.998 97 22 44 2 1352958896 13519824 6055168
01:01:35 47146 8192000000 10258835120 10719772672 1340586176 14.063 99 3 6 0 1359530144 18334896 609072
01:03:24 44733 8192000000 10286357968 10734968832 1350710544 14.154 99 26 52 1 1370081536 13153984 6217008
01:05:15 47231 8192000000 10293725968 10742939648 1371763312 14.343 100 26 52 1 1391384944 13985408 5636224
01:07:03 93767 8192000000 10331921824 10774466560 1376506656 14.385 99 27 54 1 1400933712 18458656 5968400
01:09:44 58523 8192000000 10310282112 10760642560 1372562000 14.350 98 22 42 1 1386099248 8610640 4926608
01:11:43 31297 8192000000 10301326016 10754146304 1366171440 14.293 98 23 46 0 1377366704 6816464 4378800
01:13:30 69331 8192000000 10310406992 10758963200 1388446992 14.492 99 16 32 3 1402482816 10457888 3577936
01:15:43 77483 8192000000 10317358096 10776199168 1403380352 14.625 100 35 69 1 1424188960 13019664 7788944
01:18:02 55826 8192000000 10304153808 10772340736 1396095072 14.560 99 19 38 0 1409786848 8612032 5079744
01:21:29 71701 8192000000 10269368720 10731651072 1323211584 13.906 95 7 14 0 1333446288 8588512 1646192
01:23:43 69791 8192000000 10287536688 10751488000 1357781536 14.217 98 19 38 0 1371464720 8899280 4783904
01:25:53 68613 8192000000 10286906816 10755395584 1344418592 14.097 97 20 40 1 1363023408 13938240 4666576
01:28:05 52291 8192000000 10258607632 10706849792 1293290528 13.634 94 20 40 0 1311542304 13906848 4344928
01:29:58 47743 8192000000 10273511872 10720542720 1316739712 13.847 96 16 32 0 1331823312 11690080 3393520
01:31:46 55888 8192000000 10278075792 10731700224 1336934000 14.030 98 26 51 2 1358434704 15087104 6413600
01:33:45 94350 8192000000 10259829648 10720370688 1293737168 13.638 95 17 34 1 1313929264 16671712 3520384
01:36:25 126890 8192000000 9123993536 9369313280 284878272 3.360 21 2 3 0 291036944 5800320 358352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2021 01:40:54 to 24-12-2021 01:41:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 192 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2021 01:41:10 to 24-12-2021 01:41:12.

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          245369
MON$OLDEST_ACTIVE               245370
MON$OLDEST_SNAPSHOT             245370
MON$NEXT_TRANSACTION            245370
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                       2046048
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Dec 24 01:38:56 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

24.12.21 01:41:13. 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
2021-12-24 01:41:15 No severe PSQL-related problems occured
24.12.21 01:41:16. End of report.