Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.2023 16: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 15275640 kB
MemAvailable 30426220 kB
Buffers 0 kB
Cached 16418724 kB
SwapCached 3176 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 2023-02-18 16:38:39
DTS_END                         2023-02-18 16:38:39.9070
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/45098
TRN_ID                          249905
ATT_ID                          726
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: 9458.23 16800 1134988
customer order: creation 186.44 334 22373
customer order: refuse 15.31 371 1837
order to supplier: creation 47.98 1191 5758
order to supplier: removal 3.45 85788 414
invoice (draft): creation 49.60 2968 5952
invoice (draft): removal 52.30 643 6276
invoice accept: apply 45.63 16941 5475
invoice accept: cancel 46.94 776 5633
customer reserve: creation 4124.80 171 494976
customer reserve: removal 4576.62 51 549194
realization accept: apply 90.14 185 10817
realization accept: cancel 80.87 106 9704
payment from customer: creation 63.40 123 7608
payment from customer: removal 5.86 71 703
payment to supplier: creation 52.38 128 6286
payment to supplier: removal 3.88 61 465
service: total inventory turnovers 4.88 1076 586
service: total monetary turnovers 6.92 23 830
service: aggregate perf. data 0.81 24034 97
service: refresh index statistics 0.03 200949 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-02-2023 16:39:43 to 18-02-2023 16:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7316
TEST_TIME 2 6249
TEST_TIME 3 7115
TEST_TIME 4 6880
TEST_TIME 5 6398
TEST_TIME 6 5899
TEST_TIME 7 6238
TEST_TIME 8 6810
TEST_TIME 9 6594
TEST_TIME 10 6528
TEST_TIME 11 6719
TEST_TIME 12 6904
TEST_TIME 13 7381
TEST_TIME 14 7585
TEST_TIME 15 7586
TEST_TIME 16 7572
TEST_TIME 17 7512
TEST_TIME 18 7988
TEST_TIME 19 8329
TEST_TIME 20 8553
TEST_TIME 21 8691
TEST_TIME 22 9143
TEST_TIME 23 9412
TEST_TIME 24 9604
TEST_TIME 25 9796
TEST_TIME 26 10096
TEST_TIME 27 10117
TEST_TIME 28 10617
TEST_TIME 29 10588
TEST_TIME 30 10513
TEST_TIME 31 10596
TEST_TIME 32 10416
TEST_TIME 33 10450
TEST_TIME 34 10409
TEST_TIME 35 10451
TEST_TIME 36 10479
TEST_TIME 37 10590
TEST_TIME 38 10417
TEST_TIME 39 10351
TEST_TIME 40 10176
TEST_TIME 41 10085
TEST_TIME 42 10258
TEST_TIME 43 10067
TEST_TIME 44 9976
TEST_TIME 45 9939
TEST_TIME 46 9971
TEST_TIME 47 10030
TEST_TIME 48 10003
TEST_TIME 49 9935
TEST_TIME 50 9846
TEST_TIME 51 9815
TEST_TIME 52 9696
TEST_TIME 53 9832
TEST_TIME 54 9780
TEST_TIME 55 9675
TEST_TIME 56 9588
TEST_TIME 57 9533
TEST_TIME 58 9487
TEST_TIME 59 9472
TEST_TIME 60 9517
TEST_TIME 61 9523
TEST_TIME 62 9616
TEST_TIME 63 9630
TEST_TIME 64 9685
TEST_TIME 65 9693
TEST_TIME 66 9680
TEST_TIME 67 9697
TEST_TIME 68 9769
TEST_TIME 69 9768
TEST_TIME 70 9735
TEST_TIME 71 9774
TEST_TIME 72 9828
TEST_TIME 73 9863
TEST_TIME 74 9879
TEST_TIME 75 9776
TEST_TIME 76 9769
TEST_TIME 77 9709
TEST_TIME 78 9691
TEST_TIME 79 9647
TEST_TIME 80 9693
TEST_TIME 81 9643
TEST_TIME 82 9608
TEST_TIME 83 9608
TEST_TIME 84 9514
TEST_TIME 85 9473
TEST_TIME 86 9501
TEST_TIME 87 9475
TEST_TIME 88 9454
TEST_TIME 89 9421
TEST_TIME 90 9546
TEST_TIME 91 9556
TEST_TIME 92 9510
TEST_TIME 93 9628
TEST_TIME 94 9607
TEST_TIME 95 9632
TEST_TIME 96 9673
TEST_TIME 97 9657
TEST_TIME 98 9627
TEST_TIME 99 9722
TEST_TIME 100 9702
TEST_TIME 101 9702
TEST_TIME 102 9693
TEST_TIME 103 9640
TEST_TIME 104 9622
TEST_TIME 105 9581
TEST_TIME 106 9582
TEST_TIME 107 9557
TEST_TIME 108 9549
TEST_TIME 109 9533
TEST_TIME 110 9497
TEST_TIME 111 9462
TEST_TIME 112 9391
TEST_TIME 113 9462
TEST_TIME 114 9462
TEST_TIME 115 9434
TEST_TIME 116 9468
TEST_TIME 117 9465
TEST_TIME 118 9474
TEST_TIME 119 9490
TEST_TIME 120 9466

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-02-2023 16:39:46 to 18-02-2023 16:39:50.

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 1593221 1593221 0 0.00 0 16214 6 0 0
doc_list_biud 1593221 1593221 0 0.00 0 44468 23 0 0
sp_add_invoice_to_stock 5593 5475 118 2.10 37 203730 16942 0 118
sp_cancel_adding_invoice 5633 5633 0 0.00 4 58466 777 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 14145 372 0 0
sp_cancel_customer_reserve 549194 549194 0 0.00 0 44470 51 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 9555 72 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 3 2313 62 0 0
sp_cancel_supplier_invoice 6282 6276 6 0.09 1 16381 644 0 6
sp_cancel_supplier_order 414 414 0 0.00 8 813386 85789 0 0
sp_cancel_write_off 9704 9704 0 0.00 1 15085 107 0 0
sp_client_order 22373 22373 0 0.00 3 18294 334 0 0
sp_customer_reserve 494976 494976 0 0.00 0 28491 171 0 0
sp_fill_shopping_cart 34069 34069 0 0.00 1 22305 36 0 0
sp_fill_shopping_cart_clo_res 494966 494966 0 0.00 0 15451 11 0 0
sp_get_clo_for_invoice 5460 5460 0 0.00 0 25673 270 0 0
sp_kill_cost_storno 16505 16505 0 0.00 0 13700 48 0 0
sp_kill_qstorno_ret_qs2qd 1114782 1114782 0 0.00 0 44392 28 0 0
sp_kill_qty_storno 589333 589333 0 0.00 0 44467 61 0 0
sp_lock_selected_doc 22403 22403 0 0.00 0 8693 6 0 0
sp_make_cost_storno 30169 30169 0 0.00 0 14922 58 0 0
sp_multiply_rows_for_pdistr 30169 30169 0 0.00 0 14758 31 0 0
sp_multiply_rows_for_qdistr 22373 22373 0 0.00 0 17926 151 0 0
sp_pay_from_customer 7608 7608 0 0.00 4 21190 123 0 0
sp_pay_to_supplier 6286 6286 0 0.00 4 14990 128 0 0
sp_payment_common 13894 13894 0 0.00 2 21127 37 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 14137 340 0 0
sp_qd_handle_on_invoice_upd_sts 11091 11091 0 0.00 0 30775 313 0 0
sp_qd_handle_on_reserve_upd_sts 10817 10817 0 0.00 0 15243 55 0 0
sp_reserve_write_off 10817 10817 0 0.00 6 15277 185 0 0
sp_supplier_invoice 5952 5952 0 0.00 6 93672 2968 0 0
sp_supplier_order 5758 5758 0 0.00 5 57746 1192 0 0
srv_aggregate_perf_data 97 97 0 0.00 3126 397899 24034 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2601 141821 40711 0 0
srv_find_qd_qs_mism 1083756 1083756 0 0.00 0 16214 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 33 23643 1077 0 0
srv_make_money_saldo 830 830 0 0.00 1 490 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 58084 335335 200950 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5476 5476 0 0.00 0 11668 171 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 6105 99 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 2922 15 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 4027 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2925 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 1929 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 461 461 0 0.00 0 2952 36 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 20268 1550 0 0
t$perf-norm:sp_cancel_write_off 1087 1087 0 0.00 0 2838 13 0 0
t$perf-norm:sp_client_order 22373 22373 0 0.00 0 8599 18 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 4553 19 0 0
t$perf-norm:sp_pay_from_customer 7608 7608 0 0.00 0 10854 14 0 0
t$perf-norm:sp_pay_to_supplier 6286 6286 0 0.00 0 5285 12 0 0
t$perf-norm:sp_reserve_write_off 10817 10817 0 0.00 0 10666 17 0 0
t$perf-norm:sp_supplier_invoice 5952 5952 0 0.00 0 8530 22 0 0
t$perf-norm:sp_supplier_order 5758 5758 0 0.00 0 4293 15 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 288 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 725 17 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 1616 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 11152 10916 236 2.11 0 12265 19 0 236
v_all_customers 40416 40416 0 0.00 0 31 0 0 0
v_all_suppliers 31722 31722 0 0.00 0 16 1 0 0
v_all_wares 44742 44742 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 2442 11 0 0
v_cancel_client_order 3674 3674 0 0.00 0 7666 7 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 605 4 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 3238 7 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 2288 9 0 0
v_cancel_supplier_invoice 934 922 12 1.28 0 168 11 0 12
v_cancel_supplier_order 828 828 0 0.00 0 1463 6 0 0
v_cancel_write_off 2174 2174 0 0.00 0 2808 16 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 593 1 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 1071 12 0 0
v_max_id_ord_sup 5944 5944 0 0.00 0 2749 4 0 0
v_max_non_paid_invoice 2123 2123 0 0.00 0 814 3 0 0
v_max_non_paid_realizn 4209 4209 0 0.00 0 4632 6 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 4675 10 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 3564 12 0 0
v_min_id_ord_sup 5944 5944 0 0.00 0 4747 8 0 0
v_min_non_paid_invoice 6286 6286 0 0.00 0 14290 23 0 0
v_min_non_paid_realizn 7608 7608 0 0.00 0 21125 33 0 0
v_our_firm 11124 11124 0 0.00 0 15 1 0 0
v_reserve_write_off 21634 21634 0 0.00 0 7408 7 0 0
x_lock_depdocs_on_canc_invoice 11448 11448 0 0.00 0 16755 103 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 29794 392 0 0
x_make_qstorno_1000_1200 5758 5758 0 0.00 1 57719 1115 0 0
x_make_qstorno_1200_2000 5952 5952 0 0.00 2 90504 2837 0 0
x_make_qstorno_2100_3300 494976 494976 0 0.00 0 28490 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-02-2023 16:39:51 to 18-02-2023 16:39:55.

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
14:08:44 26 8192000000 8834416624 8858673152 30032976 0.365 15 5 5 0 30276512 104256 139280
14:09:46 22280 8192000000 9905728640 10218512384 993874592 10.819 100 88 176 0 1022471392 6184624 22412176
14:11:11 20345 8192000000 9940099440 10261106688 1019877680 11.071 100 83 166 0 1049781440 8650992 21252768
14:12:39 11492 8192000000 9982438256 10312548352 1065190912 11.506 100 87 174 0 1096833152 10055664 21586576
14:13:57 12568 8192000000 10021845744 10361647104 1097947152 11.818 100 85 170 0 1129469536 11424192 20098192
14:15:12 6276 8192000000 10058405936 10412011520 1145783184 12.270 100 77 154 0 1175765184 12337904 17644096
14:16:21 5382 8192000000 10100125024 10467778560 1193771344 12.718 100 71 142 0 1222002784 12694672 15536768
14:17:28 2303 8192000000 10138512640 10509914112 1227082144 13.027 100 79 159 0 1257552160 13379360 17090656
14:18:32 1550 8192000000 10169694800 10548277248 1254836208 13.283 100 85 168 0 1286645008 13739104 18069696
14:19:35 856 8192000000 10203660064 10584952832 1282450496 13.535 100 83 166 0 1317532112 16517520 18564096
14:20:37 7229 8192000000 10175768784 10584653824 1291073616 13.614 100 22 42 0 1302395296 6517392 4804288
14:21:45 8990 8192000000 10188011008 10602278912 1308298304 13.771 100 15 30 0 1317452896 6062752 3091840
14:22:58 68012 8192000000 10217287840 10636537856 1323258816 13.906 100 23 46 0 1333342064 4715472 5367776
14:25:07 4345 8192000000 10230658912 10649231360 1334251936 14.006 100 21 43 0 1349989856 10738144 4999776
14:26:11 7341 8192000000 10234271408 10654601216 1340503824 14.062 100 26 48 1 1353087696 6288992 6294880
14:27:20 11801 8192000000 10227015744 10651082752 1339467184 14.053 100 18 36 0 1349465072 6335088 3662800
14:28:33 19827 8192000000 10224113008 10650308608 1327410672 13.944 99 40 80 1 1347782048 11548736 8822640
14:29:54 7724 8192000000 10192602752 10604519424 1296689792 13.665 100 27 53 1 1308920496 6700944 5529760
14:31:03 4244 8192000000 10139437376 10536968192 1236496672 13.114 99 42 83 0 1252711488 6570400 9644416
14:32:10 23430 8192000000 10109075664 10506199040 1178999232 12.581 95 20 38 2 1187695520 4717056 3979232
14:33:35 8023 8192000000 10134451808 10538901504 1246280272 13.204 100 17 35 0 1255372416 5343728 3748416
14:34:44 8346 8192000000 10171368176 10580705280 1279844784 13.512 100 35 68 4 1294829232 6172320 8812128
14:35:54 6832 8192000000 10191095504 10600886272 1294547936 13.646 100 25 51 0 1305515552 5047824 5919792
14:37:03 76124 8192000000 10211302144 10621079552 1310753280 13.793 100 32 59 1 1322179600 4378768 7047552
14:39:26 7160 8192000000 10245669904 10653446144 1339759072 14.055 100 69 133 1 1365484544 9768512 15956960
14:40:37 13179 8192000000 10261374224 10674847744 1350466304 14.152 100 55 108 1 1376251088 13554384 12230400
14:42:10 24578 8192000000 10279950160 10701295616 1371914480 14.344 100 47 92 0 1395798032 12181296 11702256
14:43:50 20733 8192000000 10278577584 10710024192 1378254000 14.401 100 19 38 0 1394668384 11894384 4520000
14:45:15 6364 8192000000 10301515120 10725457920 1384569472 14.457 100 45 90 1 1414326592 19478208 10278912
14:46:25 14583 8192000000 10313550768 10736795648 1396203440 14.561 100 47 94 0 1424634304 17305104 11125760
14:47:46 12057 8192000000 10319098416 10743521280 1398477824 14.581 100 57 111 0 1431421488 19738480 13205184
14:49:06 2600 8192000000 10324954592 10741760000 1386946176 14.479 99 69 136 0 1427609824 24392848 16270800
14:50:11 4603 8192000000 10339569456 10754310144 1388205840 14.490 99 64 126 0 1432921312 29618352 15097120
14:51:18 9569 8192000000 10333150336 10758709248 1397289584 14.571 100 62 122 0 1444417872 31775440 15352848
14:52:32 5510 8192000000 10312804768 10735575040 1379515040 14.412 99 48 95 0 1413064768 21747968 11801760
14:53:50 8496 8192000000 10299789920 10711445504 1361296240 14.249 99 55 111 0 1400093168 25457376 13339552
14:55:04 3932 8192000000 10328905936 10732511232 1373610288 14.359 100 83 164 0 1424755424 30757488 20387648
14:56:11 3972 8192000000 10335021760 10741952512 1377284992 14.392 100 77 153 0 1436667296 39840368 19541936
14:57:24 2627 8192000000 10329993360 10734329856 1374796336 14.370 100 81 162 1 1429409552 34786384 19826832
14:58:31 10088 8192000000 10286142544 10706223104 1340517536 14.062 99 38 76 1 1386601360 36108672 9975152
14:59:50 3302 8192000000 10313136224 10729332736 1367388096 14.304 100 60 118 1 1417254672 34923728 14942848
15:00:54 17018 8192000000 10291396272 10713780224 1340020352 14.058 98 31 62 0 1380800368 32788480 7991536
15:02:15 30666 8192000000 10287405120 10711056384 1339268704 14.051 98 22 44 0 1369473504 24368976 5835824
15:03:53 9670 8192000000 10278647376 10711707648 1355098064 14.193 100 26 50 0 1385092320 23520976 6473280
15:05:05 37893 8192000000 10261332160 10701635584 1338734608 14.046 100 16 32 1 1365105408 21845536 4525264
15:07:39 57560 8192000000 10276724784 10723205120 1356387552 14.205 100 20 40 1 1380934160 19962192 4584416
15:09:37 64038 8192000000 10294698176 10718605312 1355232800 14.195 99 30 59 0 1372624016 10296128 7095088
15:11:48 40677 8192000000 10280039632 10724483072 1366721744 14.298 99 23 43 1 1382032720 10433360 4877616
15:13:33 141803 8192000000 10293797536 10757832704 1351348272 14.160 98 10 18 0 1368711152 15384720 1978160
15:16:55 52835 8192000000 10306532464 10745323520 1376744960 14.387 99 44 88 3 1395473328 8695648 10032720
15:20:10 62026 8192000000 10311875488 10764001280 1379666464 14.414 99 26 52 0 1397543920 11723520 6153936
15:22:32 7698 8192000000 10324461952 10760531968 1398974656 14.586 100 50 98 3 1419092336 8751552 11366128
15:23:43 28006 8192000000 10311976544 10761957376 1388590304 14.493 99 34 66 0 1406635872 9863744 8181824
15:25:26 15581 8192000000 10308908128 10760638464 1394663120 14.547 100 33 61 0 1407410784 5784304 6963360
15:26:44 81040 8192000000 10292215392 10753937408 1363158128 14.266 97 11 22 0 1381111168 15337824 2615216
15:29:11 52417 8192000000 10308354272 10772197376 1390836032 14.513 99 19 36 1 1411790320 16478560 4475728
15:31:08 27119 8192000000 10300805264 10763657216 1392236736 14.526 100 17 34 0 1406825488 10572128 4016624
15:32:38 54224 8192000000 10312078976 10763292672 1389619216 14.502 99 41 81 3 1405799472 7839600 8340656
15:34:38 56772 8192000000 10277846608 10748387328 1375875760 14.380 99 21 42 0 1393787136 13154512 4756864
15:36:50 36549 8192000000 10281005232 10744582144 1350851328 14.155 98 16 30 1 1366809520 12440096 3518096
15:38:44 33668 8192000000 10277539760 10724425728 1331221568 13.978 97 25 50 0 1347814976 11280272 5313136
15:40:18 62530 8192000000 10275934400 10742181888 1320221040 13.879 96 14 28 0 1339490224 16444992 2824192
15:42:21 38929 8192000000 10264773136 10716483584 1321732224 13.892 97 15 28 1 1331494560 7121376 2640960
15:44:00 62538 8192000000 10284551264 10732490752 1303561776 13.728 95 19 38 0 1321390832 13641760 4187296
15:46:12 33797 8192000000 10280820208 10719768576 1342651984 14.081 99 33 66 0 1367574160 17492368 7429808
15:47:48 32259 8192000000 10271725392 10704506880 1306721888 13.756 96 42 82 0 1326026656 10041488 9263280
15:49:26 17072 8192000000 10287569424 10723147776 1345576544 14.108 99 39 78 1 1366031296 11938672 8516080
15:50:43 62789 8192000000 10269109216 10717437952 1312701296 13.811 97 15 30 0 1332227696 16138192 3388208
15:52:46 89075 8192000000 10260345696 10711379968 1343760336 14.091 99 14 28 0 1358706208 11970368 2975504
15:55:24 48487 8192000000 10280069776 10726522880 1344918592 14.102 98 35 68 0 1363430464 10105904 8405968
15:57:16 20907 8192000000 10280670720 10728058880 1362116432 14.256 99 47 94 0 1385042112 12659216 10266464
15:58:40 56248 8192000000 10259681104 10722172928 1339857600 14.056 98 12 24 0 1350782528 8116160 2808768
16:00:37 30861 8192000000 10277358992 10735099904 1369260544 14.320 100 20 40 0 1387318272 13501792 4555936
16:02:14 63333 8192000000 10279580048 10736181248 1376087296 14.382 100 14 30 0 1387668080 8353472 3227312
16:04:22 84053 8192000000 10275848912 10742525952 1362892960 14.263 99 7 14 0 1374816928 10438976 1484992
16:06:47 50519 8192000000 10293191888 10756018176 1387434672 14.483 100 12 24 0 1401914032 11612048 2867312
16:08:38 76549 8192000000 10298287664 10758074368 1379220976 14.410 99 14 28 0 1392918352 10806336 2891040
16:10:57 51205 8192000000 10308653856 10776965120 1397283472 14.571 99 27 54 0 1416316992 13003440 6030080
16:12:51 127232 8192000000 10316439664 10778296320 1403681440 14.628 100 8 16 0 1422107600 16752320 1673840
16:16:03 108657 8192000000 10299037056 10774470656 1351290864 14.159 96 22 43 0 1369941968 14034464 4616640
16:18:52 67417 8192000000 10269008320 10722656256 1291011952 13.613 93 14 28 1 1306383520 12075648 3295920
16:21:14 89081 8192000000 10256400896 10705838080 1310759344 13.793 96 11 22 0 1321305712 8104272 2442096
16:23:44 60431 8192000000 10261109072 10722865152 1333354944 13.997 97 20 39 0 1346627520 8791184 4481392
16:25:47 45363 8192000000 10272944224 10733604864 1359055856 14.229 99 4 8 0 1371568560 11688624 824080
16:27:35 35343 8192000000 10253298976 10711715840 1353557232 14.179 99 20 38 0 1367406240 9903712 3945296
16:29:20 69915 8192000000 10288474784 10746249216 1379823232 14.415 100 15 30 0 1396453488 12824976 3805280
16:33:18 23705 8192000000 10252216672 10704248832 1323773216 13.911 97 19 38 0 1338051904 10073408 4205280
16:34:47 34604 8192000000 10269548320 10709876736 1307842032 13.766 96 31 62 0 1326662064 12018192 6801840
16:36:24 51016 8192000000 10271807312 10710376448 1325609296 13.927 98 29 57 0 1344084208 11631392 6843520
16:38:15 32321 8192000000 9515546512 9847013376 651902464 7.371 48 13 26 0 664191072 9691632 2596976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-02-2023 16:39:57 to 18-02-2023 16:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-02-2023 16:40:13 to 18-02-2023 16:40:15.

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          250599
MON$OLDEST_ACTIVE               250600
MON$OLDEST_SNAPSHOT             250600
MON$NEXT_TRANSACTION            250600
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                       2036448
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
--- end of diff output ---

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

18.02.23 16:40:16. 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
2023-02-18 16:40:19 No severe PSQL-related problems occured
18.02.23 16:40:20. End of report.