Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 15030900 kB
MemAvailable 30062536 kB
Buffers 0 kB
Cached 16660224 kB
SwapCached 3688 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-16 19:38:31
DTS_END                         2022-12-16 19:38:31.8750
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/54446
TRN_ID                          244685
ATT_ID                          707
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: 9515.60 19152 1141872
customer order: creation 178.83 323 21460
customer order: refuse 15.18 355 1822
order to supplier: creation 47.53 1250 5703
order to supplier: removal 3.56 89022 427
invoice (draft): creation 47.13 3500 5656
invoice (draft): removal 52.53 789 6303
invoice accept: apply 43.64 17014 5237
invoice accept: cancel 47.78 801 5733
customer reserve: creation 4034.08 169 484089
customer reserve: removal 4742.19 51 569063
realization accept: apply 85.90 183 10308
realization accept: cancel 80.80 105 9696
payment from customer: creation 62.57 110 7508
payment from customer: removal 6.42 77 770
payment to supplier: creation 50.91 136 6109
payment to supplier: removal 3.48 98 417
service: total inventory turnovers 4.95 1083 594
service: total monetary turnovers 7.10 32 852
service: aggregate perf. data 1.01 16207 121
service: refresh index statistics 0.03 251741 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-12-2022 19:39:22 to 16-12-2022 19:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5355
TEST_TIME 2 3495
TEST_TIME 3 5592
TEST_TIME 4 5237
TEST_TIME 5 5256
TEST_TIME 6 5299
TEST_TIME 7 5266
TEST_TIME 8 5447
TEST_TIME 9 5327
TEST_TIME 10 5411
TEST_TIME 11 5685
TEST_TIME 12 5695
TEST_TIME 13 5777
TEST_TIME 14 6145
TEST_TIME 15 6083
TEST_TIME 16 6204
TEST_TIME 17 6652
TEST_TIME 18 6837
TEST_TIME 19 6953
TEST_TIME 20 7497
TEST_TIME 21 7958
TEST_TIME 22 7991
TEST_TIME 23 8424
TEST_TIME 24 8375
TEST_TIME 25 9530
TEST_TIME 26 9719
TEST_TIME 27 9683
TEST_TIME 28 9766
TEST_TIME 29 9638
TEST_TIME 30 10105
TEST_TIME 31 10169
TEST_TIME 32 10185
TEST_TIME 33 10100
TEST_TIME 34 10055
TEST_TIME 35 10016
TEST_TIME 36 9906
TEST_TIME 37 10165
TEST_TIME 38 10169
TEST_TIME 39 10231
TEST_TIME 40 10194
TEST_TIME 41 10233
TEST_TIME 42 10058
TEST_TIME 43 9882
TEST_TIME 44 9874
TEST_TIME 45 9829
TEST_TIME 46 9738
TEST_TIME 47 9680
TEST_TIME 48 9761
TEST_TIME 49 9723
TEST_TIME 50 9665
TEST_TIME 51 9558
TEST_TIME 52 9622
TEST_TIME 53 9608
TEST_TIME 54 9493
TEST_TIME 55 9545
TEST_TIME 56 9515
TEST_TIME 57 9575
TEST_TIME 58 9554
TEST_TIME 59 9502
TEST_TIME 60 9583
TEST_TIME 61 9605
TEST_TIME 62 9681
TEST_TIME 63 9646
TEST_TIME 64 9684
TEST_TIME 65 9782
TEST_TIME 66 9737
TEST_TIME 67 9795
TEST_TIME 68 9808
TEST_TIME 69 9789
TEST_TIME 70 9792
TEST_TIME 71 9851
TEST_TIME 72 9970
TEST_TIME 73 9876
TEST_TIME 74 9782
TEST_TIME 75 9768
TEST_TIME 76 9714
TEST_TIME 77 9713
TEST_TIME 78 9688
TEST_TIME 79 9667
TEST_TIME 80 9686
TEST_TIME 81 9633
TEST_TIME 82 9553
TEST_TIME 83 9558
TEST_TIME 84 9508
TEST_TIME 85 9560
TEST_TIME 86 9527
TEST_TIME 87 9552
TEST_TIME 88 9539
TEST_TIME 89 9536
TEST_TIME 90 9496
TEST_TIME 91 9527
TEST_TIME 92 9583
TEST_TIME 93 9639
TEST_TIME 94 9605
TEST_TIME 95 9624
TEST_TIME 96 9615
TEST_TIME 97 9667
TEST_TIME 98 9647
TEST_TIME 99 9651
TEST_TIME 100 9695
TEST_TIME 101 9651
TEST_TIME 102 9580
TEST_TIME 103 9603
TEST_TIME 104 9541
TEST_TIME 105 9527
TEST_TIME 106 9521
TEST_TIME 107 9533
TEST_TIME 108 9504
TEST_TIME 109 9447
TEST_TIME 110 9432
TEST_TIME 111 9447
TEST_TIME 112 9462
TEST_TIME 113 9466
TEST_TIME 114 9481
TEST_TIME 115 9508
TEST_TIME 116 9504
TEST_TIME 117 9528
TEST_TIME 118 9498
TEST_TIME 119 9527
TEST_TIME 120 9517

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2022 19:39:26 to 16-12-2022 19:39:29.

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 1589169 1589169 0 0.00 0 33957 6 0 0
doc_list_biud 1589169 1589169 0 0.00 0 71523 24 0 0
sp_add_invoice_to_stock 5377 5237 140 2.60 46 184905 17015 0 140
sp_cancel_adding_invoice 5733 5733 0 0.00 4 86846 801 0 0
sp_cancel_client_order 1822 1822 0 0.00 5 9082 356 0 0
sp_cancel_customer_reserve 569063 569063 0 0.00 0 71525 52 0 0
sp_cancel_pay_from_customer 770 770 0 0.00 3 3727 78 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 4518 99 0 0
sp_cancel_supplier_invoice 6315 6303 12 0.19 1 16935 789 0 12
sp_cancel_supplier_order 427 427 0 0.00 12 1015404 89022 0 0
sp_cancel_write_off 9696 9696 0 0.00 1 12387 105 0 0
sp_client_order 21460 21460 0 0.00 3 70559 324 0 0
sp_customer_reserve 484089 484089 0 0.00 0 94764 169 0 0
sp_fill_shopping_cart 32819 32819 0 0.00 1 13591 37 0 0
sp_fill_shopping_cart_clo_res 484083 484083 0 0.00 0 23242 13 0 0
sp_get_clo_for_invoice 5231 5231 0 0.00 0 34472 265 0 0
sp_kill_cost_storno 16616 16616 0 0.00 0 18648 53 0 0
sp_kill_qstorno_ret_qs2qd 1154552 1154552 0 0.00 0 71511 28 0 0
sp_kill_qty_storno 608583 608583 0 0.00 0 71523 62 0 0
sp_lock_selected_doc 21601 21601 0 0.00 0 8065 4 0 0
sp_make_cost_storno 29156 29156 0 0.00 0 33945 55 0 0
sp_multiply_rows_for_pdistr 29156 29156 0 0.00 0 7925 25 0 0
sp_multiply_rows_for_qdistr 21460 21460 0 0.00 0 70551 147 0 0
sp_pay_from_customer 7508 7508 0 0.00 4 11605 110 0 0
sp_pay_to_supplier 6109 6109 0 0.00 4 35222 137 0 0
sp_payment_common 13617 13617 0 0.00 1 8063 29 0 0
sp_qd_handle_on_cancel_clo 1822 1822 0 0.00 0 9076 324 0 0
sp_qd_handle_on_invoice_upd_sts 10964 10964 0 0.00 0 22741 330 0 0
sp_qd_handle_on_reserve_upd_sts 10308 10308 0 0.00 0 21268 61 0 0
sp_reserve_write_off 10308 10308 0 0.00 6 21286 183 0 0
sp_supplier_invoice 5656 5656 0 0.00 5 114139 3500 0 0
sp_supplier_order 5703 5703 0 0.00 5 23246 1251 0 0
srv_aggregate_perf_data 121 121 0 0.00 2683 153735 16208 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1683 150805 42354 0 0
srv_find_qd_qs_mism 1091475 1091475 0 0.00 0 16579 6 0 0
srv_make_invnt_saldo 594 594 0 0.00 31 49903 1083 0 0
srv_make_money_saldo 852 852 0 0.00 1 3653 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 94420 399122 251742 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5237 5237 0 0.00 0 10466 160 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 2912 107 0 0
t$perf-norm:sp_cancel_client_order 1822 1822 0 0.00 0 3907 13 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 1085 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 770 770 0 0.00 0 2604 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 1023 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 1991 28 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 23315 1416 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 762 4 0 0
t$perf-norm:sp_client_order 21460 21460 0 0.00 0 8097 17 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 2707 12 0 0
t$perf-norm:sp_pay_from_customer 7508 7508 0 0.00 0 6067 9 0 0
t$perf-norm:sp_pay_to_supplier 6109 6109 0 0.00 0 16713 14 0 0
t$perf-norm:sp_reserve_write_off 10308 10308 0 0.00 0 10707 12 0 0
t$perf-norm:sp_supplier_invoice 5656 5656 0 0.00 0 8523 15 0 0
t$perf-norm:sp_supplier_order 5703 5703 0 0.00 0 8610 14 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 1287 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 727 17 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 6052 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10740 10460 280 2.60 0 5657 17 0 280
v_all_customers 39034 39034 0 0.00 0 25 1 0 0
v_all_suppliers 31720 31720 0 0.00 0 24 1 0 0
v_all_wares 42920 42920 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1621 13 0 0
v_cancel_client_order 3644 3644 0 0.00 0 3863 8 0 0
v_cancel_customer_prepayment 1540 1540 0 0.00 0 2766 10 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 3311 5 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 3820 19 0 0
v_cancel_supplier_invoice 862 838 24 2.78 0 3397 17 0 24
v_cancel_supplier_order 854 854 0 0.00 0 3429 7 0 0
v_cancel_write_off 2130 2130 0 0.00 0 5248 19 0 0
v_max_id_clo_ord 5703 5703 0 0.00 0 208 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2392 12 0 0
v_max_id_ord_sup 5656 5656 0 0.00 0 7672 5 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 7264 7 0 0
v_max_non_paid_realizn 4101 4101 0 0.00 0 4725 7 0 0
v_min_id_clo_ord 5703 5703 0 0.00 0 4745 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 7454 15 0 0
v_min_id_ord_sup 5656 5656 0 0.00 0 4031 6 0 0
v_min_non_paid_invoice 6109 6109 0 0.00 0 7732 20 0 0
v_min_non_paid_realizn 7508 7508 0 0.00 0 7453 21 0 0
v_our_firm 10700 10700 0 0.00 0 20 1 0 0
v_reserve_write_off 20616 20616 0 0.00 0 5646 7 0 0
x_lock_depdocs_on_canc_invoice 11617 11617 0 0.00 0 23095 109 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 7946 296 0 0
x_make_qstorno_1000_1200 5703 5703 0 0.00 1 22711 1173 0 0
x_make_qstorno_1200_2000 5656 5656 0 0.00 2 114119 3365 0 0
x_make_qstorno_2100_3300 484089 484089 0 0.00 0 94763 153 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-12-2022 19:39:30 to 16-12-2022 19:39:35.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 29716 8192000000 9919703184 10231664640 995195232 10.832 100 95 190 0 1026463872 6492368 24776272
17:11:10 18072 8192000000 9955111888 10276544512 1034719632 11.214 100 91 182 0 1066928848 8957600 23251616
17:12:33 10746 8192000000 9979431360 10312572928 1068480848 11.538 100 87 174 0 1100014560 10149904 21383808
17:13:55 9121 8192000000 10037828080 10385539072 1124301088 12.068 100 79 157 0 1154294976 11828688 18165200
17:15:12 6163 8192000000 10076725168 10433904640 1160999376 12.413 100 78 156 0 1192168608 13187280 17981952
17:16:21 3334 8192000000 10118434656 10486714368 1207286192 12.844 100 76 148 1 1234608544 10968016 16354336
17:17:27 1602 8192000000 10151208416 10522578944 1238993824 13.137 100 90 179 4 1272873472 13808816 20070832
17:18:31 1090 8192000000 10180732928 10557722624 1268260880 13.406 100 89 176 1 1301501680 14135040 19105760
17:19:34 1073 8192000000 10211488608 10598506496 1302448000 13.717 100 85 170 0 1334389472 13025008 18916464
17:20:36 2848 8192000000 10205221360 10604552192 1308285296 13.771 100 58 107 2 1329523680 8507136 12731248
17:21:39 3253 8192000000 10208432256 10619076608 1317102528 13.850 100 36 70 1 1332048864 7301024 7645312
17:22:44 9253 8192000000 10204567040 10623074304 1320484304 13.881 100 17 31 0 1329461408 5735136 3241968
17:23:57 12093 8192000000 10209706400 10636566528 1333959744 14.003 100 23 46 0 1345802704 6317456 5525504
17:25:12 57814 8192000000 10229152080 10661232640 1342979408 14.084 100 31 62 1 1360072992 9149120 7944464
17:27:15 4171 8192000000 10242093808 10656366592 1337448544 14.034 100 35 67 1 1351626912 6240528 7937840
17:28:21 5690 8192000000 10228066816 10643038208 1312954368 13.813 98 25 49 2 1323950240 5331824 5664048
17:29:30 66525 8192000000 10179064624 10582736896 1213166752 12.898 93 31 60 3 1225259472 4645552 7447168
17:31:42 3984 8192000000 10165817504 10566123520 1260774000 13.337 99 28 55 0 1273461360 7154640 5532720
17:32:48 8772 8192000000 10140405008 10540081152 1231521328 13.068 98 22 45 0 1242224448 5006784 5696336
17:33:57 14023 8192000000 10167127760 10568892416 1259920400 13.329 99 32 63 1 1273316112 5897296 7498416
17:35:12 16862 8192000000 10165887248 10572238848 1272944736 13.449 100 30 60 0 1286259168 6995568 6318864
17:36:31 12008 8192000000 10184049632 10593869824 1292972464 13.631 100 24 48 0 1303763968 5081312 5710192
17:37:46 6100 8192000000 10190028816 10602504192 1300601120 13.701 100 29 58 0 1313042784 5769824 6671840
17:38:55 12236 8192000000 10236478944 10640961536 1325496464 13.926 100 45 90 1 1342668464 6087392 11084608
17:40:11 93349 8192000000 10237558208 10668429312 1343323696 14.087 100 40 80 0 1362537920 9782656 9431568
17:42:48 14236 8192000000 10277031040 10688843776 1359518752 14.233 100 64 126 1 1383296240 10250496 13526992
17:44:08 11451 8192000000 10288110144 10705408000 1373977760 14.363 100 52 104 1 1399219584 13529808 11712016
17:45:23 13425 8192000000 10292389408 10713587712 1378851456 14.406 100 47 94 0 1403106080 13495680 10758944
17:46:47 6800 8192000000 10307266368 10734239744 1389554208 14.502 100 55 108 0 1419192416 16298688 13339520
17:47:58 19724 8192000000 10307467216 10736922624 1392784640 14.531 100 34 68 0 1416326752 14894544 8647568
17:49:35 19529 8192000000 10317216784 10744750080 1397286352 14.571 100 35 70 1 1428781536 22454896 9040288
17:51:03 6210 8192000000 10341262464 10758316032 1403666192 14.628 100 70 139 0 1446162448 25705072 16791184
17:52:15 2251 8192000000 10327926784 10737180672 1381745952 14.432 98 63 122 0 1428849536 32149920 14953664
17:53:24 9052 8192000000 10324033056 10741694464 1387192912 14.481 99 49 97 0 1430556608 30948336 12415360
17:54:45 2540 8192000000 10369806240 10781396992 1412770704 14.709 100 82 161 1 1470985120 38823632 19390784
17:55:53 2678 8192000000 10352753584 10763116544 1377971776 14.398 98 77 146 0 1438178112 42166432 18039904
17:57:03 2765 8192000000 10341516064 10747662336 1383467376 14.448 100 77 154 0 1442163392 39185216 19510800
17:58:13 1680 8192000000 10348823136 10749034496 1375076944 14.373 100 88 176 1 1443456560 45905168 22474448
17:59:18 6458 8192000000 10328532352 10743304192 1365194368 14.284 99 48 96 1 1422924272 45133024 12596880
18:00:26 6066 8192000000 10313519952 10722746368 1349125088 14.140 98 65 127 1 1405500128 39656048 16718992
18:01:37 8056 8192000000 10304839200 10732707840 1363524816 14.269 100 45 87 3 1414961248 39620288 11816144
18:02:48 7801 8192000000 10315777376 10741768192 1343191888 14.086 98 51 102 0 1409909584 52529792 14187904
18:04:03 13453 8192000000 10268652608 10712821760 1335596432 14.018 98 23 44 0 1363308640 22148176 5564032
18:05:17 41035 8192000000 10276832000 10715492352 1357940992 14.219 99 19 38 0 1377923072 15447920 4534160
18:07:02 39608 8192000000 10287659952 10732158976 1368043856 14.310 100 24 48 0 1394609392 20328944 6236592
18:08:43 61855 8192000000 10273508016 10709852160 1340251872 14.060 98 9 19 0 1360609488 18593744 1763872
18:11:02 32370 8192000000 10295455552 10732929024 1380149728 14.418 100 46 88 0 1396450304 6604128 9696448
18:12:40 59466 8192000000 10298888576 10749931520 1373057536 14.354 99 10 20 0 1388909248 13602688 2249024
18:14:55 38076 8192000000 10276809920 10718826496 1342935280 14.084 98 24 46 3 1362161312 14098048 5127984
18:16:34 150803 8192000000 10312072128 10766540800 1354427472 14.187 98 13 26 0 1373637776 16434608 2775696
18:20:36 102664 8192000000 10294117888 10736742400 1378150064 14.400 99 37 73 0 1395940096 9134400 8655632
18:24:49 18715 8192000000 10310257424 10756771840 1399190432 14.588 100 37 71 1 1415010608 7935536 7884640
18:26:11 28230 8192000000 10298164576 10761089024 1385053792 14.462 99 26 50 1 1402060848 10704080 6302976
18:27:42 26828 8192000000 10298165968 10756358144 1384559616 14.457 99 24 48 0 1398648336 8544064 5544656
18:29:10 57658 8192000000 10301490752 10761228288 1399324864 14.589 100 9 18 0 1414842720 13208048 2309808
18:31:09 53386 8192000000 10293498704 10751991808 1363186528 14.266 97 17 34 0 1383127216 15798336 4142352
18:33:03 69430 8192000000 10295998752 10777694208 1400416624 14.599 100 25 50 1 1426348240 19439248 6492368
18:35:41 65838 8192000000 10276460128 10740928512 1347991328 14.129 97 5 10 0 1367795664 18265376 1538960
18:37:48 60336 8192000000 10271293360 10726502400 1334416192 14.007 97 13 26 0 1348739792 11591472 2732128
18:39:48 34771 8192000000 10269454768 10721062912 1346329472 14.114 98 18 36 1 1362897584 13036032 3532080
18:41:23 51581 8192000000 10293363024 10739806208 1329613072 13.964 96 29 57 0 1347940528 12642544 5684912
18:43:15 31141 8192000000 10279197888 10729660416 1351370608 14.160 98 16 32 0 1362463472 7741120 3351744
18:44:52 29779 8192000000 10297802672 10740379648 1365563568 14.287 98 29 58 0 1388777936 16898672 6315696
18:47:11 36838 8192000000 10292642128 10727776256 1320298000 13.879 96 60 119 0 1344140304 10909072 12933232
18:49:32 48442 8192000000 10270212880 10734059520 1331750832 13.983 97 6 12 0 1350777632 17674192 1352608
18:51:21 92122 8192000000 10278573264 10727768064 1355562224 14.197 99 11 22 1 1373981344 16465024 1954096
18:54:03 21095 8192000000 10294012912 10724237312 1344036400 14.094 98 45 90 0 1366716352 12818976 9860976
18:55:29 16209 8192000000 10290380064 10738204672 1375213520 14.374 100 34 69 0 1400362528 17251792 7897216
18:56:47 50390 8192000000 10262308464 10714251264 1330544048 13.972 97 23 46 0 1347788976 10961408 6283520
18:58:38 43346 8192000000 10257614176 10710556672 1328440352 13.953 98 12 24 0 1343994336 12639424 2914560
19:00:23 59575 8192000000 10271614048 10724499456 1362378704 14.259 100 12 24 0 1386834448 21450048 3005696
19:02:25 77971 8192000000 10269455360 10729095168 1364959600 14.282 100 24 48 0 1385943360 15147344 5836416
19:04:48 28717 8192000000 10277699456 10733133824 1374738368 14.369 100 13 26 0 1387976208 10526336 2711504
19:06:17 38691 8192000000 10280025280 10734882816 1359505232 14.233 98 21 44 0 1372577424 8164128 4908064
19:07:58 37513 8192000000 10291334704 10739142656 1352081856 14.166 97 23 46 0 1369212960 12548560 4582544
19:09:42 78331 8192000000 10314426864 10773827584 1387768672 14.486 99 17 34 0 1405399472 13709152 3921648
19:12:04 47241 8192000000 10302225968 10747756544 1387171248 14.481 99 33 65 1 1403625232 9614640 6839344
19:13:54 30183 8192000000 10310961520 10770186240 1398412848 14.581 100 21 42 0 1415870368 12866656 4590864
19:15:27 82814 8192000000 10324951552 10767351808 1348997984 14.138 96 20 36 1 1362816688 9611904 4206800
19:17:54 42041 8192000000 10278441616 10731577344 1338752160 14.046 96 21 42 0 1356288480 13097632 4438688
19:19:40 144851 8192000000 10295742160 10765086720 1362420928 14.259 97 16 32 0 1379966576 14098144 3447504
19:23:06 63505 8192000000 10286537648 10749542400 1332731952 13.992 95 16 32 0 1349844880 13395600 3717328
19:25:11 49276 8192000000 10269361552 10735718400 1367813168 14.307 99 8 15 0 1376399632 7219936 1366528
19:27:00 72343 8192000000 10266997952 10723676160 1347612288 14.126 98 24 48 0 1360771872 8119616 5039968
19:29:23 44572 8192000000 10283731744 10744619008 1365255488 14.285 99 5 10 0 1378352080 12025360 1071232
19:31:09 108533 8192000000 10284438336 10736504832 1336884096 14.029 97 16 32 0 1358660448 18237936 3538416
19:34:01 44791 8192000000 10281400096 10725146624 1342141616 14.077 98 26 52 0 1356095632 8087712 5866304
19:36:25 80200 8192000000 10280226144 10732949504 1336685616 14.028 97 24 49 0 1358480112 16455776 5338720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2022 19:39:37 to 16-12-2022 19:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2022 19:39:53 to 16-12-2022 19:39:55.

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          244684
MON$OLDEST_ACTIVE               246977
MON$OLDEST_SNAPSHOT             246977
MON$NEXT_TRANSACTION            246977
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                       2028064
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 ---
:737: 
:738: mscs-test-161.budzdorov.ru    Fri Dec 16 19:38:35 2022
:739:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:740: 
:741: 
:742: mscs-test-161.budzdorov.ru    Fri Dec 16 19:38:43 2022
:743:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:744: 
:745: 
:746: mscs-test-161.budzdorov.ru    Fri Dec 16 19:38:43 2022
:747:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:748: 
--- end of diff output ---

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

16.12.22 19:39:56. 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-16 19:39:58 No severe PSQL-related problems occured
16.12.22 19:40:00. End of report.