Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2023 22: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 15090440 kB
MemAvailable 30408864 kB
Buffers 0 kB
Cached 16590912 kB
SwapCached 5056 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-09 22:38:42
DTS_END                         2023-02-09 22:38:42.4420
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/51340
TRN_ID                          239902
ATT_ID                          665
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: 9620.19 20052 1154423
customer order: creation 174.09 332 20891
customer order: refuse 14.53 369 1744
order to supplier: creation 45.88 1295 5506
order to supplier: removal 3.38 104710 405
invoice (draft): creation 46.12 3332 5534
invoice (draft): removal 56.83 755 6819
invoice accept: apply 41.65 16718 4998
invoice accept: cancel 51.92 771 6230
customer reserve: creation 3817.76 166 458131
customer reserve: removal 5065.43 54 607851
realization accept: apply 83.28 180 9994
realization accept: cancel 90.51 109 10861
payment from customer: creation 59.13 135 7096
payment from customer: removal 5.68 79 682
payment to supplier: creation 48.32 143 5798
payment to supplier: removal 3.56 56 427
service: total inventory turnovers 4.49 1376 539
service: total monetary turnovers 6.85 31 822
service: aggregate perf. data 0.76 23695 91
service: refresh index statistics 0.03 246740 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-02-2023 22:39:44 to 09-02-2023 22:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3702
TEST_TIME 2 4433
TEST_TIME 3 4121
TEST_TIME 4 4226
TEST_TIME 5 4182
TEST_TIME 6 4298
TEST_TIME 7 4308
TEST_TIME 8 4435
TEST_TIME 9 5219
TEST_TIME 10 5302
TEST_TIME 11 6141
TEST_TIME 12 6372
TEST_TIME 13 6489
TEST_TIME 14 6975
TEST_TIME 15 7254
TEST_TIME 16 7783
TEST_TIME 17 7946
TEST_TIME 18 8013
TEST_TIME 19 8359
TEST_TIME 20 8252
TEST_TIME 21 8235
TEST_TIME 22 8188
TEST_TIME 23 8202
TEST_TIME 24 8212
TEST_TIME 25 8239
TEST_TIME 26 8182
TEST_TIME 27 8519
TEST_TIME 28 8746
TEST_TIME 29 8980
TEST_TIME 30 9234
TEST_TIME 31 10369
TEST_TIME 32 10654
TEST_TIME 33 10519
TEST_TIME 34 10461
TEST_TIME 35 10597
TEST_TIME 36 10615
TEST_TIME 37 10575
TEST_TIME 38 10449
TEST_TIME 39 10263
TEST_TIME 40 10219
TEST_TIME 41 10103
TEST_TIME 42 10272
TEST_TIME 43 10348
TEST_TIME 44 10272
TEST_TIME 45 10342
TEST_TIME 46 10244
TEST_TIME 47 10254
TEST_TIME 48 10357
TEST_TIME 49 10221
TEST_TIME 50 10120
TEST_TIME 51 10015
TEST_TIME 52 9938
TEST_TIME 53 9972
TEST_TIME 54 9902
TEST_TIME 55 9839
TEST_TIME 56 9807
TEST_TIME 57 9792
TEST_TIME 58 9984
TEST_TIME 59 9877
TEST_TIME 60 9785
TEST_TIME 61 9726
TEST_TIME 62 9657
TEST_TIME 63 9594
TEST_TIME 64 9581
TEST_TIME 65 9560
TEST_TIME 66 9547
TEST_TIME 67 9611
TEST_TIME 68 9619
TEST_TIME 69 9595
TEST_TIME 70 9608
TEST_TIME 71 9663
TEST_TIME 72 9685
TEST_TIME 73 9695
TEST_TIME 74 9769
TEST_TIME 75 9939
TEST_TIME 76 9932
TEST_TIME 77 10008
TEST_TIME 78 9908
TEST_TIME 79 9824
TEST_TIME 80 9836
TEST_TIME 81 9746
TEST_TIME 82 9759
TEST_TIME 83 9697
TEST_TIME 84 9716
TEST_TIME 85 9698
TEST_TIME 86 9682
TEST_TIME 87 9613
TEST_TIME 88 9588
TEST_TIME 89 9547
TEST_TIME 90 9478
TEST_TIME 91 9511
TEST_TIME 92 9483
TEST_TIME 93 9515
TEST_TIME 94 9526
TEST_TIME 95 9499
TEST_TIME 96 9459
TEST_TIME 97 9581
TEST_TIME 98 9684
TEST_TIME 99 9662
TEST_TIME 100 9689
TEST_TIME 101 9667
TEST_TIME 102 9741
TEST_TIME 103 9750
TEST_TIME 104 9782
TEST_TIME 105 9744
TEST_TIME 106 9671
TEST_TIME 107 9670
TEST_TIME 108 9631
TEST_TIME 109 9650
TEST_TIME 110 9600
TEST_TIME 111 9573
TEST_TIME 112 9582
TEST_TIME 113 9503
TEST_TIME 114 9510
TEST_TIME 115 9525
TEST_TIME 116 9532
TEST_TIME 117 9506
TEST_TIME 118 9544
TEST_TIME 119 9533
TEST_TIME 120 9622

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2023 22:39:48 to 09-02-2023 22:39:51.

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 1579584 1579584 0 0.00 0 29184 6 0 0
doc_list_biud 1579584 1579584 0 0.00 0 44144 27 0 0
sp_add_invoice_to_stock 5096 4998 98 1.92 34 176624 16718 0 98
sp_cancel_adding_invoice 6230 6230 0 0.00 5 68148 771 0 0
sp_cancel_client_order 1744 1744 0 0.00 4 18377 369 0 0
sp_cancel_customer_reserve 607851 607851 0 0.00 0 29764 55 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 6303 79 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 1864 56 0 0
sp_cancel_supplier_invoice 6834 6819 15 0.21 1 44161 755 0 15
sp_cancel_supplier_order 405 405 0 0.00 7 1040744 104711 0 0
sp_cancel_write_off 10861 10861 0 0.00 1 29185 110 0 0
sp_client_order 20891 20891 0 0.00 3 18290 333 0 0
sp_customer_reserve 458131 458131 0 0.00 0 115594 166 0 0
sp_fill_shopping_cart 31927 31927 0 0.00 1 17091 41 0 0
sp_fill_shopping_cart_clo_res 458130 458130 0 0.00 0 14732 13 0 0
sp_get_clo_for_invoice 4995 4995 0 0.00 0 26027 317 0 0
sp_kill_cost_storno 18200 18200 0 0.00 0 29182 55 0 0
sp_kill_qstorno_ret_qs2qd 1233787 1233787 0 0.00 0 44135 30 0 0
sp_kill_qty_storno 648896 648896 0 0.00 0 44140 65 0 0
sp_lock_selected_doc 20719 20719 0 0.00 0 8826 6 0 0
sp_make_cost_storno 27880 27880 0 0.00 0 13841 61 0 0
sp_multiply_rows_for_pdistr 27880 27880 0 0.00 0 8384 30 0 0
sp_multiply_rows_for_qdistr 20891 20891 0 0.00 0 13786 149 0 0
sp_pay_from_customer 7096 7096 0 0.00 4 18034 135 0 0
sp_pay_to_supplier 5798 5798 0 0.00 5 28251 143 0 0
sp_payment_common 12894 12894 0 0.00 1 18024 35 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 18366 337 0 0
sp_qd_handle_on_invoice_upd_sts 11222 11222 0 0.00 0 38691 331 0 0
sp_qd_handle_on_reserve_upd_sts 9994 9994 0 0.00 0 14787 54 0 0
sp_reserve_write_off 9994 9994 0 0.00 6 18963 181 0 0
sp_supplier_invoice 5534 5534 0 0.00 6 100937 3333 0 0
sp_supplier_order 5506 5506 0 0.00 5 49337 1296 0 0
srv_aggregate_perf_data 91 91 0 0.00 2656 202117 23695 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1012 146226 41694 0 0
srv_find_qd_qs_mism 1107241 1107241 0 0.00 0 10974 4 0 0
srv_make_invnt_saldo 539 539 0 0.00 33 110680 1377 0 0
srv_make_money_saldo 822 822 0 0.00 1 2732 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 82733 453987 246740 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4998 4998 0 0.00 0 14499 158 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 13095 142 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 4036 13 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 1285 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 2875 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 4353 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 3497 19 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 23054 1778 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 692 3 0 0
t$perf-norm:sp_client_order 20891 20891 0 0.00 0 7647 16 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 1349 7 0 0
t$perf-norm:sp_pay_from_customer 7096 7096 0 0.00 0 5405 9 0 0
t$perf-norm:sp_pay_to_supplier 5798 5798 0 0.00 0 9716 12 0 0
t$perf-norm:sp_reserve_write_off 9994 9994 0 0.00 0 5411 10 0 0
t$perf-norm:sp_supplier_invoice 5535 5535 0 0.00 0 6100 15 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 12146 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 590 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1403 25 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 2238 25 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 2483 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10180 9984 196 1.92 0 4668 17 0 196
v_all_customers 38066 38066 0 0.00 0 53 1 0 0
v_all_suppliers 30550 30550 0 0.00 0 239 1 0 0
v_all_wares 41776 41776 0 0.00 0 128 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2093 11 0 0
v_cancel_client_order 3488 3488 0 0.00 0 4310 8 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 4801 10 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 2719 9 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1769 8 0 0
v_cancel_supplier_invoice 854 824 30 3.51 0 2914 21 0 30
v_cancel_supplier_order 810 810 0 0.00 0 1531 9 0 0
v_cancel_write_off 2024 2024 0 0.00 0 1171 12 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 1146 2 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 1887 13 0 0
v_max_id_ord_sup 5533 5533 0 0.00 0 7736 6 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 3724 7 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 2808 4 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 10829 15 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 6636 14 0 0
v_min_id_ord_sup 5533 5533 0 0.00 0 5995 5 0 0
v_min_non_paid_invoice 5798 5798 0 0.00 0 11054 24 0 0
v_min_non_paid_realizn 7096 7096 0 0.00 0 18019 27 0 0
v_our_firm 10540 10540 0 0.00 0 53 1 0 0
v_reserve_write_off 19986 19986 0 0.00 0 4753 6 0 0
x_lock_depdocs_on_canc_invoice 12637 12637 0 0.00 0 11782 104 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 16566 377 0 0
x_make_qstorno_1000_1200 5506 5506 0 0.00 1 49242 1209 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 2 100921 3186 0 0
x_make_qstorno_2100_3300 458131 458131 0 0.00 0 115588 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-02-2023 22:39:52 to 09-02-2023 22:39:57.

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
20:08:46 46 8192000000 8843974992 8870666240 36551072 0.444 16 5 3 0 36734432 97680 85680
20:09:46 26920 8192000000 9905865968 10217590784 987396688 10.756 100 89 178 0 1016739424 5818272 23524464
20:11:16 16173 8192000000 9965545376 10286260224 1041080784 11.275 100 93 186 0 1073697808 8534000 24083024
20:12:36 11301 8192000000 9979717360 10312699904 1070510864 11.557 100 79 156 0 1098765696 9281648 18973184
20:14:05 9348 8192000000 10030040640 10377924608 1119850480 12.026 100 79 158 0 1149312416 11051024 18410912
20:15:23 6998 8192000000 10086344192 10443792384 1172078064 12.516 100 80 160 0 1203806272 13728192 18000016
20:16:36 6617 8192000000 10125145136 10492993536 1210188640 12.871 100 82 164 0 1241720032 13342800 18188592
20:17:49 1933 8192000000 10159396048 10534813696 1248064512 13.220 100 87 173 1 1279000416 11534848 19401056
20:18:54 1954 8192000000 10186096432 10567786496 1270955888 13.430 100 82 163 1 1302141520 13201952 17983680
20:19:57 1386 8192000000 10208533728 10593751040 1295742592 13.657 100 83 167 2 1326442496 12691264 18008640
20:21:00 2429 8192000000 10216083968 10608181248 1305360512 13.744 100 66 131 1 1327815120 7816592 14638016
20:22:03 8088 8192000000 10199056864 10609754112 1309280816 13.780 100 30 60 2 1322330976 6913920 6136240
20:23:14 4645 8192000000 10207006688 10621038592 1319008368 13.868 100 19 36 0 1329675200 6311824 4355008
20:24:24 7697 8192000000 10216674976 10635214848 1328885136 13.957 100 21 42 1 1339411216 6040848 4485232
20:25:33 60003 8192000000 10216657792 10643451904 1331727296 13.983 100 31 61 0 1344226304 5280176 7218832
20:27:34 6819 8192000000 10235039312 10654273536 1339879776 14.056 100 17 33 1 1348464336 4893888 3690672
20:28:43 11741 8192000000 10217511184 10639716352 1309897744 13.785 98 14 28 0 1318418560 5476592 3044224
20:29:55 8943 8192000000 10164538992 10567598080 1214404432 12.910 94 26 53 1 1225194592 5280576 5509584
20:31:04 25145 8192000000 10121622288 10527387648 1143098448 12.245 89 24 48 0 1153604960 5179504 5327008
20:32:33 5929 8192000000 10127354976 10521960448 1216393712 12.928 98 24 48 2 1227169472 5525376 5250384
20:33:42 10193 8192000000 10136799856 10541826048 1238613120 13.133 98 17 33 0 1247978624 5498928 3866576
20:34:52 25162 8192000000 10170186848 10584784896 1282757264 13.538 100 37 73 0 1297491584 5897472 8836848
20:36:49 6373 8192000000 10201563104 10613968896 1303421456 13.726 100 37 74 0 1318443472 6442704 8579312
20:37:56 10561 8192000000 10205743792 10620936192 1313197936 13.815 100 13 26 0 1321296256 5527088 2571232
20:39:17 4823 8192000000 10252185296 10655698944 1341342368 14.070 100 45 88 1 1357966944 6737520 9887056
20:40:25 6469 8192000000 10266783120 10680332288 1357568432 14.216 100 49 98 0 1379996288 11311680 11116176
20:41:37 18143 8192000000 10267412208 10693439488 1364387936 14.277 100 35 69 0 1391267600 19112080 7767584
20:44:48 12583 8192000000 10305702960 10728620032 1389826736 14.504 100 52 103 1 1419902112 18292608 11782768
20:46:05 13964 8192000000 10322904320 10741899264 1394656928 14.547 100 59 118 0 1430260608 22119232 13484448
20:47:27 8897 8192000000 10330213312 10753609728 1401984960 14.613 100 52 106 0 1438255280 22941872 13328448
20:48:41 22051 8192000000 10327624768 10754142208 1395596816 14.556 99 42 84 0 1430741328 25212624 9931888
20:50:19 2345 8192000000 10342093504 10759077888 1410549952 14.689 100 79 154 0 1448905104 19891984 18463168
20:51:25 2504 8192000000 10340806416 10755616768 1397250976 14.571 100 84 164 2 1443664848 26812496 19601376
20:52:30 1254 8192000000 10329443696 10743005184 1388335376 14.491 100 76 149 3 1434976912 28025584 18615952
20:53:36 2832 8192000000 10300129008 10721222656 1366668224 14.297 100 54 106 2 1407658800 28108336 12882240
20:54:46 4698 8192000000 10307696208 10721693696 1365585072 14.287 99 68 136 0 1411473760 28835488 17053200
20:55:57 5867 8192000000 10311233360 10728120320 1367886528 14.308 99 61 120 4 1409417440 27053872 14477040
20:57:05 1873 8192000000 10319905008 10735013888 1377088784 14.391 100 65 128 1 1424254048 30942320 16222944
20:58:14 7036 8192000000 10324936080 10740846592 1387585968 14.484 100 65 125 0 1432783168 29938368 15258832
20:59:30 7390 8192000000 10335931792 10756337664 1383168720 14.445 99 45 87 0 1431625888 37207248 11249920
21:00:47 9240 8192000000 10329356272 10754510848 1377334176 14.393 99 36 69 1 1424716448 38759648 8622624
21:02:00 1009 8192000000 10333873392 10751774720 1385228496 14.463 99 44 87 1 1436237120 39751424 11257200
21:03:04 14376 8192000000 10322762432 10742013952 1339423968 14.052 97 35 70 0 1398279952 49446784 9409200
21:04:23 13395 8192000000 10329911856 10753675264 1366475120 14.295 100 36 72 0 1435766496 58949296 10342080
21:05:51 12435 8192000000 10334164864 10755211264 1355017920 14.193 99 39 76 0 1426209120 60467632 10723568
21:07:13 31700 8192000000 10335864048 10771718144 1370936224 14.335 100 24 48 0 1440528464 62635088 6957152
21:08:50 41428 8192000000 10314739024 10757193728 1340775504 14.064 98 24 48 0 1409313904 61652928 6885472
21:10:35 60434 8192000000 10305189488 10743046144 1382089568 14.435 100 37 74 0 1408297872 17556096 8652208
21:13:15 58866 8192000000 10300731984 10761498624 1360243536 14.240 98 18 34 0 1391870944 27594192 4033216
21:15:15 146226 8192000000 10291829568 10735091712 1369847632 14.326 99 10 20 0 1386086416 13879600 2359184
21:19:04 59337 8192000000 10306876208 10752061440 1380201472 14.418 99 19 38 0 1399455440 14965408 4288560
21:21:09 53176 8192000000 10305460416 10766336000 1392891344 14.532 100 12 24 0 1411683696 15653984 3138368
21:23:35 124225 8192000000 10304885152 10767024128 1368150560 14.310 98 10 21 0 1387393312 17351872 1890880
21:26:40 90960 8192000000 10303321216 10755219456 1382772144 14.441 99 27 52 0 1403405936 14488848 6144944
21:29:11 66408 8192000000 10304054864 10749599744 1376787456 14.388 99 54 107 1 1396901760 7463056 12651248
21:31:23 65027 8192000000 10299480224 10761670656 1385510016 14.466 99 21 42 0 1406195056 16029952 4655088
21:33:30 26113 8192000000 10278779712 10727686144 1337172352 14.032 96 12 24 1 1358569312 18931232 2465728
21:34:57 18247 8192000000 10307519600 10752655360 1382846384 14.442 100 27 54 0 1404529968 14729440 6954144
21:36:25 70043 8192000000 10290104256 10770489344 1381720384 14.432 100 24 48 0 1409088896 21968720 5399792
21:38:43 14771 8192000000 10264636064 10722476032 1348803296 14.137 98 12 24 0 1360337008 9418608 2115104
21:40:04 39667 8192000000 10269140000 10735751168 1373738656 14.361 100 7 14 0 1386504448 11212416 1553376
21:41:50 3217 8192000000 10247273328 10692845568 1323793280 13.911 97 43 80 2 1336489152 4644608 8051264
21:42:53 47015 8192000000 10272093712 10721239040 1337351056 14.034 97 17 34 0 1350421040 9213408 3856576
21:44:42 25310 8192000000 10271433280 10710605824 1314487424 13.827 96 44 87 0 1332658704 8440000 9731280
21:46:08 75179 8192000000 10292687968 10744664064 1352053776 14.166 98 15 30 0 1378201136 22707280 3440080
21:48:41 56055 8192000000 10257022944 10699808768 1261495968 13.344 93 11 21 0 1286431120 21783472 3151680
21:50:41 36156 8192000000 10273638064 10713174016 1325694000 13.928 98 30 60 0 1353353008 20945824 6713184
21:52:21 48375 8192000000 10240486352 10698854400 1285080368 13.559 96 12 24 1 1319103808 30806384 3217056
21:54:25 80898 8192000000 10264943712 10729103360 1339387136 14.052 99 18 36 0 1366260768 22724592 4149040
21:56:51 92439 8192000000 10251052128 10698612736 1312841472 13.812 97 25 50 0 1327631728 8498192 6292064
22:01:09 45190 8192000000 10272526352 10734694400 1367008976 14.300 100 5 10 0 1382137664 14189840 938848
22:03:04 64967 8192000000 10254445824 10720976896 1339747024 14.055 98 12 24 0 1354258240 11692752 2818464
22:05:14 43433 8192000000 10250509072 10699866112 1331197696 13.978 98 19 36 0 1342271888 6685392 4388800
22:06:58 56733 8192000000 10253996256 10721333248 1365194624 14.284 100 22 44 0 1378447184 8234560 5018000
22:08:57 89715 8192000000 10272624560 10742816768 1351959504 14.165 98 12 24 0 1365384544 10971056 2453984
22:11:27 47666 8192000000 10289320112 10747621376 1361076864 14.247 98 19 38 1 1372692832 7321424 4294544
22:13:16 56033 8192000000 10324984384 10780286976 1396284544 14.562 100 23 46 0 1418246752 16839536 5122672
22:15:19 99058 8192000000 10307388848 10787196928 1400620848 14.601 100 12 24 0 1427163552 23567744 2974960
22:18:01 29983 8192000000 10310891824 10763124736 1384760896 14.459 99 32 64 0 1402176352 10569328 6846128
22:19:36 24716 8192000000 10327364496 10773229568 1404234496 14.633 100 56 111 1 1429091680 12182064 12675120
22:21:05 56918 8192000000 10316872192 10765070336 1376784480 14.388 98 29 58 0 1395753504 12453008 6516016
22:23:02 88470 8192000000 10283096832 10733117440 1347488112 14.125 97 21 40 0 1359562112 7222176 4851824
22:25:37 55278 8192000000 10288108848 10750238720 1372847616 14.353 99 12 24 0 1391030112 15562576 2619920
22:27:33 55244 8192000000 10270456624 10732605440 1356801712 14.209 98 14 28 0 1368075008 7560160 3713136
22:29:35 51554 8192000000 10253722512 10712313856 1334468016 14.008 97 21 39 0 1348447840 9415760 4564064
22:31:26 51975 8192000000 10281759216 10735837184 1377074656 14.390 100 22 44 0 1390927216 8510656 5341904
22:33:24 81459 8192000000 10276730960 10739896320 1339481088 14.053 97 17 33 0 1356681696 13709472 3491136
22:35:46 56768 8192000000 10277769968 10728468480 1332062832 13.986 97 18 37 0 1352392560 15955936 4373792
22:37:43 50367 8192000000 10268885824 10742034432 1352812848 14.173 99 15 30 0 1376430240 19700544 3916848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-02-2023 22:39:59 to 09-02-2023 22:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2023 22:40:15 to 09-02-2023 22:40:17.

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          239349
MON$OLDEST_ACTIVE               240282
MON$OLDEST_SNAPSHOT             240282
MON$NEXT_TRANSACTION            240282
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                       2051808
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Thu Feb  9 22:38:53 2023
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Feb  9 22:38:53 2023
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Feb  9 22:38:54 2023
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
--- end of diff output ---

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

09.02.23 22:40:18. 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-09 22:40:20 No severe PSQL-related problems occured
09.02.23 22:40:22. End of report.