Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.01.2023 13: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 15085976 kB
MemAvailable 30446384 kB
Buffers 0 kB
Cached 16649760 kB
SwapCached 1640 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-24 13:37:24
DTS_END                         2023-01-24 13:37:24.8180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42108
TRN_ID                          229463
ATT_ID                          676
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: 9064.18 19655 1087702
customer order: creation 182.25 450 21870
customer order: refuse 15.96 375 1915
order to supplier: creation 47.96 1364 5755
order to supplier: removal 3.43 146013 412
invoice (draft): creation 48.13 3184 5776
invoice (draft): removal 49.33 967 5920
invoice accept: apply 43.17 24927 5180
invoice accept: cancel 43.84 1192 5261
customer reserve: creation 4016.86 239 482023
customer reserve: removal 4316.77 90 518013
realization accept: apply 86.58 265 10389
realization accept: cancel 73.97 168 8876
payment from customer: creation 62.42 163 7490
payment from customer: removal 5.78 117 694
payment to supplier: creation 51.17 180 6140
payment to supplier: removal 3.53 93 424
service: total inventory turnovers 4.09 3568 491
service: total monetary turnovers 7.93 302 951
service: aggregate perf. data 0.98 13832 117
service: refresh index statistics 0.04 195616 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-01-2023 13:39:27 to 24-01-2023 13:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6254
TEST_TIME 2 4333
TEST_TIME 3 4149
TEST_TIME 4 4321
TEST_TIME 5 4624
TEST_TIME 6 4399
TEST_TIME 7 4540
TEST_TIME 8 4861
TEST_TIME 9 5302
TEST_TIME 10 5610
TEST_TIME 11 5709
TEST_TIME 12 5925
TEST_TIME 13 6138
TEST_TIME 14 6451
TEST_TIME 15 6551
TEST_TIME 16 6780
TEST_TIME 17 6815
TEST_TIME 18 7344
TEST_TIME 19 8058
TEST_TIME 20 8196
TEST_TIME 21 8373
TEST_TIME 22 8462
TEST_TIME 23 8311
TEST_TIME 24 8171
TEST_TIME 25 8551
TEST_TIME 26 8646
TEST_TIME 27 8486
TEST_TIME 28 9368
TEST_TIME 29 10184
TEST_TIME 30 10359
TEST_TIME 31 10734
TEST_TIME 32 11129
TEST_TIME 33 10939
TEST_TIME 34 11055
TEST_TIME 35 10962
TEST_TIME 36 10957
TEST_TIME 37 10844
TEST_TIME 38 10872
TEST_TIME 39 10758
TEST_TIME 40 10719
TEST_TIME 41 10842
TEST_TIME 42 10639
TEST_TIME 43 10425
TEST_TIME 44 10363
TEST_TIME 45 10271
TEST_TIME 46 10214
TEST_TIME 47 10168
TEST_TIME 48 10128
TEST_TIME 49 10085
TEST_TIME 50 10048
TEST_TIME 51 10052
TEST_TIME 52 10040
TEST_TIME 53 9933
TEST_TIME 54 9898
TEST_TIME 55 9868
TEST_TIME 56 9785
TEST_TIME 57 9833
TEST_TIME 58 9796
TEST_TIME 59 9804
TEST_TIME 60 9786
TEST_TIME 61 9782
TEST_TIME 62 9830
TEST_TIME 63 9778
TEST_TIME 64 9780
TEST_TIME 65 9894
TEST_TIME 66 9832
TEST_TIME 67 9983
TEST_TIME 68 10052
TEST_TIME 69 10036
TEST_TIME 70 10044
TEST_TIME 71 10021
TEST_TIME 72 9935
TEST_TIME 73 9891
TEST_TIME 74 9850
TEST_TIME 75 9766
TEST_TIME 76 9717
TEST_TIME 77 9720
TEST_TIME 78 9678
TEST_TIME 79 9663
TEST_TIME 80 9582
TEST_TIME 81 9603
TEST_TIME 82 9575
TEST_TIME 83 9516
TEST_TIME 84 9501
TEST_TIME 85 9529
TEST_TIME 86 9508
TEST_TIME 87 9537
TEST_TIME 88 9590
TEST_TIME 89 9547
TEST_TIME 90 9526
TEST_TIME 91 9558
TEST_TIME 92 9602
TEST_TIME 93 9634
TEST_TIME 94 9619
TEST_TIME 95 9579
TEST_TIME 96 9530
TEST_TIME 97 9470
TEST_TIME 98 9505
TEST_TIME 99 9466
TEST_TIME 100 9429
TEST_TIME 101 9357
TEST_TIME 102 9324
TEST_TIME 103 9289
TEST_TIME 104 9282
TEST_TIME 105 9256
TEST_TIME 106 9303
TEST_TIME 107 9301
TEST_TIME 108 9292
TEST_TIME 109 9310
TEST_TIME 110 9318
TEST_TIME 111 9331
TEST_TIME 112 9294
TEST_TIME 113 9305
TEST_TIME 114 9369
TEST_TIME 115 9369
TEST_TIME 116 9299
TEST_TIME 117 9247
TEST_TIME 118 9191
TEST_TIME 119 9144
TEST_TIME 120 9086

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-01-2023 13:39:31 to 24-01-2023 13:39:35.

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 1531252 1531252 0 0.00 0 82176 14 0 0
doc_list_biud 1531244 1531244 0 0.00 0 22620 36 0 0
sp_add_invoice_to_stock 5260 5180 80 1.52 34 200027 24927 0 80
sp_cancel_adding_invoice 5261 5261 0 0.00 6 101858 1192 0 0
sp_cancel_client_order 1915 1915 0 0.00 6 12331 376 0 0
sp_cancel_customer_reserve 518013 518013 0 0.00 1 22646 91 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 6055 117 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 4 1744 93 0 0
sp_cancel_supplier_invoice 5927 5920 7 0.11 4 20490 967 0 7
sp_cancel_supplier_order 412 412 0 0.00 13 1457846 146013 0 0
sp_cancel_write_off 8876 8876 0 0.00 2 18341 168 0 0
sp_client_order 21870 21870 0 0.00 5 21389 451 0 0
sp_customer_reserve 482023 482023 0 0.00 0 146580 240 0 0
sp_fill_shopping_cart 33396 33396 0 0.00 1 20814 78 0 0
sp_fill_shopping_cart_clo_res 482005 482005 0 0.00 0 19325 17 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 21754 258 0 0
sp_kill_cost_storno 15255 15255 0 0.00 0 6936 66 0 0
sp_kill_qstorno_ret_qs2qd 1051234 1051234 0 0.00 0 22618 40 0 0
sp_kill_qty_storno 555921 555921 0 0.00 0 22620 92 0 0
sp_lock_selected_doc 21497 21497 0 0.00 0 7295 6 0 0
sp_make_cost_storno 29156 29156 0 0.00 0 12942 73 0 0
sp_multiply_rows_for_pdistr 29156 29156 0 0.00 0 11192 36 0 0
sp_multiply_rows_for_qdistr 21869 21869 0 0.00 0 12381 172 0 0
sp_pay_from_customer 7490 7490 0 0.00 5 15836 164 0 0
sp_pay_to_supplier 6140 6140 0 0.00 6 13887 181 0 0
sp_payment_common 13629 13629 0 0.00 2 6483 40 0 0
sp_qd_handle_on_cancel_clo 1915 1915 0 0.00 1 12314 309 0 0
sp_qd_handle_on_invoice_upd_sts 10398 10398 0 0.00 0 14695 480 0 0
sp_qd_handle_on_reserve_upd_sts 10387 10387 0 0.00 0 19377 69 0 0
sp_reserve_write_off 10389 10389 0 0.00 8 25282 265 0 0
sp_supplier_invoice 5776 5776 0 0.00 8 80607 3184 0 0
sp_supplier_order 5755 5755 0 0.00 6 23328 1364 0 0
srv_aggregate_perf_data 117 117 0 0.00 2191 135574 13832 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 845 148692 35336 0 0
srv_find_qd_qs_mism 1036220 1036220 0 0.00 0 81944 12 0 0
srv_make_invnt_saldo 491 491 0 0.00 55 94939 3569 0 0
srv_make_money_saldo 951 951 0 0.00 1 12150 303 0 0
srv_recalc_idx_stat 5 5 0 0.00 105258 308721 195616 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5181 5181 0 0.00 1 15794 707 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 6503 407 0 0
t$perf-norm:sp_cancel_client_order 1915 1915 0 0.00 0 1706 24 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1763 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 2058 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1514 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 643 29 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 89982 6278 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 3150 20 0 0
t$perf-norm:sp_client_order 21870 21870 0 0.00 0 17124 33 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 2555 17 0 0
t$perf-norm:sp_pay_from_customer 7490 7490 0 0.00 0 3709 15 0 0
t$perf-norm:sp_pay_to_supplier 6140 6140 0 0.00 0 5271 19 0 0
t$perf-norm:sp_reserve_write_off 10389 10389 0 0.00 0 14200 24 0 0
t$perf-norm:sp_supplier_invoice 5776 5776 0 0.00 0 14249 28 0 0
t$perf-norm:sp_supplier_order 5755 5755 0 0.00 0 12400 34 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 1189 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 825 22 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 1862 17 0 0
t$perf-norm:srv_make_money_saldo 951 951 0 0.00 0 2148 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 191 83 0 0
v_add_invoice_to_stock 10434 10274 160 1.53 0 8418 24 0 160
v_all_customers 40300 40300 0 0.00 0 129 3 0 0
v_all_suppliers 32250 32250 0 0.00 0 78 4 0 0
v_all_wares 43734 43734 0 0.00 0 231 3 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2435 18 0 0
v_cancel_client_order 3830 3830 0 0.00 0 2201 10 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 2194 14 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 1 2600 11 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1652 11 0 0
v_cancel_supplier_invoice 806 792 14 1.73 0 942 21 0 14
v_cancel_supplier_order 824 824 0 0.00 0 2353 21 0 0
v_cancel_write_off 2028 2028 0 0.00 0 14479 33 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 661 4 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 1996 13 0 0
v_max_id_ord_sup 5773 5773 0 0.00 0 3984 7 0 0
v_max_non_paid_invoice 1541 1541 0 0.00 0 1087 8 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 3364 8 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 1210 7 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 2772 10 0 0
v_min_id_ord_sup 5773 5773 0 0.00 0 6892 13 0 0
v_min_non_paid_invoice 6139 6139 0 0.00 0 5362 16 0 0
v_min_non_paid_realizn 7489 7489 0 0.00 0 6393 14 0 0
v_our_firm 10920 10920 0 0.00 0 59 4 0 0
v_reserve_write_off 20774 20774 0 0.00 0 12891 13 0 0
x_lock_depdocs_on_canc_invoice 10785 10785 0 0.00 0 21890 122 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 8505 313 0 0
x_make_qstorno_1000_1200 5755 5755 0 0.00 2 22723 1224 0 0
x_make_qstorno_1200_2000 5776 5776 0 0.00 3 80363 2948 0 0
x_make_qstorno_2100_3300 482023 482023 0 0.00 0 146579 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-01-2023 13:39:36 to 24-01-2023 13:39:40.

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
11:07:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:29 15150 8192000000 9711338368 10009989120 797030256 8.866 100 78 156 0 822851664 6800848 19020560
11:09:52 22845 8192000000 9762465088 10071498752 839871984 9.298 100 79 158 0 869231520 11066944 18292592
11:11:21 20006 8192000000 9818640656 10138361856 876217472 9.662 100 91 182 0 915303744 18040016 21046256
11:12:46 9412 8192000000 9861314464 10192728064 920222832 10.098 100 79 158 0 978422128 41104736 17094560
11:13:59 5614 8192000000 9915701376 10257010688 949389104 10.385 100 82 160 3 1026128464 59775360 16964000
11:15:08 3105 8192000000 9950677232 10300735488 988056352 10.763 100 90 176 1 1065896256 59379584 18460320
11:16:12 1450 8192000000 9994207328 10351210496 1016114352 11.034 100 94 182 0 1109667952 74838912 18714688
11:17:16 922 8192000000 10005994368 10374930432 1042393056 11.288 100 88 166 2 1122779136 63801008 16585072
11:18:19 915 8192000000 10027550608 10400673792 1058377920 11.441 100 97 187 2 1151214544 73504368 19332256
11:19:21 1139 8192000000 10028483632 10408468480 1071454688 11.566 100 95 179 3 1149048112 59528432 18064992
11:20:25 1024 8192000000 10032241696 10412711936 1077109328 11.620 100 92 180 0 1156061984 60955616 17997040
11:21:27 942 8192000000 10043187824 10428383232 1080508208 11.652 100 94 177 3 1166000784 67694528 17798048
11:22:30 1257 8192000000 10033197840 10423992320 1082876176 11.675 100 88 167 3 1157893712 57521424 17496112
11:23:34 1106 8192000000 10058566896 10446835712 1086708352 11.711 100 97 194 0 1178804352 72399616 19696384
11:24:36 1045 8192000000 10045875392 10434826240 1089208832 11.735 100 94 182 0 1170381872 61756192 19416848
11:25:38 1008 8192000000 10042776048 10435919872 1093211616 11.773 100 93 175 1 1167227728 55339072 18677040
11:26:40 7531 8192000000 10044546960 10435645440 1092079200 11.762 100 81 140 4 1161783360 55723248 13980912
11:27:48 1387 8192000000 10058819040 10452860928 1093897952 11.780 100 83 162 4 1179801568 69011232 16892384
11:28:51 1150 8192000000 10061651536 10449129472 1093144784 11.773 100 94 175 0 1183513280 72589760 17778736
11:29:52 969 8192000000 10037295104 10425032704 1076239120 11.612 100 86 160 0 1158950080 66009456 16701504
11:30:54 1541 8192000000 10007216544 10387099648 1047007904 11.332 98 72 132 3 1125607392 65859232 12740256
11:31:57 1039 8192000000 10012525456 10391883776 1066904112 11.523 100 87 164 0 1137276400 52582384 17789904
11:32:59 1027 8192000000 9990189072 10368786432 1048228944 11.344 100 84 161 1 1117393712 53096864 16067904
11:34:01 1142 8192000000 9975673632 10345140224 1018102816 11.054 99 90 176 3 1105398240 67821552 19473872
11:35:03 1940 8192000000 9965783536 10337136640 1012485696 10.999 98 61 118 1 1086384000 61397920 12500384
11:36:05 3725 8192000000 9979016432 10356785152 1025019968 11.120 99 71 133 1 1096877856 58563824 13294064
11:37:11 926 8192000000 9986676192 10361798656 1045552944 11.318 100 90 163 2 1110442032 47792752 17096336
11:38:20 6951 8192000000 9984370368 10372448256 1058546784 11.443 100 69 120 4 1112256000 39668208 14041008
11:39:31 4812 8192000000 10027414784 10407895040 1074236656 11.593 100 69 123 2 1137941520 51460624 12244240
11:40:41 6161 8192000000 10087134864 10473058304 1092269904 11.764 100 76 147 0 1204151200 97297760 14583536
11:41:52 11194 8192000000 10114504032 10514001920 1099713872 11.835 100 67 128 0 1225586880 112610624 13262384
11:43:08 1068 8192000000 10134190672 10527211520 1120202736 12.029 100 78 145 2 1254168208 116777888 17187584
11:44:12 1129 8192000000 10201540192 10596311040 1128696064 12.109 100 85 165 3 1322129584 175195888 18237632
11:45:18 1472 8192000000 10225138048 10633551872 1139854336 12.214 100 79 150 0 1349129072 192436592 16838144
11:46:23 8602 8192000000 10238584368 10653282304 1151766144 12.326 100 74 144 1 1355447504 188102864 15578496
11:47:36 1372 8192000000 10263305936 10678673408 1169946576 12.496 100 79 146 1 1378877616 192280384 16650656
11:48:41 1039 8192000000 10311820704 10712014848 1177926384 12.571 100 89 166 1 1419898432 223264800 18707248
11:49:44 955 8192000000 10385082320 10790592512 1185212496 12.639 100 94 177 0 1495137632 289338544 20586592
11:50:48 1344 8192000000 10406405664 10828734464 1192797584 12.709 100 92 174 3 1517274224 303056864 21419776
11:51:53 837 8192000000 10442349184 10859696128 1189229888 12.676 100 95 183 1 1549674256 338161520 22282848
11:52:57 863 8192000000 10441064384 10868482048 1182313184 12.612 100 92 176 0 1547098176 343512448 21272544
11:54:03 2731 8192000000 10468318720 10881581056 1188809216 12.672 100 82 160 1 1571941504 363685088 19447200
11:55:09 882 8192000000 10461507744 10927972352 1198039872 12.758 100 97 181 2 1570612224 349901712 22670640
11:56:13 3584 8192000000 10421514576 10910101504 1195178336 12.732 100 70 140 1 1527972496 315534624 17259536
11:57:21 1010 8192000000 10399791776 10820046848 1185600560 12.642 100 85 154 0 1509722256 306225584 17896112
11:58:26 1174 8192000000 10419922976 10851045376 1186591312 12.652 100 85 161 1 1522265104 316226624 19447168
11:59:31 17123 8192000000 10464447264 10917113856 1197703024 12.755 100 44 88 0 1556484224 347895280 10885920
12:00:54 2949 8192000000 10499743424 10935599104 1190074512 12.684 100 48 92 0 1602766688 400819584 11872592
12:02:02 8666 8192000000 10557147936 11029532672 1205243408 12.825 100 63 123 1 1662478560 442240304 14994848
12:03:16 16376 8192000000 10555879600 11024392192 1191129680 12.694 99 47 94 0 1640780816 437938432 11712704
12:04:35 17119 8192000000 10559664416 11076116480 1187267488 12.658 100 35 70 0 1701087328 503772960 10046880
12:05:59 30973 8192000000 10327308240 10915483648 1150830624 12.317 98 28 52 2 1474156288 316193632 7132032
12:07:45 53455 8192000000 10167572192 10667044864 1085936176 11.704 94 16 32 0 1320255824 230323328 3996320
12:09:41 80173 8192000000 10097499648 10549182464 1076754656 11.617 94 20 40 0 1128035344 47554816 3725872
12:12:07 34044 8192000000 10106194416 10536488960 1127149296 12.094 98 39 77 0 1182733488 46742720 8841472
12:13:42 27781 8192000000 10096211216 10555432960 1125339072 12.077 99 14 27 0 1167900256 40367232 2193952
12:15:16 16639 8192000000 10077977360 10499727360 1121123088 12.038 98 60 105 3 1153328416 20575392 11629936
12:16:51 18834 8192000000 10108423776 10530816000 1140790560 12.223 100 57 112 0 1197098688 43473376 12834752
12:18:11 148692 8192000000 10061613328 10493943808 1090768704 11.750 96 21 39 0 1145924528 51574240 3581584
12:21:42 32478 8192000000 10075686176 10514640896 1121460400 12.041 99 55 105 3 1172769856 40066032 11243424
12:23:15 107695 8192000000 10072274080 10487951360 1096320512 11.803 96 71 135 2 1134477328 22970224 15186592
12:26:05 16492 8192000000 10079128896 10498293760 1107539280 11.909 99 49 93 2 1158423536 41287216 9597040
12:27:49 20363 8192000000 10119643312 10536087552 1110090048 11.933 99 52 101 1 1203112976 81578656 11444272
12:29:12 43982 8192000000 10065286384 10508406784 1112192208 11.953 100 15 31 0 1187940528 72199360 3548960
12:30:57 50985 8192000000 10113083184 10565681152 1121524064 12.041 100 12 24 0 1219973648 95420320 3029264
12:32:49 115657 8192000000 10079808976 10587529216 1106571680 11.900 98 19 38 0 1196182560 84919680 4691200
12:35:50 66499 8192000000 10071769360 10520588288 1116259312 11.992 98 30 60 0 1186395536 63628224 6508000
12:37:58 6364 8192000000 10083952736 10520100864 1129054624 12.112 100 42 81 3 1188191824 51064784 8072416
12:39:12 63978 8192000000 10129914944 10557378560 1136310256 12.181 99 22 43 0 1198710368 58075168 4324944
12:41:18 17829 8192000000 10172665216 10628780032 1135254256 12.171 99 37 74 0 1258680112 115784160 7641696
12:42:41 38572 8192000000 10144395408 10623967232 1127420352 12.097 99 17 33 0 1241880400 110639168 3820880
12:44:20 29434 8192000000 10095747344 10531430400 1102911392 11.865 97 33 64 0 1163493184 53680320 6901472
12:45:50 51389 8192000000 10087674432 10517340160 1130099088 12.122 100 24 46 0 1187567072 52634000 4833984
12:47:43 46543 8192000000 10108915696 10559938560 1125829664 12.082 99 40 80 0 1197382368 63616240 7936464
12:49:30 58834 8192000000 10099086864 10559238144 1144711344 12.260 100 49 96 2 1210544816 54585840 11247632
12:52:12 36580 8192000000 10121819008 10569330688 1149750160 12.307 100 36 72 0 1218314704 61008208 7556336
12:53:49 64326 8192000000 10097459008 10592153600 1123851920 12.063 98 19 38 0 1210753808 82252064 4649824
12:55:58 61942 8192000000 10070268816 10547871744 1133876704 12.158 100 19 38 0 1186795376 48954208 3964464
12:58:02 80360 8192000000 10049774608 10516041728 1101080464 11.848 97 13 26 0 1157802544 53789232 2932848
13:00:30 54128 8192000000 10103921600 10536529920 1093338848 11.774 97 42 84 0 1157162000 55132336 8690816
13:02:27 37231 8192000000 10078047936 10537910272 1072932944 11.580 96 21 43 0 1168365760 90565120 4867696
13:04:05 29298 8192000000 10119915728 10575011840 1113333472 11.964 100 42 82 0 1215392144 93523808 8534864
13:05:35 26729 8192000000 10054141952 10475954176 1076422912 11.613 96 35 69 0 1111213744 27940128 6850704
13:07:05 25432 8192000000 10155432592 10582831104 1129325808 12.115 100 47 94 0 1238733520 99675072 9732640
13:08:32 51947 8192000000 10101294144 10546712576 1114301568 11.973 98 31 61 1 1206244672 85433296 6509808
13:10:28 13452 8192000000 10096598672 10523828224 1132968544 12.149 100 44 88 0 1182003840 40288464 8746832
13:11:44 102919 8192000000 10078111680 10532044800 1123409872 12.059 99 27 54 1 1186630240 58129872 5090496
13:14:38 54029 8192000000 10059756432 10543255552 1132945360 12.149 100 32 64 0 1208232176 69075648 6211168
13:16:33 66793 8192000000 10038021216 10496999424 1093492560 11.776 97 9 18 0 1123163264 27305056 2365648
13:18:39 93014 8192000000 10047390688 10522263552 1115854880 11.988 99 16 31 0 1155853968 36747424 3251664
13:21:13 26212 8192000000 10066072864 10518867968 1113770928 11.968 98 25 48 0 1166635904 47722080 5142896
13:22:51 33494 8192000000 10093078016 10555969536 1113234816 11.963 99 48 96 1 1195442800 72365856 9842128
13:24:25 17819 8192000000 10088559536 10525040640 1130373392 12.125 100 50 96 0 1186094368 46228064 9492912
13:25:43 44147 8192000000 10100660480 10559860736 1098860208 11.827 97 20 40 0 1157157216 54306464 3990544
13:27:38 43271 8192000000 10105408320 10551726080 1115009392 11.980 98 31 60 0 1174441216 52496160 6935664
13:29:27 57196 8192000000 10141544832 10580017152 1106411616 11.898 98 41 82 1 1204804784 89506128 8887040
13:31:33 109391 8192000000 10052127456 10540195840 1093879872 11.780 97 6 12 0 1135651488 40474544 1297072
13:34:32 67717 8192000000 10032411760 10497945600 1127634352 12.099 100 10 20 0 1144795024 14934688 2225984
13:36:46 49439 8192000000 9605598864 10004877312 721664896 8.096 64 4 8 0 745249248 22787968 796384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-01-2023 13:39:42 to 24-01-2023 13:39:58.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-01-2023 13:39:59 to 24-01-2023 13:40:01.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229640
MON$OLDEST_ACTIVE               229641
MON$OLDEST_SNAPSHOT             229641
MON$NEXT_TRANSACTION            229641
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-30 07:30:08.6030 GMT
MON$PAGES                       2059744
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2891 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2891 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2891 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2891 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Jan 24 13:37:46 2023
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
--- end of diff output ---

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

24.01.23 13:40:03. There are 3 logs in /var/tmp/logs-oltp40 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-01-24 13:40:05 No severe PSQL-related problems occured
24.01.23 13:40:07. End of report.