Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.07.2022 16: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 15726924 kB
MemAvailable 31008720 kB
Buffers 0 kB
Cached 15969060 kB
SwapCached 2512 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-07-28 16:38:30
DTS_END                         2022-07-28 16:38:30.3720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45672
TRN_ID                          246676
ATT_ID                          640
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: 9631.13 19420 1155736
customer order: creation 176.15 366 21138
customer order: refuse 15.39 328 1847
order to supplier: creation 46.43 1234 5571
order to supplier: removal 3.70 106078 444
invoice (draft): creation 46.88 3065 5626
invoice (draft): removal 53.74 881 6449
invoice accept: apply 43.63 17718 5235
invoice accept: cancel 48.93 811 5872
customer reserve: creation 4070.66 173 488479
customer reserve: removal 4825.04 63 579005
realization accept: apply 85.13 189 10215
realization accept: cancel 84.79 107 10175
payment from customer: creation 58.97 137 7076
payment from customer: removal 5.89 84 707
payment to supplier: creation 49.73 180 5968
payment to supplier: removal 3.63 77 435
service: total inventory turnovers 5.11 1071 613
service: total monetary turnovers 6.41 38 769
service: aggregate perf. data 0.90 19441 108
service: refresh index statistics 0.03 236361 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-07-2022 16:39:25 to 28-07-2022 16:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5353
TEST_TIME 2 4520
TEST_TIME 3 4201
TEST_TIME 4 5248
TEST_TIME 5 5399
TEST_TIME 6 5338
TEST_TIME 7 4942
TEST_TIME 8 5246
TEST_TIME 9 5286
TEST_TIME 10 5205
TEST_TIME 11 5635
TEST_TIME 12 6471
TEST_TIME 13 6658
TEST_TIME 14 6709
TEST_TIME 15 6997
TEST_TIME 16 6961
TEST_TIME 17 6907
TEST_TIME 18 7008
TEST_TIME 19 7082
TEST_TIME 20 7360
TEST_TIME 21 7444
TEST_TIME 22 7736
TEST_TIME 23 8132
TEST_TIME 24 8236
TEST_TIME 25 8366
TEST_TIME 26 8372
TEST_TIME 27 8684
TEST_TIME 28 9364
TEST_TIME 29 10238
TEST_TIME 30 10755
TEST_TIME 31 10848
TEST_TIME 32 10666
TEST_TIME 33 10646
TEST_TIME 34 10565
TEST_TIME 35 10442
TEST_TIME 36 10539
TEST_TIME 37 10497
TEST_TIME 38 10516
TEST_TIME 39 10400
TEST_TIME 40 10278
TEST_TIME 41 10340
TEST_TIME 42 10336
TEST_TIME 43 10560
TEST_TIME 44 10518
TEST_TIME 45 10384
TEST_TIME 46 10248
TEST_TIME 47 10262
TEST_TIME 48 10248
TEST_TIME 49 10262
TEST_TIME 50 10151
TEST_TIME 51 10086
TEST_TIME 52 10035
TEST_TIME 53 10019
TEST_TIME 54 10022
TEST_TIME 55 10090
TEST_TIME 56 9986
TEST_TIME 57 10058
TEST_TIME 58 10071
TEST_TIME 59 9929
TEST_TIME 60 9902
TEST_TIME 61 9815
TEST_TIME 62 9769
TEST_TIME 63 9806
TEST_TIME 64 9833
TEST_TIME 65 9832
TEST_TIME 66 9848
TEST_TIME 67 9832
TEST_TIME 68 9855
TEST_TIME 69 9929
TEST_TIME 70 9929
TEST_TIME 71 10041
TEST_TIME 72 9979
TEST_TIME 73 9966
TEST_TIME 74 10069
TEST_TIME 75 10058
TEST_TIME 76 9991
TEST_TIME 77 10034
TEST_TIME 78 9923
TEST_TIME 79 9921
TEST_TIME 80 9875
TEST_TIME 81 9832
TEST_TIME 82 9878
TEST_TIME 83 9816
TEST_TIME 84 9797
TEST_TIME 85 9779
TEST_TIME 86 9710
TEST_TIME 87 9696
TEST_TIME 88 9641
TEST_TIME 89 9617
TEST_TIME 90 9608
TEST_TIME 91 9593
TEST_TIME 92 9703
TEST_TIME 93 9718
TEST_TIME 94 9724
TEST_TIME 95 9763
TEST_TIME 96 9757
TEST_TIME 97 9783
TEST_TIME 98 9809
TEST_TIME 99 9764
TEST_TIME 100 9844
TEST_TIME 101 9842
TEST_TIME 102 9879
TEST_TIME 103 9873
TEST_TIME 104 9817
TEST_TIME 105 9764
TEST_TIME 106 9728
TEST_TIME 107 9710
TEST_TIME 108 9704
TEST_TIME 109 9662
TEST_TIME 110 9652
TEST_TIME 111 9629
TEST_TIME 112 9597
TEST_TIME 113 9616
TEST_TIME 114 9629
TEST_TIME 115 9623
TEST_TIME 116 9625
TEST_TIME 117 9647
TEST_TIME 118 9692
TEST_TIME 119 9660
TEST_TIME 120 9637

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-07-2022 16:39:28 to 28-07-2022 16:39:32.

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 1608028 1608028 0 0.00 0 28437 6 0 0
doc_list_biud 1608028 1608028 0 0.00 0 54739 29 0 0
sp_add_invoice_to_stock 5371 5235 136 2.53 18 170466 17719 0 136
sp_cancel_adding_invoice 5872 5872 0 0.00 3 75656 812 0 0
sp_cancel_client_order 1847 1847 0 0.00 4 10516 328 0 0
sp_cancel_customer_reserve 579005 579005 0 0.00 0 54746 63 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 2 4511 85 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 8599 78 0 0
sp_cancel_supplier_invoice 6463 6449 14 0.21 1 16828 881 0 14
sp_cancel_supplier_order 444 444 0 0.00 7 1230618 106078 0 0
sp_cancel_write_off 10175 10175 0 0.00 1 7933 108 0 0
sp_client_order 21138 21138 0 0.00 3 25768 367 0 0
sp_customer_reserve 488479 488479 0 0.00 0 41810 174 0 0
sp_fill_shopping_cart 32334 32334 0 0.00 1 10687 36 0 0
sp_fill_shopping_cart_clo_res 488473 488473 0 0.00 0 15702 14 0 0
sp_get_clo_for_invoice 5229 5229 0 0.00 0 21464 267 0 0
sp_kill_cost_storno 17189 17189 0 0.00 0 8594 55 0 0
sp_kill_qstorno_ret_qs2qd 1175078 1175078 0 0.00 0 54732 34 0 0
sp_kill_qty_storno 619236 619236 0 0.00 0 54739 74 0 0
sp_lock_selected_doc 21451 21451 0 0.00 0 7840 7 0 0
sp_make_cost_storno 28488 28488 0 0.00 0 28430 76 0 0
sp_multiply_rows_for_pdistr 28488 28488 0 0.00 0 9292 28 0 0
sp_multiply_rows_for_qdistr 21138 21138 0 0.00 0 12646 152 0 0
sp_pay_from_customer 7076 7076 0 0.00 3 23609 138 0 0
sp_pay_to_supplier 5968 5968 0 0.00 4 28347 180 0 0
sp_payment_common 13044 13044 0 0.00 1 15942 43 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 0 10506 292 0 0
sp_qd_handle_on_invoice_upd_sts 11101 11101 0 0.00 0 15313 341 0 0
sp_qd_handle_on_reserve_upd_sts 10215 10215 0 0.00 0 27772 58 0 0
sp_reserve_write_off 10215 10215 0 0.00 6 28461 189 0 0
sp_supplier_invoice 5626 5626 0 0.00 7 66448 3066 0 0
sp_supplier_order 5571 5571 0 0.00 4 25866 1234 0 0
srv_aggregate_perf_data 108 108 0 0.00 2647 155724 19441 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1213 145114 40219 0 0
srv_find_qd_qs_mism 1106542 1106542 0 0.00 0 16141 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 32 68945 1072 0 0
srv_make_money_saldo 769 769 0 0.00 1 7084 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 188974 335135 236361 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5236 5236 0 0.00 0 17244 189 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 4417 79 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 6273 16 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 5282 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 505 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 978 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1848 15 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 23473 1502 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 3325 15 0 0
t$perf-norm:sp_client_order 21138 21138 0 0.00 0 10813 16 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 721 4 0 0
t$perf-norm:sp_pay_from_customer 7076 7076 0 0.00 0 6283 10 0 0
t$perf-norm:sp_pay_to_supplier 5968 5968 0 0.00 0 6359 12 0 0
t$perf-norm:sp_reserve_write_off 10215 10215 0 0.00 0 9894 15 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 10340 23 0 0
t$perf-norm:sp_supplier_order 5571 5571 0 0.00 0 4681 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 401 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 61 1 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 2282 24 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 3157 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10730 10458 272 2.53 0 3338 18 0 272
v_all_customers 37888 37888 0 0.00 0 26 1 0 0
v_all_suppliers 30966 30966 0 0.00 0 22 1 0 0
v_all_wares 42276 42276 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1275 7 0 0
v_cancel_client_order 3694 3694 0 0.00 0 3262 5 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 614 4 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 3185 8 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 326 4 0 0
v_cancel_supplier_invoice 776 748 28 3.60 0 2501 23 0 28
v_cancel_supplier_order 888 888 0 0.00 0 3421 11 0 0
v_cancel_write_off 2126 2126 0 0.00 0 899 12 0 0
v_max_id_clo_ord 5571 5571 0 0.00 0 580 1 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 6017 23 0 0
v_max_id_ord_sup 5625 5625 0 0.00 0 9313 4 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 6200 7 0 0
v_max_non_paid_realizn 3882 3882 0 0.00 0 5330 9 0 0
v_min_id_clo_ord 5571 5571 0 0.00 0 7944 11 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 1350 4 0 0
v_min_id_ord_sup 5625 5625 0 0.00 0 3459 5 0 0
v_min_non_paid_invoice 5968 5968 0 0.00 0 9410 32 0 0
v_min_non_paid_realizn 7076 7076 0 0.00 0 15937 34 0 0
v_our_firm 10776 10776 0 0.00 0 15 1 0 0
v_reserve_write_off 20430 20430 0 0.00 0 3115 6 0 0
x_lock_depdocs_on_canc_invoice 11947 11947 0 0.00 0 20996 119 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 10460 386 0 0
x_make_qstorno_1000_1200 5571 5571 0 0.00 1 25860 1155 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 3 65282 2943 0 0
x_make_qstorno_2100_3300 488479 488479 0 0.00 0 41808 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-07-2022 16:39:33 to 28-07-2022 16:39:37.

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
14:08:34 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:35 19855 8192000000 9884006864 10191175680 966298608 10.551 100 85 170 0 995329488 5858336 23172544
14:10:58 20681 8192000000 9945521296 10267131904 1026542800 11.135 100 85 170 0 1056269680 7949040 21777840
14:12:29 21198 8192000000 9990794848 10325622784 1073719232 11.588 100 90 180 0 1105976736 9567248 22690256
14:13:56 8611 8192000000 10052826000 10397839360 1130305536 12.124 100 86 172 0 1162710608 12236672 20168400
14:15:07 6310 8192000000 10097521072 10454425600 1174208144 12.536 100 83 165 0 1206235584 13609456 18417984
14:16:17 3239 8192000000 10123376032 10494861312 1214506976 12.911 100 73 146 0 1243548944 12442960 16599008
14:17:23 1478 8192000000 10164103104 10537787392 1252579264 13.262 100 87 173 1 1284606032 12922880 19103888
14:18:26 1052 8192000000 10196122352 10576609280 1282229952 13.533 100 89 174 0 1315643600 14573376 18840272
14:19:27 1315 8192000000 10206745776 10595450880 1296316352 13.662 100 81 162 0 1326618512 12059264 18242896
14:20:30 19630 8192000000 10201342400 10605555712 1302459712 13.718 100 50 98 1 1325743648 12403072 10880864
14:21:51 9949 8192000000 10196016128 10610827264 1310518848 13.791 100 21 40 0 1325634960 10600960 4515152
14:23:06 16953 8192000000 10204832240 10623643648 1322065952 13.895 100 13 27 0 1330738304 5799280 2873072
14:24:24 3463 8192000000 10229086672 10640883712 1328435712 13.953 100 39 79 0 1347697072 10062000 9199360
14:25:28 18152 8192000000 10228313488 10646372352 1330072384 13.968 100 23 45 0 1340962224 5627200 5262640
14:26:46 8073 8192000000 10229573776 10650681344 1336336624 14.024 100 17 34 0 1345815792 5939312 3539856
14:28:01 9019 8192000000 10220673952 10642923520 1310785280 13.793 98 22 44 0 1325796288 10462480 4548528
14:29:10 52942 8192000000 10159588768 10544443392 1199553632 12.772 93 58 117 1 1220132032 5696544 14881856
14:31:04 6735 8192000000 10157184192 10556653568 1240233056 13.148 98 26 51 0 1252155552 5300160 6622336
14:32:12 6080 8192000000 10150943536 10544279552 1237997440 13.128 98 28 55 0 1249619168 5735792 5885936
14:33:18 11416 8192000000 10159031632 10565304320 1246727776 13.208 98 25 52 0 1258390368 4897872 6764720
14:34:31 27239 8192000000 10173130800 10584649728 1283314416 13.543 100 32 62 1 1295587680 5166672 7106592
14:35:59 7368 8192000000 10190803920 10601562112 1296728432 13.665 100 28 56 0 1310135504 6116912 7290160
14:37:07 12186 8192000000 10192666304 10608963584 1303185056 13.724 100 19 39 0 1312762736 5096368 4481312
14:38:20 18135 8192000000 10221278480 10636898304 1323866560 13.912 100 16 32 0 1331967520 5031488 3069472
14:39:51 23530 8192000000 10253437840 10663723008 1343414736 14.088 100 41 85 0 1359598400 6751584 9432080
14:41:17 5485 8192000000 10253755824 10671456256 1352254720 14.168 100 34 66 0 1367420240 8407456 6758064
14:42:32 1348 8192000000 10278618048 10693308416 1367575616 14.305 100 60 118 1 1392264224 11451968 13236640
14:43:40 27473 8192000000 10282433104 10712494080 1370734752 14.334 99 33 66 0 1389191408 10139824 8316832
14:45:11 19944 8192000000 10303201504 10724806656 1387402608 14.483 100 44 88 0 1412243424 14429504 10411312
14:46:36 13408 8192000000 10318997664 10745348096 1400210752 14.597 100 46 93 1 1434022080 22706400 11104928
14:48:09 8335 8192000000 10326640016 10753970176 1408311328 14.669 100 33 65 0 1439874576 23145056 8418192
14:49:25 3196 8192000000 10334437056 10748293120 1390120256 14.507 99 64 126 1 1434177136 27719888 16336992
14:50:42 4910 8192000000 10327125984 10753875968 1390044864 14.506 100 64 128 0 1441986912 36137408 15804640
14:51:52 6926 8192000000 10332393488 10756722688 1404542704 14.635 100 55 110 0 1445044512 27228016 13273792
14:53:02 2962 8192000000 10340059392 10750377984 1393036288 14.533 100 76 151 1 1443231072 30504368 19690416
14:54:13 2501 8192000000 10314843376 10724392960 1373783280 14.361 99 65 128 1 1424418176 34545472 16089424
14:55:21 1662 8192000000 10343980656 10752000000 1385834288 14.469 100 71 142 0 1439013440 35457104 17722048
14:56:35 1210 8192000000 10304417152 10706251776 1349340544 14.142 99 69 137 2 1409403504 43142784 16920176
14:57:41 2325 8192000000 10331063408 10733924352 1371863072 14.344 100 72 144 0 1439565344 48687408 19014864
14:58:48 2550 8192000000 10343483536 10745901056 1376139936 14.382 100 83 166 1 1447873264 50408464 21324864
14:59:57 2017 8192000000 10358861536 10759811072 1383285072 14.446 100 88 168 3 1457229568 51706192 22238304
15:01:05 6961 8192000000 10343127792 10756378624 1382275552 14.437 100 48 95 1 1448529696 53310576 12943568
15:02:20 4415 8192000000 10354450640 10773069824 1396672944 14.565 100 53 103 1 1460312912 49450864 14189104
15:03:32 6788 8192000000 10355796672 10776723456 1397271376 14.571 100 38 76 0 1457770896 50227888 10271632
15:04:42 9387 8192000000 10357229120 10782154752 1391467152 14.519 100 36 72 1 1460697040 58280816 10949072
15:05:54 22297 8192000000 10329046688 10756730880 1349097296 14.139 99 28 56 0 1425930064 67505984 9326784
15:07:22 35249 8192000000 10302773984 10746851328 1357394016 14.214 100 31 60 0 1423142400 57180768 8567616
15:09:16 57451 8192000000 10290102592 10716577792 1341852208 14.074 98 22 45 0 1356184624 9184688 5147728
15:11:14 18608 8192000000 10275586272 10711932928 1355700848 14.199 99 14 28 0 1364869488 5646000 3522640
15:12:34 77260 8192000000 10311577136 10740338688 1355745152 14.199 98 28 57 0 1374675920 13355552 5575216
15:14:52 145114 8192000000 10294986896 10748981248 1356606832 14.207 98 9 16 1 1374845760 16806800 1432128
15:18:18 27893 8192000000 10334010400 10766073856 1397108400 14.569 100 39 76 0 1417544768 11699344 8737024
15:19:55 114999 8192000000 10334629056 10809708544 1391903680 14.523 99 14 29 0 1420281904 25204528 3173696
15:23:48 77602 8192000000 10305595168 10750476288 1379770208 14.414 99 25 50 0 1399490192 14255120 5464864
15:26:11 16205 8192000000 10322697440 10762932224 1401183872 14.606 100 35 69 2 1417812080 8749504 7878704
15:27:28 35113 8192000000 10318376080 10778877952 1412074896 14.702 100 27 52 0 1426805776 8281216 6449664
15:29:26 32562 8192000000 10316955552 10770714624 1366029008 14.291 96 30 60 0 1384649296 11457264 7163024
15:31:06 32805 8192000000 10336440464 10774343680 1396199456 14.561 99 30 59 0 1418790192 15255040 7335696
15:32:40 62124 8192000000 10317020848 10787504128 1406940704 14.657 100 23 46 1 1429005376 16214672 5850000
15:35:02 68233 8192000000 10304088752 10773364736 1383858336 14.451 98 17 35 0 1407618896 19722768 4037792
15:37:11 29916 8192000000 10284073888 10759778304 1390436496 14.510 100 5 10 0 1400095024 8906208 752320
15:38:41 33384 8192000000 10283370816 10744131584 1362727776 14.262 98 18 36 0 1374794640 8313120 3753744
15:40:25 57686 8192000000 10289811568 10764705792 1380017248 14.417 98 10 18 0 1392017104 10187104 1812752
15:42:30 49096 8192000000 10282262256 10747195392 1350260784 14.150 97 8 16 0 1361605584 9522224 1822576
15:44:19 46969 8192000000 10268869888 10720927744 1317729472 13.856 96 18 36 0 1330594480 8972000 3893008
15:46:21 48301 8192000000 10260801872 10720747520 1336398784 14.025 98 27 54 0 1357465872 14943440 6123648
15:48:16 78496 8192000000 10284565104 10737254400 1341143520 14.068 98 26 52 0 1359878992 12522720 6212752
15:50:40 35091 8192000000 10284301984 10718015488 1330464848 13.971 97 35 66 0 1348057248 10042352 7550048
15:52:21 111070 8192000000 10269226816 10714066944 1282102016 13.532 94 12 24 0 1296318240 11727344 2488880
15:55:13 100182 8192000000 10278506944 10729635840 1350910176 14.156 98 28 56 0 1368667904 11542432 6215296
15:57:59 60618 8192000000 10271352576 10726383616 1354629008 14.189 99 22 44 0 1371106032 11361824 5115200
16:00:00 49665 8192000000 10273791104 10744385536 1365828256 14.290 99 17 34 0 1383979200 14168608 3982336
16:01:59 42781 8192000000 10281449088 10737082368 1369913536 14.326 99 19 37 1 1384159888 10384176 3862176
16:04:25 73379 8192000000 10273488592 10750791680 1356101248 14.202 98 8 16 0 1367505552 9493696 1910608
16:06:38 81292 8192000000 10283181760 10750447616 1377176720 14.391 99 13 26 0 1386929344 7338576 2414048
16:09:01 30581 8192000000 10313895312 10763251712 1389778480 14.504 99 34 67 1 1407470064 10098192 7593392
16:10:32 72173 8192000000 10307597952 10777702400 1397077024 14.569 100 17 34 0 1420596112 19642912 3876176
16:12:44 53116 8192000000 10305743792 10777964544 1394500352 14.546 99 12 24 0 1413333904 15997792 2835760
16:14:40 28482 8192000000 10324086096 10782494720 1407133616 14.658 100 44 88 1 1429352752 12730848 9488288
16:17:31 42336 8192000000 10291238288 10742890496 1347301808 14.123 96 30 60 0 1364958384 11017584 6638992
16:19:18 78952 8192000000 10308782720 10761056256 1365308944 14.285 98 20 40 0 1382132848 12097648 4726256
16:21:42 69879 8192000000 10293547488 10750091264 1371712240 14.342 98 32 64 1 1387786160 8648592 7425328
16:24:10 26287 8192000000 10277851216 10730582016 1365405584 14.286 99 23 42 0 1376754464 6284880 5064000
16:25:36 45469 8192000000 10248004112 10719825920 1336471152 14.026 98 9 18 0 1351615136 12982080 2161904
16:27:23 76782 8192000000 10272407184 10732187648 1359062768 14.229 99 16 32 0 1370836336 7884496 3889072
16:29:40 76481 8192000000 10275299472 10741137408 1346479760 14.116 97 13 26 0 1360728736 11245664 3003312
16:31:57 37187 8192000000 10282672528 10739326976 1344790096 14.101 97 21 42 0 1357656752 8178128 4688528
16:33:38 66403 8192000000 10270414192 10719436800 1314380480 13.826 95 12 23 0 1329656704 12243920 3032304
16:35:45 53056 8192000000 10281380816 10747543552 1351792224 14.164 98 26 50 1 1371588432 14337488 5458720
16:37:48 40501 8192000000 10262632224 10709131264 1297293904 13.671 95 36 72 0 1314378816 9246400 7838512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-07-2022 16:39:39 to 28-07-2022 16:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 28-07-2022 16:39:55 to 28-07-2022 16:39:56.

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          245945
MON$OLDEST_ACTIVE               246871
MON$OLDEST_SNAPSHOT             246871
MON$NEXT_TRANSACTION            246871
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                       2061792
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:374: 
:375: mscs-test-161.budzdorov.ru    Thu Jul 28 16:38:42 2022
:376:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:377: 
:378: 
:379: mscs-test-161.budzdorov.ru    Thu Jul 28 16:38:43 2022
:380:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:381: 
:382: 
:383: mscs-test-161.budzdorov.ru    Thu Jul 28 16:38:43 2022
:384:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:385: 
--- end of diff output ---

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

28.07.22 16:39:57. 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-07-28 16:40:00 No severe PSQL-related problems occured
28.07.22 16:40:01. End of report.