Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2022 01:40
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 15485464 kB
MemAvailable 30529012 kB
Buffers 0 kB
Cached 16258500 kB
SwapCached 2172 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 2022-10-17 01:38:30
DTS_END                         2022-10-17 01:38:30.2760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49538
TRN_ID                          235812
ATT_ID                          672
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: 9411.88 23843 1129425
customer order: creation 169.23 365 20307
customer order: refuse 14.47 318 1736
order to supplier: creation 44.58 1273 5350
order to supplier: removal 3.53 100922 423
invoice (draft): creation 45.50 3259 5460
invoice (draft): removal 55.78 780 6694
invoice accept: apply 40.66 16341 4879
invoice accept: cancel 50.44 734 6053
customer reserve: creation 3758.55 160 451026
customer reserve: removal 4929.41 57 591529
realization accept: apply 80.94 183 9713
realization accept: cancel 90.22 99 10826
payment from customer: creation 58.80 117 7056
payment from customer: removal 5.93 61 712
payment to supplier: creation 48.58 124 5829
payment to supplier: removal 3.43 107 412
service: total inventory turnovers 4.53 1217 544
service: total monetary turnovers 6.43 27 771
service: aggregate perf. data 0.84 19526 101
service: refresh index statistics 0.03 331184 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2022 01:40:35 to 17-10-2022 01:40:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1703
TEST_TIME 2 2863
TEST_TIME 3 2889
TEST_TIME 4 3623
TEST_TIME 5 4175
TEST_TIME 6 4005
TEST_TIME 7 3932
TEST_TIME 8 4039
TEST_TIME 9 4273
TEST_TIME 10 4581
TEST_TIME 11 5298
TEST_TIME 12 5844
TEST_TIME 13 6192
TEST_TIME 14 6652
TEST_TIME 15 6743
TEST_TIME 16 7373
TEST_TIME 17 7615
TEST_TIME 18 7884
TEST_TIME 19 8036
TEST_TIME 20 8615
TEST_TIME 21 8855
TEST_TIME 22 9455
TEST_TIME 23 9582
TEST_TIME 24 9658
TEST_TIME 25 9569
TEST_TIME 26 9590
TEST_TIME 27 10090
TEST_TIME 28 11021
TEST_TIME 29 10819
TEST_TIME 30 10495
TEST_TIME 31 10356
TEST_TIME 32 10600
TEST_TIME 33 10596
TEST_TIME 34 10466
TEST_TIME 35 10412
TEST_TIME 36 10279
TEST_TIME 37 10109
TEST_TIME 38 10057
TEST_TIME 39 10058
TEST_TIME 40 10098
TEST_TIME 41 10020
TEST_TIME 42 10072
TEST_TIME 43 10061
TEST_TIME 44 9931
TEST_TIME 45 9854
TEST_TIME 46 9913
TEST_TIME 47 9820
TEST_TIME 48 9760
TEST_TIME 49 9661
TEST_TIME 50 9603
TEST_TIME 51 9545
TEST_TIME 52 9569
TEST_TIME 53 9426
TEST_TIME 54 9414
TEST_TIME 55 9418
TEST_TIME 56 9412
TEST_TIME 57 9457
TEST_TIME 58 9509
TEST_TIME 59 9575
TEST_TIME 60 9576
TEST_TIME 61 9639
TEST_TIME 62 9642
TEST_TIME 63 9738
TEST_TIME 64 9738
TEST_TIME 65 9781
TEST_TIME 66 9774
TEST_TIME 67 9768
TEST_TIME 68 9748
TEST_TIME 69 9810
TEST_TIME 70 9726
TEST_TIME 71 9636
TEST_TIME 72 9614
TEST_TIME 73 9551
TEST_TIME 74 9583
TEST_TIME 75 9583
TEST_TIME 76 9528
TEST_TIME 77 9464
TEST_TIME 78 9448
TEST_TIME 79 9521
TEST_TIME 80 9548
TEST_TIME 81 9510
TEST_TIME 82 9514
TEST_TIME 83 9580
TEST_TIME 84 9644
TEST_TIME 85 9634
TEST_TIME 86 9690
TEST_TIME 87 9698
TEST_TIME 88 9656
TEST_TIME 89 9714
TEST_TIME 90 9785
TEST_TIME 91 9812
TEST_TIME 92 9735
TEST_TIME 93 9653
TEST_TIME 94 9631
TEST_TIME 95 9565
TEST_TIME 96 9558
TEST_TIME 97 9564
TEST_TIME 98 9520
TEST_TIME 99 9458
TEST_TIME 100 9503
TEST_TIME 101 9560
TEST_TIME 102 9619
TEST_TIME 103 9628
TEST_TIME 104 9629
TEST_TIME 105 9630
TEST_TIME 106 9610
TEST_TIME 107 9638
TEST_TIME 108 9685
TEST_TIME 109 9728
TEST_TIME 110 9702
TEST_TIME 111 9650
TEST_TIME 112 9594
TEST_TIME 113 9633
TEST_TIME 114 9576
TEST_TIME 115 9559
TEST_TIME 116 9565
TEST_TIME 117 9514
TEST_TIME 118 9482
TEST_TIME 119 9486
TEST_TIME 120 9412

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2022 01:40:38 to 17-10-2022 01:40:42.

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 1547498 1547498 0 0.00 0 16112 6 0 0
doc_list_biud 1547498 1547498 0 0.00 0 69228 28 0 0
sp_add_invoice_to_stock 4985 4879 106 2.12 39 176296 16342 0 106
sp_cancel_adding_invoice 6053 6053 0 0.00 4 63452 734 0 0
sp_cancel_client_order 1736 1736 0 0.00 3 11658 318 0 0
sp_cancel_customer_reserve 591529 591529 0 0.00 0 25229 58 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 2718 61 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 13063 108 0 0
sp_cancel_supplier_invoice 6706 6694 12 0.17 1 69231 781 0 12
sp_cancel_supplier_order 423 423 0 0.00 6 970025 100923 0 0
sp_cancel_write_off 10826 10826 0 0.00 1 8126 100 0 0
sp_client_order 20307 20307 0 0.00 3 15480 366 0 0
sp_customer_reserve 451026 451026 0 0.00 0 132814 160 0 0
sp_fill_shopping_cart 31112 31112 0 0.00 1 20029 38 0 0
sp_fill_shopping_cart_clo_res 451024 451024 0 0.00 0 14693 15 0 0
sp_get_clo_for_invoice 4877 4877 0 0.00 0 28379 315 0 0
sp_kill_cost_storno 18003 18003 0 0.00 0 6744 53 0 0
sp_kill_qstorno_ret_qs2qd 1201001 1201001 0 0.00 0 69227 31 0 0
sp_kill_qty_storno 631851 631851 0 0.00 0 69228 67 0 0
sp_lock_selected_doc 20329 20329 0 0.00 0 8234 8 0 0
sp_make_cost_storno 27475 27475 0 0.00 0 15640 58 0 0
sp_multiply_rows_for_pdistr 27475 27475 0 0.00 0 8078 31 0 0
sp_multiply_rows_for_qdistr 20307 20307 0 0.00 0 14992 180 0 0
sp_pay_from_customer 7056 7056 0 0.00 4 15402 117 0 0
sp_pay_to_supplier 5829 5829 0 0.00 4 15750 124 0 0
sp_payment_common 12885 12885 0 0.00 1 11523 31 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 11650 284 0 0
sp_qd_handle_on_invoice_upd_sts 10930 10930 0 0.00 0 25912 303 0 0
sp_qd_handle_on_reserve_upd_sts 9713 9713 0 0.00 0 17285 55 0 0
sp_reserve_write_off 9713 9713 0 0.00 6 17353 184 0 0
sp_supplier_invoice 5460 5460 0 0.00 7 78182 3260 0 0
sp_supplier_order 5350 5350 0 0.00 5 50634 1274 0 0
srv_aggregate_perf_data 101 101 0 0.00 2303 224778 19526 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1135 167728 45380 0 0
srv_find_qd_qs_mism 1082673 1082673 0 0.00 0 16095 4 0 0
srv_make_invnt_saldo 544 544 0 0.00 39 88237 1217 0 0
srv_make_money_saldo 771 771 0 0.00 1 568 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 231187 470268 331185 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4879 4879 0 0.00 0 12304 163 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 5936 121 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 2389 14 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 7725 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 3929 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 677 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 4167 21 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 25484 1623 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 9019 17 0 0
t$perf-norm:sp_client_order 20307 20307 0 0.00 0 14348 19 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 3914 10 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 3658 9 0 0
t$perf-norm:sp_pay_to_supplier 5829 5829 0 0.00 0 3515 8 0 0
t$perf-norm:sp_reserve_write_off 9713 9713 0 0.00 0 18290 16 0 0
t$perf-norm:sp_supplier_invoice 5460 5460 0 0.00 0 7818 16 0 0
t$perf-norm:sp_supplier_order 5350 5350 0 0.00 0 6176 12 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 390 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 160 5 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 532 16 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 998 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9956 9744 212 2.12 0 9628 19 0 212
v_all_customers 37460 37460 0 0.00 0 93 1 0 0
v_all_suppliers 30134 30134 0 0.00 0 36 1 0 0
v_all_wares 40612 40612 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 154 6 0 0
v_cancel_client_order 3472 3472 0 0.00 0 2256 5 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1203 4 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 822 4 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2802 11 0 0
v_cancel_supplier_invoice 834 810 24 2.87 0 839 14 0 24
v_cancel_supplier_order 846 846 0 0.00 0 1680 6 0 0
v_cancel_write_off 1938 1938 0 0.00 0 2321 14 0 0
v_max_id_clo_ord 5349 5349 0 0.00 0 111 1 0 0
v_max_id_clo_res 928 928 0 0.00 0 5521 15 0 0
v_max_id_ord_sup 5457 5457 0 0.00 0 7200 5 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 220 2 0 0
v_max_non_paid_realizn 3573 3573 0 0.00 0 6554 5 0 0
v_min_id_clo_ord 5349 5349 0 0.00 0 2664 7 0 0
v_min_id_clo_res 928 928 0 0.00 0 5869 22 0 0
v_min_id_ord_sup 5457 5457 0 0.00 0 5831 7 0 0
v_min_non_paid_invoice 5829 5829 0 0.00 0 6027 18 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 9663 25 0 0
v_our_firm 10118 10118 0 0.00 0 28 1 0 0
v_reserve_write_off 19424 19424 0 0.00 0 3588 6 0 0
x_lock_depdocs_on_canc_invoice 12342 12342 0 0.00 0 16381 110 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 12693 334 0 0
x_make_qstorno_1000_1200 5350 5350 0 0.00 1 49198 1201 0 0
x_make_qstorno_1200_2000 5460 5460 0 0.00 3 76148 3121 0 0
x_make_qstorno_2100_3300 451026 451026 0 0.00 0 132812 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2022 01:40:43 to 17-10-2022 01:40:47.

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
23:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:34 32122 8192000000 9891486816 10200264704 969120784 10.578 100 85 170 0 997858880 6277344 22460752
23:11:11 13033 8192000000 9936154992 10256646144 1021249536 11.084 100 91 180 1 1053410336 8711216 23449584
23:12:29 13131 8192000000 9963189072 10294509568 1048819104 11.349 100 87 174 0 1080759600 10323392 21617104
23:13:50 12576 8192000000 10015036496 10355916800 1097096752 11.810 100 82 163 1 1127531920 10858112 19577056
23:15:08 6574 8192000000 10066826688 10424639488 1154161552 12.349 100 77 154 0 1183702384 11728032 17812800
23:16:17 3616 8192000000 10117805088 10485219328 1206346816 12.835 100 73 146 0 1234980688 12750080 15883792
23:17:22 1926 8192000000 10150934416 10525724672 1245296304 13.195 100 73 140 1 1272877312 12776656 14804352
23:18:26 1659 8192000000 10196538352 10576855040 1282098704 13.532 100 89 179 0 1314393440 12125248 20169488
23:19:28 19196 8192000000 10198658288 10592063488 1293936064 13.640 100 62 124 1 1317398560 9795344 13667152
23:20:49 5313 8192000000 10194083168 10606571520 1305234752 13.743 100 26 50 0 1321010848 10750432 5025664
23:21:56 6249 8192000000 10214310224 10624888832 1317364304 13.853 100 24 46 0 1332843616 10167040 5312272
23:23:04 14355 8192000000 10233325248 10631884800 1322255232 13.897 100 57 114 1 1341044848 5551728 13237888
23:24:19 3300 8192000000 10218419712 10636881920 1329160112 13.960 100 32 64 2 1343598352 6618128 7820112
23:25:23 11316 8192000000 10225046032 10645528576 1333840224 14.002 100 12 24 1 1341892960 5857728 2195008
23:26:35 10123 8192000000 10231302816 10647207936 1337566672 14.035 100 19 35 0 1346688624 5352304 3769648
23:27:47 39712 8192000000 10210616912 10625265664 1290769088 13.611 97 23 46 0 1300515424 4563568 5182768
23:29:28 4468 8192000000 10186642144 10595520512 1248959136 13.229 95 19 39 0 1258715472 5484656 4271680
23:30:34 6391 8192000000 10143800128 10543849472 1205653984 12.829 95 26 53 0 1221882192 9996464 6231744
23:31:43 17080 8192000000 10143514928 10526531584 1192757264 12.709 95 23 45 0 1202659744 5331600 4570880
23:33:04 25824 8192000000 10127615808 10522763264 1163389872 12.435 93 16 31 0 1172073488 5342000 3341616
23:34:30 4907 8192000000 10167536976 10571878400 1271828272 13.438 100 40 81 0 1288555232 6180864 10546096
23:35:37 11222 8192000000 10178635264 10588618752 1286591264 13.573 100 16 33 0 1295642448 5182480 3868704
23:36:52 22768 8192000000 10206033872 10614661120 1300305024 13.698 100 13 26 0 1308937296 5480672 3151600
23:38:18 14731 8192000000 10208201728 10623000576 1313845968 13.821 100 12 24 1 1320897072 4830912 2220192
23:40:11 12023 8192000000 10241299216 10656899072 1344007696 14.094 100 33 66 0 1359793232 8552112 7233424
23:41:34 32209 8192000000 10269476400 10686857216 1367005888 14.300 100 47 94 0 1392196272 14145248 11045136
23:43:22 28486 8192000000 10292441648 10716794880 1379937568 14.416 100 37 74 1 1408880112 19601920 9340624
23:44:55 8715 8192000000 10315385584 10738102272 1397208768 14.570 100 40 76 0 1429610992 22084880 10317344
23:46:13 9982 8192000000 10333053936 10754592768 1408690624 14.672 100 48 92 0 1445687376 25120864 11875888
23:47:27 4947 8192000000 10350713584 10771697664 1415777440 14.735 100 58 114 1 1463438048 33102112 14558496
23:48:37 6183 8192000000 10359678640 10783604736 1420627456 14.778 100 57 112 0 1469392112 34609152 14155504
23:49:48 3886 8192000000 10366702560 10787905536 1416473024 14.741 100 71 140 1 1473336224 39475344 17387856
23:50:57 1133 8192000000 10377377120 10795732992 1419575200 14.769 100 80 155 1 1478738304 39827968 19335136
23:52:02 2000 8192000000 10369585072 10792857600 1418675184 14.761 100 80 158 2 1477252400 39362064 19215152
23:53:09 2259 8192000000 10374363632 10795327488 1421384240 14.785 100 72 144 1 1481985072 42102304 18498528
23:54:20 2059 8192000000 10358504592 10775306240 1404142512 14.632 100 87 172 1 1466878256 40379120 22356624
23:55:25 4114 8192000000 10365150576 10782900224 1408064960 14.667 100 67 134 1 1469740208 44596672 17078576
23:56:33 22831 8192000000 10361016624 10798505984 1414971696 14.728 100 40 80 0 1474352352 47947440 11433216
23:58:05 17298 8192000000 10327090448 10753294336 1357448896 14.214 98 38 75 0 1421835472 53459968 10926608
23:59:29 25297 8192000000 10339617808 10768207872 1377817568 14.397 99 37 72 1 1433419296 45481024 10120704
00:00:58 16240 8192000000 10316295680 10749591552 1369029312 14.318 99 29 58 0 1413262816 36447136 7786368
00:02:16 6810 8192000000 10300939392 10729820160 1342711232 14.082 98 32 64 0 1389219168 37571040 8936896
00:03:27 23916 8192000000 10292358288 10727550976 1348251120 14.132 99 19 38 0 1392940944 39134352 5555472
00:05:07 29953 8192000000 10285305904 10721263616 1323421664 13.908 98 22 44 0 1377851040 47566560 6862816
00:06:41 8581 8192000000 10267649216 10684661760 1337578944 14.036 99 45 91 2 1363873104 15260704 11033456
00:07:51 91476 8192000000 10259495120 10695962624 1325088032 13.923 97 23 47 0 1341473328 10843456 5541840
00:10:40 121874 8192000000 10263146064 10708213760 1316788864 13.848 96 11 22 1 1337998592 18799280 2410448
00:13:43 78768 8192000000 10260319120 10698362880 1325025328 13.922 97 9 18 0 1335066704 8193040 1848336
00:16:03 2127 8192000000 10282019520 10719977472 1373101792 14.355 100 37 72 0 1389613456 8615024 7896640
00:17:07 13680 8192000000 10283210624 10726162432 1378510288 14.403 100 14 28 0 1392516128 10962160 3043680
00:18:23 16973 8192000000 10284483040 10723819520 1355910672 14.201 98 51 97 3 1379819072 12674864 11233536
00:19:45 58417 8192000000 10280386928 10739949568 1368061696 14.310 99 8 16 0 1383979408 14171440 1746272
00:21:44 80686 8192000000 10285487392 10737651712 1382889792 14.442 100 15 28 0 1396119664 10047136 3182736
00:24:18 66623 8192000000 10300874720 10759663616 1378820944 14.406 99 16 32 0 1402981136 20168432 3991760
00:26:46 82888 8192000000 10290891344 10755727360 1395621280 14.556 100 7 14 0 1408739904 11695712 1422912
00:29:11 28159 8192000000 10299085968 10753732608 1402231920 14.615 100 42 84 0 1419880256 8115968 9532368
00:30:44 70225 8192000000 10295700736 10755493888 1398085152 14.578 100 16 32 0 1410254368 8498672 3670544
00:33:17 63900 8192000000 10296823760 10759528448 1389047648 14.497 99 9 18 0 1398704608 7572528 2084432
00:35:27 40978 8192000000 10276825264 10735443968 1366378128 14.295 99 16 32 0 1388904320 18545008 3981184
00:37:33 36495 8192000000 10284952992 10745942016 1379670176 14.414 99 23 46 0 1398694240 13900128 5123936
00:39:10 56532 8192000000 10316425984 10773393408 1391339888 14.518 99 27 54 0 1406321696 9112688 5869120
00:41:08 62858 8192000000 10288364880 10752454656 1354565184 14.189 97 14 28 0 1375025264 16635408 3824672
00:43:14 75500 8192000000 10284900144 10742231040 1324020688 13.913 95 10 20 1 1341680720 15768400 1891632
00:45:30 24069 8192000000 10294956960 10744737792 1358612576 14.225 98 25 50 0 1372721168 8672192 5436400
00:47:00 52847 8192000000 10269532112 10723532800 1354872912 14.191 99 22 44 0 1371383856 11907360 4603584
00:48:53 70990 8192000000 10256714592 10703253504 1351657040 14.162 100 61 120 0 1379496224 13460624 14378560
00:51:05 26049 8192000000 10266255504 10702901248 1343647088 14.090 99 42 83 0 1363205280 9827408 9730784
00:52:32 28477 8192000000 10249885360 10708680704 1341147136 14.068 99 16 32 0 1360840880 15842608 3851136
00:54:06 68465 8192000000 10263829824 10724163584 1339198592 14.050 98 11 22 0 1359470352 17477568 2794192
00:56:20 70797 8192000000 10256054544 10724421632 1351509664 14.161 99 8 16 0 1371420240 17881088 2029488
00:58:31 36647 8192000000 10261225648 10713063424 1341827632 14.074 98 21 38 2 1358447728 11790128 4829968
01:00:08 44084 8192000000 10282281184 10733174784 1371913008 14.344 100 15 30 0 1387866272 12446928 3506336
01:01:58 33451 8192000000 10277525312 10720776192 1353951296 14.183 98 33 67 0 1371858368 9884224 8022848
01:03:37 57265 8192000000 10278344224 10726907904 1324995136 13.922 96 17 34 1 1338939568 10180592 3763840
01:05:35 58347 8192000000 10278556512 10717155328 1314014080 13.822 96 25 50 0 1329894528 10552544 5327904
01:07:33 167727 8192000000 10281903408 10746867712 1316570880 13.846 95 6 12 0 1336222064 18514864 1136320
01:11:26 46049 8192000000 10300713024 10739138560 1377472816 14.394 99 46 90 1 1393698272 6390800 9834656
01:13:18 66209 8192000000 10294983296 10753204224 1388670160 14.494 100 10 20 0 1409017952 17917088 2430704
01:16:15 54262 8192000000 10318737264 10771378176 1400475856 14.599 100 22 44 0 1419974944 14774496 4724592
01:18:17 75929 8192000000 10302925872 10773659648 1360903568 14.245 97 17 35 0 1391885664 27270816 3711280
01:20:34 105747 8192000000 10291578816 10750234624 1341715744 14.073 96 11 23 1 1358609024 14578032 2315248
01:23:23 96326 8192000000 10286222448 10730807296 1269007744 13.413 91 22 42 1 1282440064 8826432 4605888
01:26:02 73637 8192000000 10285283616 10740203520 1346339824 14.115 98 16 32 0 1360920288 10979936 3600528
01:28:16 48033 8192000000 10308432672 10743144448 1367025728 14.300 99 16 32 0 1389401344 18961568 3414048
01:30:04 112608 8192000000 10285682336 10739904512 1365542336 14.287 99 25 50 0 1379384896 8331120 5511440
01:33:16 81768 8192000000 10286656672 10747785216 1374866784 14.371 99 11 22 0 1394882016 17491552 2523680
01:35:44 81910 8192000000 10313720000 10774343680 1378268000 14.401 99 15 30 0 1399056064 17384064 3404000
01:38:05 36133 8192000000 9223854672 9495343104 387194960 4.513 28 6 12 0 394561792 6171200 1195632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2022 01:40:49 to 17-10-2022 01:41:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2022 01:41:05 to 17-10-2022 01:41:07.

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          235469
MON$OLDEST_ACTIVE               236130
MON$OLDEST_SNAPSHOT             236130
MON$NEXT_TRANSACTION            236130
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                       2029280
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:453: 
:454: mscs-test-161.budzdorov.ru    Mon Oct 17 01:38:52 2022
:455:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:456: 
--- end of diff output ---

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

17.10.22 01:41:08. 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
2022-10-17 01:41:11 No severe PSQL-related problems occured
17.10.22 01:41:12. End of report.