Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2023 13:38
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 14981796 kB
MemAvailable 30431480 kB
Buffers 0 kB
Cached 16731152 kB
SwapCached 3260 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-03-16 13:37:29
DTS_END                         2023-03-16 13:37:29.7680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33846
TRN_ID                          226596
ATT_ID                          641
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: 9071.21 23236 1088545
customer order: creation 179.29 438 21515
customer order: refuse 15.08 390 1810
order to supplier: creation 47.67 1279 5720
order to supplier: removal 3.29 166167 395
invoice (draft): creation 48.18 2874 5781
invoice (draft): removal 48.08 949 5769
invoice accept: apply 43.82 25227 5258
invoice accept: cancel 43.34 1192 5201
customer reserve: creation 4112.91 239 493549
customer reserve: removal 4233.04 103 507965
realization accept: apply 85.33 263 10239
realization accept: cancel 76.18 173 9141
payment from customer: creation 61.29 167 7355
payment from customer: removal 5.78 99 694
payment to supplier: creation 51.09 187 6131
payment to supplier: removal 3.43 105 411
service: total inventory turnovers 4.46 3561 535
service: total monetary turnovers 7.96 330 955
service: aggregate perf. data 0.97 14070 116
service: refresh index statistics 0.04 246905 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-03-2023 13:39:03 to 16-03-2023 13:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5079
TEST_TIME 2 4648
TEST_TIME 3 5076
TEST_TIME 4 5920
TEST_TIME 5 5985
TEST_TIME 6 5840
TEST_TIME 7 5655
TEST_TIME 8 5925
TEST_TIME 9 5999
TEST_TIME 10 6213
TEST_TIME 11 6526
TEST_TIME 12 6674
TEST_TIME 13 6880
TEST_TIME 14 7058
TEST_TIME 15 6965
TEST_TIME 16 7295
TEST_TIME 17 7474
TEST_TIME 18 7445
TEST_TIME 19 7530
TEST_TIME 20 7756
TEST_TIME 21 7812
TEST_TIME 22 8522
TEST_TIME 23 8785
TEST_TIME 24 8790
TEST_TIME 25 8879
TEST_TIME 26 9029
TEST_TIME 27 9529
TEST_TIME 28 9788
TEST_TIME 29 10459
TEST_TIME 30 10720
TEST_TIME 31 10802
TEST_TIME 32 10825
TEST_TIME 33 10669
TEST_TIME 34 10644
TEST_TIME 35 10840
TEST_TIME 36 10881
TEST_TIME 37 10874
TEST_TIME 38 10987
TEST_TIME 39 10789
TEST_TIME 40 10770
TEST_TIME 41 10731
TEST_TIME 42 10613
TEST_TIME 43 10526
TEST_TIME 44 10578
TEST_TIME 45 10457
TEST_TIME 46 10412
TEST_TIME 47 10319
TEST_TIME 48 10141
TEST_TIME 49 10004
TEST_TIME 50 9948
TEST_TIME 51 9842
TEST_TIME 52 9808
TEST_TIME 53 9753
TEST_TIME 54 9717
TEST_TIME 55 9709
TEST_TIME 56 9689
TEST_TIME 57 9638
TEST_TIME 58 9681
TEST_TIME 59 9657
TEST_TIME 60 9618
TEST_TIME 61 9679
TEST_TIME 62 9735
TEST_TIME 63 9693
TEST_TIME 64 9704
TEST_TIME 65 9676
TEST_TIME 66 9708
TEST_TIME 67 9747
TEST_TIME 68 9663
TEST_TIME 69 9731
TEST_TIME 70 9893
TEST_TIME 71 9819
TEST_TIME 72 9837
TEST_TIME 73 9872
TEST_TIME 74 9922
TEST_TIME 75 9815
TEST_TIME 76 9751
TEST_TIME 77 9687
TEST_TIME 78 9660
TEST_TIME 79 9637
TEST_TIME 80 9583
TEST_TIME 81 9559
TEST_TIME 82 9515
TEST_TIME 83 9466
TEST_TIME 84 9413
TEST_TIME 85 9358
TEST_TIME 86 9353
TEST_TIME 87 9334
TEST_TIME 88 9340
TEST_TIME 89 9373
TEST_TIME 90 9354
TEST_TIME 91 9331
TEST_TIME 92 9315
TEST_TIME 93 9330
TEST_TIME 94 9318
TEST_TIME 95 9369
TEST_TIME 96 9356
TEST_TIME 97 9419
TEST_TIME 98 9449
TEST_TIME 99 9520
TEST_TIME 100 9544
TEST_TIME 101 9491
TEST_TIME 102 9524
TEST_TIME 103 9494
TEST_TIME 104 9447
TEST_TIME 105 9391
TEST_TIME 106 9336
TEST_TIME 107 9298
TEST_TIME 108 9265
TEST_TIME 109 9220
TEST_TIME 110 9181
TEST_TIME 111 9204
TEST_TIME 112 9208
TEST_TIME 113 9164
TEST_TIME 114 9166
TEST_TIME 115 9139
TEST_TIME 116 9119
TEST_TIME 117 9083
TEST_TIME 118 9123
TEST_TIME 119 9142
TEST_TIME 120 9091

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-03-2023 13:39:07 to 16-03-2023 13:39:11.

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 1541344 1541344 0 0.00 0 17207 14 0 0
doc_list_biud 1541330 1541330 0 0.00 0 35026 39 0 0
sp_add_invoice_to_stock 5426 5258 168 3.09 46 191475 25227 0 168
sp_cancel_adding_invoice 5201 5201 0 0.00 7 99825 1193 0 0
sp_cancel_client_order 1810 1810 0 0.00 5 14419 390 0 0
sp_cancel_customer_reserve 507965 507965 0 0.00 1 33357 103 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 2421 99 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 1880 105 0 0
sp_cancel_supplier_invoice 5778 5769 9 0.15 2 35039 949 0 9
sp_cancel_supplier_order 395 395 0 0.00 8 1376565 166167 0 0
sp_cancel_write_off 9141 9141 0 0.00 2 10703 174 0 0
sp_client_order 21515 21515 0 0.00 5 31207 438 0 0
sp_customer_reserve 493549 493549 0 0.00 0 102351 240 0 0
sp_fill_shopping_cart 33002 33002 0 0.00 1 33729 77 0 0
sp_fill_shopping_cart_clo_res 493520 493520 0 0.00 0 27511 19 0 0
sp_get_clo_for_invoice 5227 5227 0 0.00 0 27453 240 0 0
sp_kill_cost_storno 15447 15447 0 0.00 0 6192 68 0 0
sp_kill_qstorno_ret_qs2qd 1031235 1031235 0 0.00 0 35024 45 0 0
sp_kill_qty_storno 545745 545745 0 0.00 0 35025 102 0 0
sp_lock_selected_doc 21346 21346 0 0.00 0 9955 7 0 0
sp_make_cost_storno 28951 28951 0 0.00 0 11343 80 0 0
sp_multiply_rows_for_pdistr 28950 28950 0 0.00 0 9530 32 0 0
sp_multiply_rows_for_qdistr 21515 21515 0 0.00 0 27586 159 0 0
sp_pay_from_customer 7355 7355 0 0.00 5 14509 168 0 0
sp_pay_to_supplier 6131 6131 0 0.00 6 21670 188 0 0
sp_payment_common 13486 13486 0 0.00 2 9413 45 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 14363 313 0 0
sp_qd_handle_on_invoice_upd_sts 10426 10426 0 0.00 0 33072 499 0 0
sp_qd_handle_on_reserve_upd_sts 10239 10239 0 0.00 0 25763 61 0 0
sp_reserve_write_off 10239 10239 0 0.00 8 25817 263 0 0
sp_supplier_invoice 5781 5781 0 0.00 9 73680 2875 0 0
sp_supplier_order 5720 5720 0 0.00 6 85185 1280 0 0
srv_aggregate_perf_data 116 116 0 0.00 2056 97626 14071 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 851 134510 34882 0 0
srv_find_qd_qs_mism 1036260 1036260 0 0.00 0 17205 11 0 0
srv_make_invnt_saldo 535 535 0 0.00 53 67860 3562 0 0
srv_make_money_saldo 955 955 0 0.00 1 17005 331 0 0
srv_recalc_idx_stat 5 5 0 0.00 83782 576185 246905 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5265 5265 0 0.00 1 17403 861 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 6571 464 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 1169 25 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 425 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 9452 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 401 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 948 36 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 102672 6567 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 3275 21 0 0
t$perf-norm:sp_client_order 21515 21515 0 0.00 0 6507 33 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 5207 21 0 0
t$perf-norm:sp_pay_from_customer 7355 7355 0 0.00 0 17186 19 0 0
t$perf-norm:sp_pay_to_supplier 6131 6131 0 0.00 0 21108 24 0 0
t$perf-norm:sp_reserve_write_off 10240 10240 0 0.00 0 6114 22 0 0
t$perf-norm:sp_supplier_invoice 5781 5781 0 0.00 0 7061 28 0 0
t$perf-norm:sp_supplier_order 5720 5720 0 0.00 0 3397 29 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 915 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 46 4 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 486 11 0 0
t$perf-norm:srv_make_money_saldo 955 955 0 0.00 0 1181 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 116 55 0 0
v_add_invoice_to_stock 10782 10446 336 3.11 0 4304 24 0 336
v_all_customers 39334 39334 0 0.00 0 222 4 0 0
v_all_suppliers 31866 31866 0 0.00 0 229 4 0 0
v_all_wares 43022 43022 0 0.00 0 235 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 4180 21 0 0
v_cancel_client_order 3620 3620 0 0.00 0 5287 13 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 638 10 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 3987 13 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 434 10 0 0
v_cancel_supplier_invoice 886 868 18 2.03 0 2768 24 0 18
v_cancel_supplier_order 790 790 0 0.00 0 731 11 0 0
v_cancel_write_off 2060 2060 0 0.00 0 2513 24 0 0
v_max_id_clo_ord 5713 5713 0 0.00 0 1513 5 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 3923 22 0 0
v_max_id_ord_sup 5773 5773 0 0.00 0 2802 6 0 0
v_max_non_paid_invoice 1682 1682 0 0.00 0 91 7 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 4705 8 0 0
v_min_id_clo_ord 5712 5712 0 0.00 0 3305 9 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 11141 26 0 0
v_min_id_ord_sup 5773 5773 0 0.00 0 7315 11 0 0
v_min_non_paid_invoice 6131 6131 0 0.00 0 4316 16 0 0
v_min_non_paid_realizn 7355 7355 0 0.00 0 5763 18 0 0
v_our_firm 11062 11062 0 0.00 0 220 4 0 0
v_reserve_write_off 20474 20474 0 0.00 0 4881 9 0 0
x_lock_depdocs_on_canc_invoice 10536 10536 0 0.00 0 16402 119 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 7676 333 0 0
x_make_qstorno_1000_1200 5720 5720 0 0.00 2 84904 1122 0 0
x_make_qstorno_1200_2000 5781 5781 0 0.00 4 73667 2672 0 0
x_make_qstorno_2100_3300 493549 493549 0 0.00 0 102348 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2023 13:39:12 to 16-03-2023 13:39:16.

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:33 3 8192000000 8801399664 8813666304 6143696 0.074 2 2 3 0 6308960 103904 61360
11:08:35 24050 8192000000 9715644416 10012311552 791045728 8.805 100 91 182 0 821879344 7883680 22949936
11:10:03 24658 8192000000 9760633168 10070130688 836754400 9.267 100 83 166 0 867474512 11618640 19101472
11:11:31 12289 8192000000 9795828000 10112077824 865942144 9.560 100 86 172 0 904852704 19146848 19763712
11:12:52 14128 8192000000 9854749424 10187055104 910420800 10.001 100 73 146 0 965882880 39685008 15777072
11:14:08 3883 8192000000 9925487360 10265595904 954214304 10.432 100 87 174 0 1038428512 65646704 18567504
11:15:15 2529 8192000000 9955823936 10309492736 994803568 10.828 100 80 157 1 1070628896 59459424 16365904
11:16:20 1862 8192000000 9990779408 10348244992 1017954032 11.052 100 90 171 1 1103059152 67733056 17372064
11:17:24 6538 8192000000 9984167248 10346680320 1030757264 11.176 100 83 158 1 1102190336 55833328 15599744
11:18:31 997 8192000000 10031036848 10407845888 1049721328 11.358 100 93 180 0 1149132768 81027152 18384288
11:19:34 8744 8192000000 10020333280 10393047040 1059835536 11.455 100 94 180 0 1137375376 59719248 17820592
11:20:45 1074 8192000000 10040365680 10417836032 1071122096 11.563 100 95 184 0 1167502576 78533792 17846688
11:21:47 1055 8192000000 10046709232 10429534208 1070616880 11.558 100 87 166 0 1166335264 79183872 16534512
11:22:49 1115 8192000000 10058529216 10445520896 1081651120 11.663 100 99 194 1 1183965472 82381120 19933232
11:23:51 1170 8192000000 10061871296 10443702272 1083395728 11.680 100 92 178 1 1184332368 83299248 17637392
11:24:53 3715 8192000000 10057821936 10454294528 1092287040 11.764 100 81 158 0 1187289840 78598320 16404480
11:25:58 1428 8192000000 10074759936 10465628160 1097033488 11.809 100 82 151 1 1196722704 83356752 16332464
11:27:00 1195 8192000000 10073507808 10460700672 1098315808 11.822 100 86 165 1 1193511680 78327696 16868176
11:28:02 1274 8192000000 10056054048 10443104256 1100396400 11.841 100 89 160 2 1176889120 59037552 17455168
11:29:05 2244 8192000000 10073363552 10472517632 1100430640 11.842 100 65 126 0 1197397792 84775280 12191872
11:30:09 1610 8192000000 10037436896 10425794560 1095119792 11.791 100 91 167 2 1157189808 46007760 16062256
11:31:11 2145 8192000000 10029412048 10418266112 1082106704 11.668 100 81 156 3 1152136720 54895200 15134816
11:32:14 1493 8192000000 10005303248 10385059840 1037615568 11.242 99 94 179 2 1124942544 68779200 18547776
11:33:16 1176 8192000000 10010396208 10389684224 1065010032 11.504 100 95 180 0 1131897776 49069872 17817872
11:34:19 979 8192000000 9961182528 10336509952 1021697712 11.088 98 84 158 4 1085756864 48379600 15679552
11:35:20 2529 8192000000 9959349328 10338758656 1017552256 11.048 99 74 142 2 1081911616 49862208 14497152
11:36:24 1058 8192000000 9992725328 10366246912 1044248352 11.305 100 90 168 0 1114471072 51285072 18937648
11:37:26 1426 8192000000 9994730128 10372603904 1051746704 11.377 100 77 153 0 1111340080 44242592 15350784
11:38:35 1017 8192000000 10024673696 10403164160 1081456384 11.661 100 94 173 4 1143753424 45522160 16774880
11:39:41 3088 8192000000 10033180208 10413756416 1072709232 11.578 99 80 140 3 1152891776 66686784 13495760
11:40:50 3385 8192000000 10088972112 10474590208 1099536288 11.833 100 87 161 2 1207597792 90974240 17087264
11:41:57 23907 8192000000 10148663520 10540982272 1118326128 12.011 100 74 147 0 1256754016 123029376 15398512
11:43:25 10770 8192000000 10152547232 10553921536 1128741648 12.109 100 69 136 0 1268046352 124716480 14588224
11:44:41 6442 8192000000 10205903008 10599641088 1131706576 12.137 100 75 142 1 1313934704 167659856 14568272
11:45:53 4334 8192000000 10221611648 10624786432 1140100576 12.216 100 73 145 2 1336655760 179932176 16623008
11:47:02 1052 8192000000 10272442272 10668240896 1154753840 12.354 100 94 176 0 1389609152 214870560 19984752
11:48:07 2068 8192000000 10280603712 10691129344 1170535824 12.502 100 89 165 2 1395030912 207067008 17428080
11:49:16 1688 8192000000 10310740768 10711760896 1173407568 12.529 100 92 177 0 1424692448 230925264 20359616
11:50:21 1501 8192000000 10369518352 10776141824 1179222528 12.583 100 94 186 1 1483930752 284112064 20596160
11:51:25 2008 8192000000 10382638704 10786906112 1168880304 12.486 99 84 160 1 1491402064 304546352 17975408
11:52:31 1000 8192000000 10409309808 10828533760 1183641376 12.624 100 94 178 2 1516986816 312718704 20626736
11:53:35 846 8192000000 10455200512 10904932352 1195729520 12.737 100 91 180 2 1559300624 342397152 21173952
11:54:41 1028 8192000000 10471925744 10883387392 1205228896 12.825 100 79 155 0 1578354992 355223504 17902592
11:55:44 1214 8192000000 10521663520 10931666944 1211274128 12.881 100 98 188 1 1627885280 393981664 22629488
11:56:51 843 8192000000 10541915312 10979672064 1201349984 12.789 100 94 179 2 1651567696 427811408 22406304
11:57:55 949 8192000000 10535033904 10980999168 1201508480 12.790 100 97 186 0 1640386688 417247120 21631088
11:58:58 1694 8192000000 10513706864 10998886400 1180162672 12.592 100 89 175 0 1621080336 419228240 21689424
12:00:03 5777 8192000000 10474151040 10940076032 1192180336 12.704 100 56 106 0 1584322112 377811968 14329808
12:01:35 17758 8192000000 10508894352 10968616960 1187657104 12.662 100 43 86 1 1624779488 425644208 11478176
12:03:04 16884 8192000000 10501751264 10970415104 1177714496 12.569 100 37 73 1 1613807216 426728544 9364176
12:04:27 27287 8192000000 10395159472 10864287744 1157879776 12.383 100 24 48 0 1520953792 356415728 6658288
12:06:16 30530 8192000000 10217756608 10735439872 1123898496 12.064 98 37 72 1 1315242016 182418112 8925408
12:07:50 7716 8192000000 10171136176 10624679936 1137811840 12.195 98 53 103 1 1260286960 111596224 10878896
12:08:59 14441 8192000000 10170478976 10633547776 1132302368 12.143 98 43 82 0 1257011440 114837856 9871216
12:10:15 12479 8192000000 10196136704 10624466944 1150276832 12.312 99 60 112 0 1291650640 128476384 12897424
12:11:29 21573 8192000000 10259639952 10735656960 1166822832 12.467 100 25 50 0 1369624752 196925056 5876864
12:12:52 71569 8192000000 10138727120 10654171136 1124828512 12.073 99 11 21 0 1274808976 147301280 2679184
12:15:05 78727 8192000000 10075568736 10531414016 1141175712 12.227 100 17 34 0 1180943104 36708480 3058912
12:17:32 134505 8192000000 10072848256 10509180928 1093699136 11.778 96 9 19 0 1111989984 16758544 1532304
12:20:47 78085 8192000000 10068656160 10551017472 1113857024 11.969 98 23 46 0 1182256896 63735024 4664848
12:23:06 15432 8192000000 10068038624 10507235328 1125112720 12.075 99 22 44 0 1164576416 34634224 4829472
12:24:25 109047 8192000000 10081070720 10513047552 1088919712 11.732 96 35 70 0 1121684992 25446096 7319184
12:27:16 106123 8192000000 10105578320 10538643456 1139646560 12.212 99 37 74 0 1185565040 38267648 7650832
12:30:03 63046 8192000000 10151675664 10592395264 1126927024 12.092 97 53 98 1 1223681520 84780688 11973808
12:32:07 13875 8192000000 10202903264 10624999424 1163567552 12.437 100 70 130 2 1292703360 113419456 15716352
12:33:23 73941 8192000000 10151913680 10629390336 1124468096 12.069 97 19 38 0 1211387360 82789584 4129680
12:35:37 54681 8192000000 10144384032 10673897472 1135665616 12.175 99 14 28 0 1253253584 114131936 3456032
12:37:34 73503 8192000000 10090897920 10533122048 1080228672 11.650 95 26 50 1 1140066944 53779648 6058624
12:39:54 50320 8192000000 10078366384 10520657920 1129698528 12.119 99 28 54 1 1155570096 20521424 5350144
12:41:45 33869 8192000000 10115009456 10546216960 1125364720 12.078 98 37 72 0 1180074944 47821200 6889024
12:43:32 53967 8192000000 10106685520 10613260288 1114595728 11.976 98 35 68 1 1233566704 111159968 7811008
12:45:28 38073 8192000000 10157020560 10594549760 1080185360 11.649 95 29 58 0 1187121776 99988896 6947520
12:47:11 53546 8192000000 10064273104 10546638848 1105431664 11.889 98 23 46 1 1171856208 61798128 4626416
12:49:10 46595 8192000000 10162344512 10626289664 1145431312 12.267 100 19 37 1 1263050368 113000128 4618928
12:50:59 55904 8192000000 10080446400 10548527104 1119151184 12.019 98 28 56 1 1169597952 44753040 5693728
12:53:02 37815 8192000000 10043575168 10488324096 1093919456 11.780 96 17 34 0 1121255760 24048320 3287984
12:54:52 30467 8192000000 10075806928 10524086272 1123914256 12.064 100 38 76 0 1176034192 44584240 7535696
12:56:25 55149 8192000000 10041192880 10502258688 1093863120 11.779 97 11 22 0 1128427696 32516912 2047664
12:58:30 54257 8192000000 10083681872 10527002624 1107431456 11.908 98 23 43 0 1155220640 43756304 4032880
13:00:25 63181 8192000000 10057893568 10554949632 1135521392 12.173 100 12 24 0 1184761296 46829184 2410720
13:02:30 14029 8192000000 10073233936 10513268736 1141678608 12.231 100 48 97 1 1179665824 28580944 9406272
13:03:46 67165 8192000000 10074104960 10535931904 1084732352 11.693 95 13 26 0 1133978992 46774768 2471872
13:05:53 7115 8192000000 10084352432 10506264576 1126088560 12.084 100 61 120 1 1180592736 42636080 11868096
13:07:02 20995 8192000000 10103248288 10557976576 1126194160 12.085 99 39 78 0 1186391952 52175312 8022480
13:08:27 11626 8192000000 10147638080 10585747456 1149395904 12.304 100 54 106 3 1255872064 95695312 10780848
13:09:39 62343 8192000000 10182058896 10624270336 1126504720 12.088 98 28 56 0 1250771888 118316048 5951120
13:11:51 25093 8192000000 10194530944 10642477056 1124214624 12.067 98 31 62 0 1291625632 160440896 6970112
13:13:21 19190 8192000000 10196515456 10628616192 1135437200 12.173 99 35 68 1 1273325536 130488496 7399840
13:14:43 67478 8192000000 10157279632 10618445824 1086977008 11.714 96 22 44 0 1252964464 160517600 5469856
13:16:50 26847 8192000000 10175166240 10607562752 1121346720 12.040 99 39 76 0 1240548608 110796128 8405760
13:18:19 91174 8192000000 10072188352 10581893120 1127224640 12.095 100 18 34 1 1194432768 63530816 3677312
13:20:50 45809 8192000000 10030765744 10488528896 1072071200 11.572 95 11 22 0 1099202896 25257296 1874400
13:22:36 61733 8192000000 10029364528 10484240384 1095549360 11.795 98 9 16 1 1128302272 30682128 2070784
13:24:40 79889 8192000000 10052041040 10533969920 1121783456 12.044 100 13 26 1 1164959568 40409856 2766256
13:27:00 41838 8192000000 10073435152 10521968640 1118842272 12.016 99 14 27 0 1160238288 38712400 2683616
13:28:48 54431 8192000000 10047589040 10502352896 1105662704 11.891 100 15 30 0 1149163408 40560752 2939952
13:31:15 29121 8192000000 10086588656 10531291136 1082092512 11.667 99 44 88 0 1170444928 78709648 9642768
13:32:47 28243 8192000000 10137229472 10572574720 1122595008 12.052 100 41 82 0 1234946816 102690304 9661504
13:34:23 27746 8192000000 10205280640 10641526784 1130040048 12.122 100 40 80 0 1285676336 146705344 8930944
13:36:01 60372 8192000000 10203592080 10681339904 1122581376 12.051 99 29 58 0 1329830384 200952400 6296608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-03-2023 13:39:18 to 16-03-2023 13:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 16-03-2023 13:39:35 to 16-03-2023 13:39:38.

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          221040
MON$OLDEST_ACTIVE               226718
MON$OLDEST_SNAPSHOT             226718
MON$NEXT_TRANSACTION            226718
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                       2071456
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2909 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2909 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2909 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 ---
:88: 
:89: mscs-test-161.budzdorov.ru    Thu Mar 16 13:37:48 2023
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Thu Mar 16 13:37:51 2023
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Thu Mar 16 13:37:51 2023
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
--- end of diff output ---

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

16.03.23 13:39:39. 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-03-16 13:39:41 No severe PSQL-related problems occured
16.03.23 13:39:43. End of report.