Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2022 19: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 14854316 kB
MemAvailable 30044924 kB
Buffers 0 kB
Cached 16833480 kB
SwapCached 3412 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-12-19 19:38:32
DTS_END                         2022-12-19 19:38:32.7630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42074
TRN_ID                          241106
ATT_ID                          612
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: 9601.14 20374 1152137
customer order: creation 175.41 327 21049
customer order: refuse 15.98 330 1917
order to supplier: creation 46.37 1174 5564
order to supplier: removal 3.45 89371 414
invoice (draft): creation 47.67 2928 5720
invoice (draft): removal 55.38 653 6646
invoice accept: apply 42.89 15676 5147
invoice accept: cancel 50.18 682 6021
customer reserve: creation 3968.94 154 476273
customer reserve: removal 4884.58 49 586150
realization accept: apply 84.93 176 10192
realization accept: cancel 92.90 105 11148
payment from customer: creation 60.43 130 7251
payment from customer: removal 5.63 103 675
payment to supplier: creation 50.67 132 6080
payment to supplier: removal 3.33 90 400
service: total inventory turnovers 4.96 1094 595
service: total monetary turnovers 6.55 27 786
service: aggregate perf. data 0.88 21835 105
service: refresh index statistics 0.03 272453 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-12-2022 19:39:38 to 19-12-2022 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2374
TEST_TIME 2 3385
TEST_TIME 3 3410
TEST_TIME 4 4446
TEST_TIME 5 4328
TEST_TIME 6 4915
TEST_TIME 7 5153
TEST_TIME 8 5267
TEST_TIME 9 5366
TEST_TIME 10 5612
TEST_TIME 11 5571
TEST_TIME 12 5873
TEST_TIME 13 6098
TEST_TIME 14 6616
TEST_TIME 15 6701
TEST_TIME 16 6827
TEST_TIME 17 7419
TEST_TIME 18 7493
TEST_TIME 19 8055
TEST_TIME 20 8084
TEST_TIME 21 8312
TEST_TIME 22 8783
TEST_TIME 23 8772
TEST_TIME 24 9007
TEST_TIME 25 9285
TEST_TIME 26 9861
TEST_TIME 27 10600
TEST_TIME 28 10704
TEST_TIME 29 10457
TEST_TIME 30 10503
TEST_TIME 31 10495
TEST_TIME 32 10454
TEST_TIME 33 10488
TEST_TIME 34 10379
TEST_TIME 35 10384
TEST_TIME 36 10509
TEST_TIME 37 10416
TEST_TIME 38 10430
TEST_TIME 39 10410
TEST_TIME 40 10435
TEST_TIME 41 10271
TEST_TIME 42 10066
TEST_TIME 43 9975
TEST_TIME 44 10083
TEST_TIME 45 9948
TEST_TIME 46 9912
TEST_TIME 47 9955
TEST_TIME 48 9939
TEST_TIME 49 9903
TEST_TIME 50 9891
TEST_TIME 51 9772
TEST_TIME 52 9873
TEST_TIME 53 9762
TEST_TIME 54 9676
TEST_TIME 55 9713
TEST_TIME 56 9649
TEST_TIME 57 9687
TEST_TIME 58 9670
TEST_TIME 59 9710
TEST_TIME 60 9697
TEST_TIME 61 9709
TEST_TIME 62 9870
TEST_TIME 63 9890
TEST_TIME 64 9888
TEST_TIME 65 9906
TEST_TIME 66 9897
TEST_TIME 67 9997
TEST_TIME 68 10004
TEST_TIME 69 9970
TEST_TIME 70 10060
TEST_TIME 71 10068
TEST_TIME 72 9951
TEST_TIME 73 9905
TEST_TIME 74 9812
TEST_TIME 75 9808
TEST_TIME 76 9801
TEST_TIME 77 9774
TEST_TIME 78 9691
TEST_TIME 79 9718
TEST_TIME 80 9695
TEST_TIME 81 9670
TEST_TIME 82 9634
TEST_TIME 83 9633
TEST_TIME 84 9637
TEST_TIME 85 9630
TEST_TIME 86 9633
TEST_TIME 87 9644
TEST_TIME 88 9594
TEST_TIME 89 9635
TEST_TIME 90 9719
TEST_TIME 91 9739
TEST_TIME 92 9762
TEST_TIME 93 9782
TEST_TIME 94 9807
TEST_TIME 95 9795
TEST_TIME 96 9792
TEST_TIME 97 9885
TEST_TIME 98 9888
TEST_TIME 99 9809
TEST_TIME 100 9805
TEST_TIME 101 9750
TEST_TIME 102 9712
TEST_TIME 103 9672
TEST_TIME 104 9700
TEST_TIME 105 9647
TEST_TIME 106 9638
TEST_TIME 107 9618
TEST_TIME 108 9593
TEST_TIME 109 9587
TEST_TIME 110 9521
TEST_TIME 111 9497
TEST_TIME 112 9545
TEST_TIME 113 9573
TEST_TIME 114 9539
TEST_TIME 115 9505
TEST_TIME 116 9540
TEST_TIME 117 9640
TEST_TIME 118 9633
TEST_TIME 119 9598
TEST_TIME 120 9603

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-12-2022 19:39:42 to 19-12-2022 19:39:46.

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 1593647 1593647 0 0.00 0 25785 6 0 0
doc_list_biud 1593647 1593647 0 0.00 0 28854 23 0 0
sp_add_invoice_to_stock 5261 5147 114 2.16 35 187152 15676 0 114
sp_cancel_adding_invoice 6021 6021 0 0.00 4 50489 683 0 0
sp_cancel_client_order 1917 1917 0 0.00 4 9589 331 0 0
sp_cancel_customer_reserve 586150 586150 0 0.00 0 28868 49 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 7763 103 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 9620 91 0 0
sp_cancel_supplier_invoice 6653 6646 7 0.10 1 15882 654 0 7
sp_cancel_supplier_order 414 414 0 0.00 9 1183929 89371 0 0
sp_cancel_write_off 11148 11148 0 0.00 1 27411 105 0 0
sp_client_order 21049 21049 0 0.00 3 25992 327 0 0
sp_customer_reserve 476273 476273 0 0.00 0 36795 154 0 0
sp_fill_shopping_cart 32328 32328 0 0.00 1 16438 38 0 0
sp_fill_shopping_cart_clo_res 476266 476266 0 0.00 0 13696 11 0 0
sp_get_clo_for_invoice 5139 5139 0 0.00 0 23096 275 0 0
sp_kill_cost_storno 18244 18244 0 0.00 0 8884 46 0 0
sp_kill_qstorno_ret_qs2qd 1190508 1190508 0 0.00 0 28851 27 0 0
sp_kill_qty_storno 627627 627627 0 0.00 0 28854 59 0 0
sp_lock_selected_doc 21259 21259 0 0.00 0 9617 6 0 0
sp_make_cost_storno 28662 28662 0 0.00 0 24395 56 0 0
sp_multiply_rows_for_pdistr 28662 28662 0 0.00 0 14461 28 0 0
sp_multiply_rows_for_qdistr 21049 21049 0 0.00 0 16404 140 0 0
sp_pay_from_customer 7251 7251 0 0.00 4 25779 130 0 0
sp_pay_to_supplier 6080 6080 0 0.00 4 16901 133 0 0
sp_payment_common 13331 13331 0 0.00 1 25776 37 0 0
sp_qd_handle_on_cancel_clo 1917 1917 0 0.00 0 9551 291 0 0
sp_qd_handle_on_invoice_upd_sts 11160 11160 0 0.00 0 19146 301 0 0
sp_qd_handle_on_reserve_upd_sts 10192 10192 0 0.00 0 19075 57 0 0
sp_reserve_write_off 10192 10192 0 0.00 6 25885 176 0 0
sp_supplier_invoice 5720 5720 0 0.00 6 67489 2928 0 0
sp_supplier_order 5564 5564 0 0.00 4 28297 1174 0 0
srv_aggregate_perf_data 105 105 0 0.00 2202 215277 21835 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1414 142780 46615 0 0
srv_find_qd_qs_mism 1103439 1103439 0 0.00 0 16388 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 21 46103 1095 0 0
srv_make_money_saldo 786 786 0 0.00 1 2513 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 212275 402935 272453 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5147 5147 0 0.00 0 19513 164 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 5458 94 0 0
t$perf-norm:sp_cancel_client_order 1917 1917 0 0.00 0 656 7 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 5847 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 4228 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1903 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 672 15 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 27261 1537 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1731 7 0 0
t$perf-norm:sp_client_order 21049 21049 0 0.00 0 16116 20 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 2936 7 0 0
t$perf-norm:sp_pay_from_customer 7251 7251 0 0.00 0 5863 11 0 0
t$perf-norm:sp_pay_to_supplier 6080 6080 0 0.00 0 4987 10 0 0
t$perf-norm:sp_reserve_write_off 10192 10192 0 0.00 0 16097 16 0 0
t$perf-norm:sp_supplier_invoice 5720 5720 0 0.00 0 15034 23 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 8840 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 237 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2058 37 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 715 12 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 2143 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10502 10274 228 2.17 0 7978 20 0 228
v_all_customers 38432 38432 0 0.00 0 20 0 0 0
v_all_suppliers 31476 31476 0 0.00 0 51 1 0 0
v_all_wares 42096 42096 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 3435 20 0 0
v_cancel_client_order 3834 3834 0 0.00 0 7127 8 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 7738 20 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 3335 8 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 933 3 0 0
v_cancel_supplier_invoice 874 860 14 1.60 0 5447 32 0 14
v_cancel_supplier_order 828 828 0 0.00 0 913 6 0 0
v_cancel_write_off 2006 2006 0 0.00 0 1752 14 0 0
v_max_id_clo_ord 5563 5563 0 0.00 0 335 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 3997 12 0 0
v_max_id_ord_sup 5717 5717 0 0.00 0 5110 5 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 350 2 0 0
v_max_non_paid_realizn 3834 3834 0 0.00 0 3881 6 0 0
v_min_id_clo_ord 5563 5563 0 0.00 0 16380 15 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 3983 16 0 0
v_min_id_ord_sup 5717 5717 0 0.00 0 7800 7 0 0
v_min_non_paid_invoice 6080 6080 0 0.00 0 13477 28 0 0
v_min_non_paid_realizn 7251 7251 0 0.00 0 25772 30 0 0
v_our_firm 10498 10498 0 0.00 0 28 1 0 0
v_reserve_write_off 20384 20384 0 0.00 0 7943 8 0 0
x_lock_depdocs_on_canc_invoice 12237 12237 0 0.00 0 19810 100 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 12659 356 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 1 28205 1100 0 0
x_make_qstorno_1200_2000 5720 5720 0 0.00 2 67482 2787 0 0
x_make_qstorno_2100_3300 476273 476273 0 0.00 0 36793 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2022 19:39:47 to 19-12-2022 19:39:51.

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
17:08:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:37 29703 8192000000 9923070304 10238410752 996016192 10.840 100 85 170 0 1025079968 6194576 22869200
17:11:09 23359 8192000000 9972178448 10300162048 1051916032 11.379 100 88 176 0 1083204304 8809008 22479264
17:12:48 13551 8192000000 9997255680 10334253056 1082744800 11.674 100 88 175 1 1114712784 10126064 21841920
17:14:09 6941 8192000000 10040005856 10388606976 1125253088 12.077 100 80 160 0 1154908144 10485648 19169408
17:15:20 9816 8192000000 10079862416 10445598720 1171878624 12.514 100 67 134 1 1199542944 11598448 16065872
17:16:39 2613 8192000000 10127307296 10500128768 1218471008 12.948 100 83 166 0 1249373024 12029328 18872688
17:17:44 2235 8192000000 10159516880 10536370176 1249814800 13.237 100 71 141 0 1277926560 12779888 15331872
17:18:48 1505 8192000000 10188837408 10570776576 1271979296 13.440 100 86 170 0 1304135200 13731584 18424320
17:19:53 1576 8192000000 10211327344 10594365440 1293687472 13.638 100 84 167 0 1325156496 13346400 18122624
17:20:56 8995 8192000000 10201267312 10600366080 1299483552 13.691 100 66 130 2 1324824352 10280592 15060208
17:22:09 11460 8192000000 10201931168 10608652288 1305710928 13.747 100 34 68 1 1322114896 8892720 7511248
17:23:22 5403 8192000000 10192865936 10609844224 1315888608 13.839 100 23 44 0 1327547632 7053408 4605616
17:24:29 8765 8192000000 10207472528 10623528960 1324858976 13.921 100 23 46 0 1335881872 5791168 5231728
17:25:46 70330 8192000000 10225641520 10634256384 1326801984 13.938 100 40 80 1 1341664176 5273536 9588656
17:27:57 3761 8192000000 10226556608 10648657920 1334185328 14.005 100 31 59 2 1347585536 6694128 6706080
17:29:02 12031 8192000000 10215894816 10628632576 1302090784 13.714 98 13 26 0 1310349840 5191728 3067328
17:30:18 19738 8192000000 10161286768 10573836288 1226979232 13.026 94 20 40 0 1242241168 10182176 5079760
17:32:09 5283 8192000000 10109500096 10501304320 1192867360 12.710 95 38 76 1 1207427792 5834976 8725456
17:33:17 8135 8192000000 10133984848 10528509952 1210047280 12.870 97 28 56 0 1223083024 6689360 6346384
17:34:25 6252 8192000000 10156513296 10560364544 1242151152 13.166 98 19 38 0 1251839088 5281600 4406336
17:35:35 31989 8192000000 10179319632 10578448384 1277122560 13.487 100 45 91 0 1294094960 6514224 10458176
17:37:07 6421 8192000000 10194296656 10602381312 1299134336 13.687 100 33 64 0 1313112496 6727632 7250528
17:38:16 8199 8192000000 10210593840 10629439488 1321025696 13.886 100 27 55 0 1336393648 9209248 6158704
17:39:31 101592 8192000000 10234345856 10658013184 1336513104 14.026 100 34 68 2 1351400192 7285520 7601568
17:42:20 8555 8192000000 10264801264 10688278528 1364457024 14.277 100 42 84 0 1385396224 11312400 9626800
17:43:34 30501 8192000000 10292534128 10716606464 1378190304 14.400 100 38 76 0 1406403952 19542048 8671600
17:45:07 2044 8192000000 10315324896 10733678592 1393565648 14.538 100 70 136 0 1429485376 20430848 15488880
17:46:18 4426 8192000000 10320105008 10740822016 1399469824 14.590 100 52 104 2 1432265632 20615440 12180368
17:47:26 12321 8192000000 10321627264 10748571648 1396538160 14.564 99 48 96 2 1429844368 21020512 12285696
17:48:47 1880 8192000000 10338931408 10763796480 1415909488 14.736 100 56 110 1 1452152944 23090752 13152704
17:49:53 4449 8192000000 10364110160 10786578432 1431300032 14.873 100 58 113 0 1476623968 30896032 14427904
17:51:06 2832 8192000000 10355680064 10777206784 1411002064 14.693 99 62 124 0 1458205616 32343104 14860448
17:52:18 3846 8192000000 10351211344 10771333120 1407775680 14.664 100 70 139 2 1461420272 37047280 16597312
17:53:29 1413 8192000000 10318152768 10730487808 1376684112 14.387 99 77 154 0 1427257792 31701920 18871760
17:54:36 5701 8192000000 10310726960 10727362560 1358111376 14.220 99 58 115 1 1409746464 36717600 14917488
17:55:50 2880 8192000000 10332284816 10748522496 1384701760 14.459 100 78 153 0 1447206240 42783376 19721104
17:56:58 2640 8192000000 10348993664 10754973696 1384051088 14.453 100 83 166 1 1450244432 45514128 20679216
17:58:03 5114 8192000000 10345702192 10758434816 1393434544 14.536 100 62 124 0 1447561056 38835184 15291328
17:59:10 9337 8192000000 10330481584 10751197184 1373504592 14.358 99 42 85 0 1425999120 41215888 11278640
18:00:25 14104 8192000000 10331618048 10763124736 1381666256 14.431 100 35 70 0 1437915856 46736544 9513056
18:01:42 16149 8192000000 10309329552 10735988736 1365267328 14.285 100 34 68 0 1420473072 45893344 9312400
18:03:00 17704 8192000000 10295098112 10728726528 1351256528 14.159 99 28 57 1 1409227280 49720064 8250688
18:04:22 22042 8192000000 10301334128 10732752896 1354108208 14.184 100 32 63 0 1410576656 48033008 8435440
18:05:45 69466 8192000000 10255410736 10704826368 1324218240 13.915 98 6 12 0 1339711328 14254448 1238640
18:08:03 62049 8192000000 10282226128 10727018496 1339201696 14.050 98 13 26 0 1361830224 19665952 2962576
18:10:15 77907 8192000000 10273923680 10720903168 1351217920 14.158 99 14 26 0 1371356256 17614960 2523376
18:12:34 80617 8192000000 10308114080 10762608640 1385733488 14.468 100 6 9 0 1403847424 17340432 773504
18:14:56 142779 8192000000 10316607808 10773684224 1381515360 14.430 99 8 16 0 1400099696 17021232 1563104
18:18:24 74098 8192000000 10310035504 10774462464 1383928320 14.452 99 31 57 1 1404749456 13600048 7221088
18:21:16 73235 8192000000 10299294272 10755399680 1366167152 14.293 97 40 78 0 1389537664 14522688 8847824
18:23:33 18311 8192000000 10292205744 10758533120 1391232544 14.517 100 8 16 0 1402136352 8991856 1911952
18:25:07 21894 8192000000 10330640768 10782838784 1409019296 14.675 100 44 87 1 1429012864 10668928 9324640
18:26:41 28810 8192000000 10324876672 10786295808 1414384576 14.723 100 22 44 0 1428495808 8723872 5387360
18:28:13 55461 8192000000 10305138816 10775744512 1395525664 14.555 99 10 20 0 1412040304 13326864 3187776
18:30:39 32312 8192000000 10305448304 10774245376 1387854224 14.487 99 15 31 0 1409933248 17868336 4210688
18:32:13 94179 8192000000 10284990000 10756644864 1353162000 14.176 97 19 38 0 1369765248 12372288 4230960
18:35:05 58465 8192000000 10292683632 10760933376 1391456208 14.519 100 7 14 0 1402789312 9852096 1481008
18:37:04 45801 8192000000 10284682336 10747445248 1343420144 14.088 96 19 37 0 1354667344 7127424 4119776
18:38:51 55158 8192000000 10294474800 10756214784 1357203744 14.212 97 18 36 0 1375767792 14411856 4152192
18:40:53 55635 8192000000 10279858656 10737913856 1350980304 14.156 98 12 24 0 1362213648 9170320 2063024
18:42:49 58143 8192000000 10294845296 10728960000 1346224224 14.113 98 24 48 0 1361696416 10423264 5048928
18:44:50 54177 8192000000 10287012496 10741002240 1335932048 14.021 97 11 22 0 1353139344 14556288 2651008
18:46:46 46725 8192000000 10277655040 10719100928 1324788032 13.920 97 30 60 0 1339638304 8522208 6328064
18:48:43 35535 8192000000 10267118400 10713370624 1308358240 13.771 97 26 52 0 1328342944 14282688 5702016
18:50:20 103511 8192000000 10262380480 10707046400 1322542080 13.900 98 19 38 0 1334925936 7914432 4469424
18:54:17 57019 8192000000 10239954128 10701529088 1314089088 13.823 97 9 18 0 1328352832 12102640 2161104
18:56:14 60662 8192000000 10255075888 10707623936 1330551552 13.972 98 15 31 0 1347457968 13199088 3707328
18:58:42 65680 8192000000 10262634256 10709147648 1343827552 14.092 99 11 22 0 1360050240 13813328 2409360
19:00:50 72013 8192000000 10271076048 10734432256 1365000256 14.282 100 12 22 2 1377533792 9993328 2540208
19:03:05 37839 8192000000 10289578256 10742181888 1373052784 14.354 100 25 48 0 1386925488 8186416 5686288
19:04:46 61943 8192000000 10284942784 10741190656 1360704864 14.244 98 21 42 0 1374979904 9247744 5027296
19:06:52 62920 8192000000 10303323056 10769371136 1388378000 14.491 100 29 56 1 1413627536 18713840 6535696
19:08:57 44051 8192000000 10308538720 10767536128 1395580144 14.556 100 18 36 0 1412336656 12865952 3890560
19:10:41 111919 8192000000 10309952384 10781151232 1360170016 14.239 97 6 12 0 1376218848 14900736 1148096
19:13:36 26065 8192000000 10298427792 10739982336 1373120592 14.355 98 55 106 1 1395316064 10476592 11718880
19:15:05 95132 8192000000 10298713440 10767773696 1355551072 14.197 97 13 26 0 1370837616 12547232 2739312
19:18:38 59621 8192000000 10280991760 10746142720 1352524256 14.170 98 15 30 1 1368010432 12535200 2950976
19:20:40 52776 8192000000 10279492016 10746302464 1367101232 14.301 99 9 17 1 1382288496 13429104 1758160
19:23:53 102638 8192000000 10276180096 10739392512 1367495888 14.305 99 14 28 0 1379988432 8932848 3559696
19:26:47 62938 8192000000 10284879600 10751819776 1374905632 14.371 99 16 32 0 1385565888 7138960 3521296
19:28:53 104463 8192000000 10277107728 10745544704 1315603632 13.837 95 6 12 0 1331818432 15140720 1074080
19:31:43 41695 8192000000 10275231280 10731069440 1322495920 13.899 96 14 28 0 1333440944 7948288 2996736
19:33:34 34464 8192000000 10300257712 10747383808 1362268016 14.258 99 44 88 0 1395653264 23537072 9848176
19:35:13 105679 8192000000 10283110752 10723913728 1288545024 13.591 94 17 34 0 1309126320 16804976 3776320
19:37:59 16776 8192000000 10272461664 10705117184 1325955408 13.931 98 43 86 0 1355119040 20199296 8964336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2022 19:39:53 to 19-12-2022 19:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2022 19:40:09 to 19-12-2022 19:40:11.

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          239484
MON$OLDEST_ACTIVE               242799
MON$OLDEST_SNAPSHOT             242799
MON$NEXT_TRANSACTION            242799
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                       2041632
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:877: 
:878: mscs-test-161.budzdorov.ru    Mon Dec 19 19:38:52 2022
:879:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:880: 
:881: 
:882: mscs-test-161.budzdorov.ru    Mon Dec 19 19:38:52 2022
:883:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:884: 
:885: 
:886: mscs-test-161.budzdorov.ru    Mon Dec 19 19:38:52 2022
:887:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:888: 
:889: 
:890: mscs-test-161.budzdorov.ru    Mon Dec 19 19:38:52 2022
:891:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:892: 
:893: 
:894: mscs-test-161.budzdorov.ru    Mon Dec 19 19:38:52 2022
:895:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:896: 
--- end of diff output ---

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

19.12.22 19:40:12. 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-12-19 19:40:15 No severe PSQL-related problems occured
19.12.22 19:40:16. End of report.