Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2023 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 15029532 kB
MemAvailable 30393588 kB
Buffers 0 kB
Cached 16657948 kB
SwapCached 3348 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-14 19:38:42
DTS_END                         2023-02-14 19:38:42.0090
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/41952
TRN_ID                          243619
ATT_ID                          687
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: 9607.17 21185 1152861
customer order: creation 177.28 335 21273
customer order: refuse 14.78 342 1773
order to supplier: creation 48.26 1213 5791
order to supplier: removal 3.53 96482 424
invoice (draft): creation 47.83 3144 5739
invoice (draft): removal 54.41 749 6529
invoice accept: apply 42.95 17079 5154
invoice accept: cancel 49.60 823 5952
customer reserve: creation 3968.93 169 476271
customer reserve: removal 4893.93 54 587272
realization accept: apply 87.39 180 10487
realization accept: cancel 86.70 103 10404
payment from customer: creation 60.72 122 7286
payment from customer: removal 5.86 76 703
payment to supplier: creation 49.63 133 5955
payment to supplier: removal 3.74 67 449
service: total inventory turnovers 4.88 1346 585
service: total monetary turnovers 5.88 26 705
service: aggregate perf. data 0.88 21681 105
service: refresh index statistics 0.03 279584 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-02-2023 19:39:42 to 14-02-2023 19:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3321
TEST_TIME 2 4425
TEST_TIME 3 4235
TEST_TIME 4 3777
TEST_TIME 5 4147
TEST_TIME 6 5042
TEST_TIME 7 5297
TEST_TIME 8 5232
TEST_TIME 9 5680
TEST_TIME 10 5841
TEST_TIME 11 6554
TEST_TIME 12 6897
TEST_TIME 13 6896
TEST_TIME 14 7383
TEST_TIME 15 7767
TEST_TIME 16 7891
TEST_TIME 17 7868
TEST_TIME 18 8165
TEST_TIME 19 8342
TEST_TIME 20 8289
TEST_TIME 21 8517
TEST_TIME 22 8414
TEST_TIME 23 8818
TEST_TIME 24 8867
TEST_TIME 25 9431
TEST_TIME 26 9624
TEST_TIME 27 9928
TEST_TIME 28 10071
TEST_TIME 29 10536
TEST_TIME 30 11209
TEST_TIME 31 11077
TEST_TIME 32 11082
TEST_TIME 33 10996
TEST_TIME 34 10853
TEST_TIME 35 10834
TEST_TIME 36 10833
TEST_TIME 37 10656
TEST_TIME 38 10540
TEST_TIME 39 10431
TEST_TIME 40 10409
TEST_TIME 41 10439
TEST_TIME 42 10485
TEST_TIME 43 10505
TEST_TIME 44 10499
TEST_TIME 45 10314
TEST_TIME 46 10141
TEST_TIME 47 10087
TEST_TIME 48 10060
TEST_TIME 49 9957
TEST_TIME 50 9863
TEST_TIME 51 9791
TEST_TIME 52 9862
TEST_TIME 53 9886
TEST_TIME 54 9994
TEST_TIME 55 9941
TEST_TIME 56 9912
TEST_TIME 57 9875
TEST_TIME 58 9788
TEST_TIME 59 9810
TEST_TIME 60 9781
TEST_TIME 61 9745
TEST_TIME 62 9614
TEST_TIME 63 9664
TEST_TIME 64 9654
TEST_TIME 65 9655
TEST_TIME 66 9624
TEST_TIME 67 9710
TEST_TIME 68 9698
TEST_TIME 69 9757
TEST_TIME 70 9782
TEST_TIME 71 9747
TEST_TIME 72 9800
TEST_TIME 73 9744
TEST_TIME 74 9799
TEST_TIME 75 9793
TEST_TIME 76 9848
TEST_TIME 77 9959
TEST_TIME 78 9890
TEST_TIME 79 9872
TEST_TIME 80 9809
TEST_TIME 81 9763
TEST_TIME 82 9701
TEST_TIME 83 9774
TEST_TIME 84 9715
TEST_TIME 85 9650
TEST_TIME 86 9663
TEST_TIME 87 9632
TEST_TIME 88 9629
TEST_TIME 89 9614
TEST_TIME 90 9557
TEST_TIME 91 9549
TEST_TIME 92 9567
TEST_TIME 93 9624
TEST_TIME 94 9639
TEST_TIME 95 9666
TEST_TIME 96 9638
TEST_TIME 97 9687
TEST_TIME 98 9636
TEST_TIME 99 9697
TEST_TIME 100 9815
TEST_TIME 101 9827
TEST_TIME 102 9867
TEST_TIME 103 9882
TEST_TIME 104 9871
TEST_TIME 105 9830
TEST_TIME 106 9798
TEST_TIME 107 9759
TEST_TIME 108 9754
TEST_TIME 109 9730
TEST_TIME 110 9732
TEST_TIME 111 9686
TEST_TIME 112 9707
TEST_TIME 113 9687
TEST_TIME 114 9628
TEST_TIME 115 9583
TEST_TIME 116 9619
TEST_TIME 117 9609
TEST_TIME 118 9655
TEST_TIME 119 9659
TEST_TIME 120 9609

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-02-2023 19:39:46 to 14-02-2023 19:39:49.

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 1594610 1594610 0 0.00 0 25426 6 0 0
doc_list_biud 1594610 1594610 0 0.00 0 26432 26 0 0
sp_add_invoice_to_stock 5238 5154 84 1.60 25 175947 17080 0 84
sp_cancel_adding_invoice 5952 5952 0 0.00 3 58488 824 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 11955 342 0 0
sp_cancel_customer_reserve 587272 587272 0 0.00 0 22370 55 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 5326 76 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 2 4243 68 0 0
sp_cancel_supplier_invoice 6535 6529 6 0.09 1 20648 749 0 6
sp_cancel_supplier_order 424 424 0 0.00 6 1049165 96483 0 0
sp_cancel_write_off 10404 10404 0 0.00 1 9824 103 0 0
sp_client_order 21273 21273 0 0.00 3 31225 335 0 0
sp_customer_reserve 476271 476271 0 0.00 0 73686 169 0 0
sp_fill_shopping_cart 32799 32799 0 0.00 1 17856 36 0 0
sp_fill_shopping_cart_clo_res 476268 476268 0 0.00 0 12656 13 0 0
sp_get_clo_for_invoice 5150 5150 0 0.00 0 29369 288 0 0
sp_kill_cost_storno 17508 17508 0 0.00 0 9089 50 0 0
sp_kill_qstorno_ret_qs2qd 1191901 1191901 0 0.00 0 22353 30 0 0
sp_kill_qty_storno 627991 627991 0 0.00 0 26431 65 0 0
sp_lock_selected_doc 21593 21593 0 0.00 0 7791 5 0 0
sp_make_cost_storno 28878 28878 0 0.00 0 25425 57 0 0
sp_multiply_rows_for_pdistr 28878 28878 0 0.00 0 6526 26 0 0
sp_multiply_rows_for_qdistr 21273 21273 0 0.00 0 26273 147 0 0
sp_pay_from_customer 7286 7286 0 0.00 4 14647 123 0 0
sp_pay_to_supplier 5955 5955 0 0.00 4 25432 134 0 0
sp_payment_common 13241 13241 0 0.00 1 19618 41 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 11947 322 0 0
sp_qd_handle_on_invoice_upd_sts 11102 11102 0 0.00 0 26429 345 0 0
sp_qd_handle_on_reserve_upd_sts 10487 10487 0 0.00 0 17328 54 0 0
sp_reserve_write_off 10487 10487 0 0.00 6 17346 180 0 0
sp_supplier_invoice 5739 5739 0 0.00 5 73842 3145 0 0
sp_supplier_order 5791 5791 0 0.00 5 34728 1213 0 0
srv_aggregate_perf_data 105 105 0 0.00 3254 257812 21681 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2571 132258 43469 0 0
srv_find_qd_qs_mism 1104106 1104106 0 0.00 0 23637 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 25 162567 1346 0 0
srv_make_money_saldo 705 705 0 0.00 1 910 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 116590 393779 279585 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 5154 5154 0 0.00 0 9323 169 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 7283 132 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 5637 16 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 2235 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 481 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 1094 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 516 9 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 32426 1721 0 0
t$perf-norm:sp_cancel_write_off 1074 1074 0 0.00 0 1714 6 0 0
t$perf-norm:sp_client_order 21273 21273 0 0.00 0 31437 20 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 5890 16 0 0
t$perf-norm:sp_pay_from_customer 7286 7286 0 0.00 0 21712 18 0 0
t$perf-norm:sp_pay_to_supplier 5955 5955 0 0.00 0 8721 16 0 0
t$perf-norm:sp_reserve_write_off 10487 10487 0 0.00 0 13218 15 0 0
t$perf-norm:sp_supplier_invoice 5739 5739 0 0.00 0 6974 22 0 0
t$perf-norm:sp_supplier_order 5791 5791 0 0.00 0 4563 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 51 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 102 4 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 962 20 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 1865 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10468 10300 168 1.60 0 4646 18 0 168
v_all_customers 38480 38480 0 0.00 0 22 1 0 0
v_all_suppliers 31554 31554 0 0.00 0 17 1 0 0
v_all_wares 42546 42546 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 859 8 0 0
v_cancel_client_order 3546 3546 0 0.00 0 2464 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 873 4 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 4597 9 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 383 4 0 0
v_cancel_supplier_invoice 828 816 12 1.44 0 3888 24 0 12
v_cancel_supplier_order 848 848 0 0.00 0 863 5 0 0
v_cancel_write_off 2148 2148 0 0.00 0 4375 15 0 0
v_max_id_clo_ord 5791 5791 0 0.00 0 445 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2251 17 0 0
v_max_id_ord_sup 5734 5734 0 0.00 0 1956 2 0 0
v_max_non_paid_invoice 1703 1703 0 0.00 0 1538 4 0 0
v_max_non_paid_realizn 4023 4023 0 0.00 0 6605 9 0 0
v_min_id_clo_ord 5791 5791 0 0.00 0 3575 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 8125 12 0 0
v_min_id_ord_sup 5734 5734 0 0.00 0 1234 3 0 0
v_min_non_paid_invoice 5955 5955 0 0.00 0 19615 32 0 0
v_min_non_paid_realizn 7286 7286 0 0.00 0 11371 29 0 0
v_our_firm 10592 10592 0 0.00 0 48 1 0 0
v_reserve_write_off 20974 20974 0 0.00 0 5956 6 0 0
x_lock_depdocs_on_canc_invoice 12073 12073 0 0.00 0 14847 113 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 5311 317 0 0
x_make_qstorno_1000_1200 5791 5791 0 0.00 1 34709 1137 0 0
x_make_qstorno_1200_2000 5739 5739 0 0.00 2 73811 3021 0 0
x_make_qstorno_2100_3300 476271 476271 0 0.00 0 73357 154 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-02-2023 19:39:50 to 14-02-2023 19: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
17:08:45 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:47 22278 8192000000 9900246736 10209570816 985501600 10.738 100 88 176 0 1015095120 6305312 23288208
17:11:18 16796 8192000000 9937107312 10259435520 1023418208 11.105 100 92 184 1 1055090944 8136336 23536400
17:12:40 16737 8192000000 9983434928 10313564160 1063546080 11.490 100 88 176 0 1094980080 9596368 21837632
17:14:04 11666 8192000000 10022541408 10368516096 1111484944 11.946 100 77 152 1 1139637808 10183120 17969744
17:15:18 7607 8192000000 10070182688 10427117568 1161252000 12.415 100 72 140 1 1187988352 10587376 16148976
17:16:32 2736 8192000000 10124857296 10492641280 1212836048 12.895 100 79 158 0 1243768512 13059856 17872608
17:17:37 1971 8192000000 10146847040 10522435584 1236387632 13.113 100 86 170 0 1268658784 13414144 18857008
17:18:42 2335 8192000000 10174980544 10558107648 1266469104 13.389 100 76 148 0 1295802576 12459072 16874400
17:19:46 1307 8192000000 10209099808 10588983296 1291950976 13.622 100 91 177 1 1326834256 14850064 20033216
17:20:49 9568 8192000000 10209559728 10604355584 1300594032 13.701 100 53 107 0 1325619200 12990848 12034320
17:21:59 5291 8192000000 10196387232 10604187648 1306480080 13.754 100 33 66 0 1320643264 7135712 7027472
17:23:05 10965 8192000000 10201322176 10615906304 1316423728 13.844 100 15 30 0 1326097008 6157520 3515760
17:24:21 17145 8192000000 10214020656 10625359872 1323190560 13.906 100 16 32 1 1332070416 5152128 3727728
17:25:39 37822 8192000000 10219407472 10639966208 1330254416 13.969 100 38 76 0 1344987744 5621888 9111440
17:27:17 6029 8192000000 10231620576 10645192704 1321194416 13.888 99 31 59 1 1333685920 5530816 6960688
17:28:23 13189 8192000000 10217396176 10639962112 1309837488 13.785 98 12 24 0 1317863328 5573520 2452320
17:29:37 11615 8192000000 10180078560 10586599424 1247154384 13.212 95 22 43 2 1257390704 5467840 4768480
17:30:50 32684 8192000000 10160118960 10566799360 1180418784 12.594 90 28 56 0 1191775936 5378112 5979040
17:32:24 10506 8192000000 10156002240 10551136256 1228384544 13.039 97 21 40 0 1237535776 4901664 4249568
17:33:35 4736 8192000000 10149603616 10544832512 1242314416 13.168 99 36 72 1 1256812368 5886176 8611776
17:34:41 7342 8192000000 10169969264 10574663680 1272302160 13.443 100 34 68 0 1286677392 5066992 9308240
17:35:52 17147 8192000000 10175552912 10586177536 1284253808 13.552 100 20 40 1 1294699520 5516320 4929392
17:37:11 9930 8192000000 10207562816 10609745920 1298546304 13.682 100 21 42 0 1309810976 5083568 6181104
17:38:21 8962 8192000000 10224735744 10631999488 1322105280 13.896 100 32 61 0 1334325056 4848784 7370992
17:39:39 4154 8192000000 10242036672 10652594176 1331950368 13.985 100 44 87 0 1354991728 13376672 9664688
17:40:46 61870 8192000000 10256576240 10680868864 1350115744 14.149 100 38 76 0 1368664112 10094864 8453504
17:42:54 24352 8192000000 10271058368 10696253440 1367470192 14.304 100 31 60 0 1386797760 12128368 7199200
17:44:23 3808 8192000000 10288474144 10710335488 1379534464 14.412 100 55 106 3 1406797360 13716480 13546416
17:45:31 8457 8192000000 10306940112 10728972288 1390245264 14.508 100 50 98 0 1415538096 13362000 11930832
17:46:45 4897 8192000000 10322702608 10739982336 1396538384 14.564 100 62 125 0 1427578432 16737104 14302944
17:47:52 2933 8192000000 10333099504 10752552960 1404734304 14.637 100 65 128 0 1439016384 19395568 14886512
17:48:59 3732 8192000000 10345347600 10763247616 1409426576 14.679 100 65 129 1 1451359760 26935424 14997760
17:50:05 3552 8192000000 10340136336 10760564736 1403296128 14.624 100 66 131 1 1447175632 27211952 16667552
17:51:14 2571 8192000000 10316604336 10729406464 1366185440 14.293 98 74 144 0 1413660384 30171664 17303280
17:52:21 3556 8192000000 10329915968 10744541184 1384273232 14.455 99 68 132 2 1432385792 32246000 15866560
17:53:33 6262 8192000000 10336954192 10762596352 1387539408 14.484 98 66 131 0 1437254240 34043184 15671648
17:54:49 5978 8192000000 10341588640 10759245824 1392901568 14.532 99 69 136 0 1439739872 30246352 16591952
17:56:03 2876 8192000000 10341244256 10756689920 1384852432 14.460 99 61 119 0 1435871296 35711968 15306896
17:57:16 3858 8192000000 10334777600 10749812736 1381643648 14.431 99 52 104 0 1431757584 36936400 13177536
17:58:23 18541 8192000000 10319301216 10742001664 1364415456 14.277 99 33 66 1 1416571056 43360112 8795488
17:59:46 8456 8192000000 10361322608 10784124928 1405721008 14.646 100 49 98 0 1470562528 51759600 13081920
18:01:09 22729 8192000000 10353481776 10780938240 1383835344 14.451 99 39 78 1 1456451600 60837648 11778608
18:02:47 5947 8192000000 10356671024 10779648000 1375647392 14.378 99 56 112 0 1448106624 57573504 14885728
18:03:58 32018 8192000000 10333795280 10773004288 1374934384 14.371 99 28 56 0 1432265344 49593456 7737504
18:06:11 25641 8192000000 10311361824 10765684736 1348711856 14.136 99 19 38 0 1412828800 57554960 6561984
18:08:16 4839 8192000000 10322976352 10754437120 1373157136 14.355 100 59 117 2 1423782208 36460368 14164704
18:09:32 61742 8192000000 10264002384 10715193344 1314259856 13.825 96 20 39 0 1328576256 10284880 4031520
18:11:34 79395 8192000000 10271784624 10713145344 1320671824 13.883 96 13 26 0 1331088608 7607328 2809456
18:14:31 72981 8192000000 10297292944 10737688576 1384210928 14.454 100 23 45 0 1398343808 8614048 5518832
18:16:56 43316 8192000000 10285646832 10726400000 1365968608 14.291 99 25 51 0 1382169376 9667600 6533168
18:19:09 86172 8192000000 10305647376 10768351232 1378839600 14.406 99 17 34 0 1399999232 17073136 4086496
18:21:36 126026 8192000000 10306930608 10749825024 1353949152 14.183 97 19 38 0 1371099408 12661056 4489200
18:24:43 35580 8192000000 10321106624 10764869632 1396382544 14.563 100 46 92 0 1415452912 8614144 10456224
18:26:22 9133 8192000000 10316700240 10757451776 1374665632 14.369 98 50 98 2 1396420512 9726592 12028288
18:27:35 52622 8192000000 10306805456 10765299712 1392124128 14.525 99 19 38 0 1411176176 14540160 4511888
18:30:28 52270 8192000000 10325557104 10776817664 1396913248 14.568 99 22 44 0 1424542992 22199184 5430560
18:32:35 69523 8192000000 10279427536 10740629504 1352695408 14.172 97 15 31 0 1371969504 15744560 3529536
18:34:48 31071 8192000000 10290993968 10746699776 1377939920 14.398 99 19 36 0 1393666832 12040032 3686880
18:36:20 86113 8192000000 10285481936 10744979456 1354919216 14.192 98 16 32 0 1369337280 10728848 3689216
18:38:57 18311 8192000000 10281747456 10734604288 1380062624 14.417 99 41 79 1 1397575520 9061824 8451072
18:41:14 111611 8192000000 10262210992 10724753408 1303521920 13.727 94 6 12 0 1316796848 11815728 1459200
18:44:06 50362 8192000000 10275373184 10723971072 1331318992 13.979 96 19 38 0 1348619584 13607744 3692848
18:46:00 19991 8192000000 10276990192 10712924160 1339877520 14.056 99 30 60 1 1354616992 8095280 6644192
18:47:25 51609 8192000000 10265916480 10729254912 1351019584 14.157 99 15 30 0 1367843984 13525552 3298848
18:49:24 84459 8192000000 10248498592 10696818688 1254158816 13.276 92 22 44 0 1275045904 15784416 5102672
18:51:49 30510 8192000000 10281200528 10724749312 1342307728 14.078 98 29 58 0 1365419440 16751472 6360240
18:53:21 30545 8192000000 10285346960 10723708928 1364838608 14.281 100 44 88 0 1389197824 14629440 9729776
18:55:04 64453 8192000000 10261455920 10724249600 1329055184 13.959 98 7 14 0 1352056576 21477408 1523984
18:57:13 118935 8192000000 10278805616 10724405248 1351618128 14.162 99 29 58 2 1366516896 8153552 6745216
19:00:20 32958 8192000000 10285840336 10732380160 1368739584 14.316 100 38 75 0 1391300480 13977424 8583472
19:01:54 49189 8192000000 10281109232 10750275584 1377242016 14.392 100 23 46 0 1393938928 11342080 5354832
19:03:43 37815 8192000000 10283959184 10755661824 1368033472 14.309 99 14 28 1 1389233792 17942960 3257360
19:05:21 47985 8192000000 10281185472 10747056128 1377729728 14.396 100 14 28 0 1398356048 16494288 4132032
19:07:09 69428 8192000000 10292971536 10752966656 1356426416 14.205 98 24 48 0 1370874368 9322096 5125856
19:10:02 34021 8192000000 10293714224 10736009216 1338810544 14.047 96 41 80 0 1359415040 11342768 9261728
19:11:53 103756 8192000000 10292474064 10741137408 1303434000 13.726 94 21 42 0 1316651040 8672848 4544192
19:14:40 56708 8192000000 10310082080 10770014208 1394035760 14.542 100 12 24 0 1413444608 16457024 2951824
19:16:38 30082 8192000000 10345633376 10781814784 1393197168 14.534 99 46 92 0 1423437488 19604576 10635744
19:18:13 90490 8192000000 10322164976 10786680832 1393481952 14.537 100 8 16 0 1415502656 19756112 2264592
19:20:46 101557 8192000000 10281605936 10737745920 1295215520 13.652 93 17 34 0 1312357712 13490624 3651568
19:23:33 132258 8192000000 10287919216 10748174336 1357459760 14.215 98 36 70 1 1377444688 11802304 8182624
19:26:45 22100 8192000000 10292024864 10745532416 1355047872 14.193 98 43 86 0 1379070400 14950592 9071936
19:28:18 62870 8192000000 10269208272 10734313472 1337919088 14.039 97 13 26 0 1351509280 10166080 3424112
19:30:21 57365 8192000000 10257752464 10722643968 1326268848 13.933 97 9 18 0 1343888864 15226688 2393328
19:32:18 63797 8192000000 10258941264 10717335552 1358103696 14.220 99 16 32 1 1372034208 9508928 4421584
19:34:22 67051 8192000000 10251540176 10714640384 1304824240 13.739 95 11 22 0 1321037968 13554672 2659056
19:36:33 61255 8192000000 10260653248 10712788992 1309533856 13.782 96 13 24 0 1321614016 9037920 3042240
19:38:34 13527 8192000000 9017266768 9220399104 187984096 2.243 14 3 6 0 190547104 1996336 566672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 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 14-02-2023 19:40:13 to 14-02-2023 19: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          244542
MON$OLDEST_ACTIVE               244543
MON$OLDEST_SNAPSHOT             244543
MON$NEXT_TRANSACTION            244543
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                       2058272
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.33664 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33664 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33664 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33664 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Feb 14 19:38:50 2023
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Feb 14 19:38:50 2023
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

14.02.23 19: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-14 19:40:18 No severe PSQL-related problems occured
14.02.23 19:40:20. End of report.