Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.06.2022 19: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 16287152 kB
MemAvailable 31359284 kB
Buffers 0 kB
Cached 15454832 kB
SwapCached 2448 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.10
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-06-05 19:38:27
DTS_END                         2022-06-05 19:38:27.0020
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/56984
TRN_ID                          247875
ATT_ID                          733
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: 9452.58 18686 1134310
customer order: creation 183.03 320 21963
customer order: refuse 15.49 338 1859
order to supplier: creation 48.19 1276 5783
order to supplier: removal 3.63 90624 435
invoice (draft): creation 48.93 3331 5872
invoice (draft): removal 52.17 773 6260
invoice accept: apply 44.75 15351 5370
invoice accept: cancel 47.03 754 5643
customer reserve: creation 4105.73 151 492687
customer reserve: removal 4586.33 56 550360
realization accept: apply 89.32 155 10718
realization accept: cancel 87.09 108 10451
payment from customer: creation 64.13 98 7696
payment from customer: removal 6.10 73 732
payment to supplier: creation 53.14 113 6377
payment to supplier: removal 4.01 97 481
service: total inventory turnovers 5.18 903 621
service: total monetary turnovers 7.44 22 893
service: aggregate perf. data 0.88 17389 105
service: refresh index statistics 0.03 241785 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-06-2022 19:40:37 to 05-06-2022 19:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5060
TEST_TIME 2 5780
TEST_TIME 3 5252
TEST_TIME 4 5822
TEST_TIME 5 5287
TEST_TIME 6 5415
TEST_TIME 7 5144
TEST_TIME 8 5138
TEST_TIME 9 5125
TEST_TIME 10 5629
TEST_TIME 11 5691
TEST_TIME 12 5734
TEST_TIME 13 6226
TEST_TIME 14 6795
TEST_TIME 15 7321
TEST_TIME 16 7245
TEST_TIME 17 7381
TEST_TIME 18 7593
TEST_TIME 19 7842
TEST_TIME 20 8014
TEST_TIME 21 8954
TEST_TIME 22 9043
TEST_TIME 23 9173
TEST_TIME 24 9617
TEST_TIME 25 10317
TEST_TIME 26 10201
TEST_TIME 27 11020
TEST_TIME 28 11080
TEST_TIME 29 11007
TEST_TIME 30 10766
TEST_TIME 31 10799
TEST_TIME 32 10744
TEST_TIME 33 10852
TEST_TIME 34 10763
TEST_TIME 35 10702
TEST_TIME 36 10719
TEST_TIME 37 10578
TEST_TIME 38 10455
TEST_TIME 39 10864
TEST_TIME 40 10679
TEST_TIME 41 10429
TEST_TIME 42 10360
TEST_TIME 43 10387
TEST_TIME 44 10201
TEST_TIME 45 10182
TEST_TIME 46 10197
TEST_TIME 47 10122
TEST_TIME 48 10196
TEST_TIME 49 10145
TEST_TIME 50 10048
TEST_TIME 51 10004
TEST_TIME 52 9938
TEST_TIME 53 9899
TEST_TIME 54 9827
TEST_TIME 55 9706
TEST_TIME 56 9674
TEST_TIME 57 9588
TEST_TIME 58 9690
TEST_TIME 59 9755
TEST_TIME 60 9765
TEST_TIME 61 9724
TEST_TIME 62 9831
TEST_TIME 63 9886
TEST_TIME 64 9892
TEST_TIME 65 9997
TEST_TIME 66 9976
TEST_TIME 67 10038
TEST_TIME 68 10042
TEST_TIME 69 10037
TEST_TIME 70 10015
TEST_TIME 71 10060
TEST_TIME 72 10008
TEST_TIME 73 9896
TEST_TIME 74 9881
TEST_TIME 75 9845
TEST_TIME 76 9789
TEST_TIME 77 9782
TEST_TIME 78 9722
TEST_TIME 79 9707
TEST_TIME 80 9647
TEST_TIME 81 9617
TEST_TIME 82 9601
TEST_TIME 83 9659
TEST_TIME 84 9643
TEST_TIME 85 9655
TEST_TIME 86 9697
TEST_TIME 87 9724
TEST_TIME 88 9688
TEST_TIME 89 9712
TEST_TIME 90 9750
TEST_TIME 91 9719
TEST_TIME 92 9774
TEST_TIME 93 9865
TEST_TIME 94 9819
TEST_TIME 95 9747
TEST_TIME 96 9698
TEST_TIME 97 9624
TEST_TIME 98 9613
TEST_TIME 99 9594
TEST_TIME 100 9581
TEST_TIME 101 9573
TEST_TIME 102 9581
TEST_TIME 103 9537
TEST_TIME 104 9552
TEST_TIME 105 9583
TEST_TIME 106 9546
TEST_TIME 107 9585
TEST_TIME 108 9606
TEST_TIME 109 9628
TEST_TIME 110 9645
TEST_TIME 111 9652
TEST_TIME 112 9732
TEST_TIME 113 9683
TEST_TIME 114 9645
TEST_TIME 115 9599
TEST_TIME 116 9565
TEST_TIME 117 9591
TEST_TIME 118 9551
TEST_TIME 119 9522
TEST_TIME 120 9458

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-06-2022 19:40:41 to 05-06-2022 19:40:45.

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 1589651 1589651 0 0.00 0 18584 5 0 0
doc_list_biud 1589649 1589649 0 0.00 0 36110 25 0 0
sp_add_invoice_to_stock 5493 5370 123 2.23 26 176534 15352 0 123
sp_cancel_adding_invoice 5643 5643 0 0.00 5 106415 755 0 0
sp_cancel_client_order 1859 1859 0 0.00 3 7998 338 0 0
sp_cancel_customer_reserve 550360 550360 0 0.00 1 36116 56 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 4331 74 0 0
sp_cancel_pay_to_supplier 481 481 0 0.00 2 6867 97 0 0
sp_cancel_supplier_invoice 6269 6260 9 0.14 1 16905 774 0 9
sp_cancel_supplier_order 435 435 0 0.00 5 1079252 90625 0 0
sp_cancel_write_off 10451 10451 0 0.00 1 14294 109 0 0
sp_client_order 21963 21963 0 0.00 3 20858 320 0 0
sp_customer_reserve 492687 492687 0 0.00 0 105334 151 0 0
sp_fill_shopping_cart 33595 33595 0 0.00 1 11790 35 0 0
sp_fill_shopping_cart_clo_res 492663 492663 0 0.00 0 16299 12 0 0
sp_get_clo_for_invoice 5345 5345 0 0.00 0 25123 269 0 0
sp_kill_cost_storno 17307 17307 0 0.00 0 6849 55 0 0
sp_kill_qstorno_ret_qs2qd 1117866 1117866 0 0.00 0 36099 31 0 0
sp_kill_qty_storno 591071 591071 0 0.00 0 36109 66 0 0
sp_lock_selected_doc 22299 22299 0 0.00 0 8555 6 0 0
sp_make_cost_storno 30136 30136 0 0.00 0 13836 47 0 0
sp_multiply_rows_for_pdistr 30136 30136 0 0.00 0 6170 23 0 0
sp_multiply_rows_for_qdistr 21963 21963 0 0.00 0 12462 126 0 0
sp_pay_from_customer 7696 7696 0 0.00 4 10097 99 0 0
sp_pay_to_supplier 6377 6377 0 0.00 4 13585 114 0 0
sp_payment_common 14073 14073 0 0.00 1 13538 23 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 7992 314 0 0
sp_qd_handle_on_invoice_upd_sts 10988 10988 0 0.00 0 26278 309 0 0
sp_qd_handle_on_reserve_upd_sts 10718 10718 0 0.00 0 15576 57 0 0
sp_reserve_write_off 10718 10718 0 0.00 6 15592 155 0 0
sp_supplier_invoice 5872 5872 0 0.00 7 89771 3332 0 0
sp_supplier_order 5783 5783 0 0.00 5 41317 1277 0 0
srv_aggregate_perf_data 105 105 0 0.00 2803 154888 17390 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1253 125568 44265 0 0
srv_find_qd_qs_mism 1082732 1082732 0 0.00 0 18583 4 0 0
srv_make_invnt_saldo 621 621 0 0.00 40 43460 903 0 0
srv_make_money_saldo 893 893 0 0.00 1 2433 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 128174 365625 241785 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 5370 5370 0 0.00 0 16643 188 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 4761 73 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 899 9 0 0
t$perf-norm:sp_cancel_customer_reserve 764 764 0 0.00 0 5960 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 770 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 481 481 0 0.00 0 906 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 7115 28 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 19735 1407 0 0
t$perf-norm:sp_cancel_write_off 1117 1117 0 0.00 0 6444 21 0 0
t$perf-norm:sp_client_order 21963 21963 0 0.00 0 9073 17 0 0
t$perf-norm:sp_customer_reserve 1131 1131 0 0.00 0 1219 5 0 0
t$perf-norm:sp_pay_from_customer 7696 7696 0 0.00 0 5172 10 0 0
t$perf-norm:sp_pay_to_supplier 6377 6377 0 0.00 0 5585 11 0 0
t$perf-norm:sp_reserve_write_off 10718 10718 0 0.00 0 6287 13 0 0
t$perf-norm:sp_supplier_invoice 5872 5872 0 0.00 0 7475 17 0 0
t$perf-norm:sp_supplier_order 5783 5783 0 0.00 0 6016 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1034 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 84 2 0 0
t$perf-norm:srv_make_invnt_saldo 621 621 0 0.00 0 1357 23 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 5968 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10936 10690 246 2.24 0 5266 15 0 246
v_all_customers 40426 40426 0 0.00 0 69 0 0 0
v_all_suppliers 32566 32566 0 0.00 0 15 1 0 0
v_all_wares 43926 43926 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1554 9 0 0
v_cancel_client_order 3718 3718 0 0.00 0 3122 5 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 2725 5 0 0
v_cancel_customer_reserve 1528 1528 0 0.00 0 2532 4 0 0
v_cancel_payment_to_supplier 962 962 0 0.00 0 816 5 0 0
v_cancel_supplier_invoice 894 876 18 2.01 0 5201 18 0 18
v_cancel_supplier_order 870 870 0 0.00 0 215 3 0 0
v_cancel_write_off 2234 2234 0 0.00 0 7514 15 0 0
v_max_id_clo_ord 5783 5783 0 0.00 0 980 2 0 0
v_max_id_clo_res 1131 1131 0 0.00 0 5182 14 0 0
v_max_id_ord_sup 5849 5849 0 0.00 0 6569 4 0 0
v_max_non_paid_invoice 1726 1726 0 0.00 0 3685 5 0 0
v_max_non_paid_realizn 3999 3999 0 0.00 0 3101 4 0 0
v_min_id_clo_ord 5783 5783 0 0.00 0 2042 5 0 0
v_min_id_clo_res 1131 1131 0 0.00 0 4933 6 0 0
v_min_id_ord_sup 5849 5849 0 0.00 0 5501 7 0 0
v_min_non_paid_invoice 6377 6377 0 0.00 0 13534 14 0 0
v_min_non_paid_realizn 7696 7696 0 0.00 0 6019 15 0 0
v_our_firm 10894 10894 0 0.00 0 15 1 0 0
v_reserve_write_off 21436 21436 0 0.00 0 3342 5 0 0
x_lock_depdocs_on_canc_invoice 11465 11465 0 0.00 0 15039 94 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 35182 385 0 0
x_make_qstorno_1000_1200 5783 5783 0 0.00 1 41303 1202 0 0
x_make_qstorno_1200_2000 5872 5872 0 0.00 2 89110 3198 0 0
x_make_qstorno_2100_3300 492687 492687 0 0.00 0 105323 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-06-2022 19:40:46 to 05-06-2022 19:40:50.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:30 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:31 33331 8192000000 9906903696 10218557440 979609904 10.680 100 89 178 0 1009213408 5918672 23684832
17:11:12 33987 8192000000 9958704144 10282135552 1034478224 11.212 100 92 184 0 1066427088 8381136 23567728
17:12:48 20019 8192000000 9993061664 10331754496 1070468112 11.557 100 76 152 0 1099663808 9794608 19401088
17:14:14 13219 8192000000 10066044224 10417864704 1146336368 12.275 100 87 174 0 1179488208 12547488 20604352
17:15:33 7247 8192000000 10101654752 10465857536 1183424320 12.622 100 69 138 1 1211011072 12441808 15144944
17:16:43 3849 8192000000 10138332128 10509668352 1225181632 13.010 100 84 168 3 1257026896 13248080 18597184
17:17:49 6803 8192000000 10163361472 10541334528 1248566032 13.225 100 81 162 1 1280512720 14552960 17393728
17:18:57 2350 8192000000 10203683024 10588676096 1289682400 13.601 100 80 160 1 1320978928 13553712 17742816
17:20:02 1840 8192000000 10223253728 10607345664 1301277584 13.707 100 91 181 0 1334910080 13462352 20170144
17:21:05 4888 8192000000 10202643184 10604531712 1307105312 13.760 100 50 100 1 1327871424 9644608 11121504
17:22:12 4600 8192000000 10205473904 10615111680 1317879072 13.858 100 40 80 1 1334385312 7443776 9062464
17:23:20 2666 8192000000 10225843712 10633125888 1325641296 13.928 100 43 86 1 1344387744 8797920 9948528
17:24:23 63186 8192000000 10245614400 10649165824 1332937296 13.994 100 41 83 1 1348402848 5372944 10092608
17:26:28 5790 8192000000 10234982928 10651676672 1338224768 14.041 100 29 58 1 1352254016 7695904 6333344
17:27:35 5178 8192000000 10218436352 10636546048 1329972192 13.967 100 33 66 2 1343366496 6061648 7332656
17:28:42 61255 8192000000 10183659984 10596151296 1252689680 13.263 95 30 60 1 1265212112 4935232 7587200
17:30:50 5335 8192000000 10152736064 10555539456 1251571936 13.253 99 37 74 1 1266494544 6085712 8836896
17:31:59 6641 8192000000 10150624128 10545668096 1251322384 13.250 100 51 101 3 1269324688 5562976 12439328
17:33:11 62212 8192000000 10154921056 10560057344 1234252320 13.093 97 42 85 0 1249306224 5175152 9878752
17:35:16 5401 8192000000 10180040624 10584289280 1283314336 13.543 100 39 78 2 1299650752 6532192 9804224
17:36:24 9291 8192000000 10194537232 10605780992 1301185824 13.706 100 40 80 1 1316074528 4746880 10141824
17:37:33 22777 8192000000 10202056432 10614775808 1309929328 13.785 100 32 64 2 1323958336 6789344 7239664
17:39:19 4266 8192000000 10236894256 10644152320 1330206704 13.969 100 52 103 1 1350379280 8130416 12042160
17:40:33 22411 8192000000 10260872800 10676928512 1351799904 14.164 100 46 90 0 1375029104 12226752 11002448
17:42:06 13394 8192000000 10287785216 10698436608 1368369728 14.312 100 56 109 2 1397146736 15644176 13132832
17:43:24 16050 8192000000 10285945008 10712174592 1380563424 14.422 100 33 64 0 1405065664 17005408 7496832
17:44:42 18152 8192000000 10307169120 10734276608 1392724128 14.530 100 37 74 2 1423667408 21302848 9640432
17:46:06 17368 8192000000 10327965648 10750816256 1403523952 14.626 100 42 84 1 1438685280 24776864 10384464
17:47:29 7768 8192000000 10339196480 10760581120 1410106000 14.685 100 63 125 2 1455174080 29830768 15237312
17:48:40 9284 8192000000 10353582032 10779328512 1418329680 14.758 100 63 126 0 1468763360 34980160 15453520
17:49:55 6666 8192000000 10372389904 10796679168 1428918176 14.852 100 62 124 0 1480143184 35541696 15683312
17:51:11 1635 8192000000 10373049392 10790486016 1424787632 14.815 100 84 169 0 1484458048 39181744 20488672
17:52:20 2313 8192000000 10352277648 10758230016 1389006704 14.497 100 84 167 0 1453685552 44216512 20462336
17:53:25 6273 8192000000 10343939856 10767212544 1396617952 14.565 100 69 136 0 1455939168 42579888 16741328
17:54:39 4198 8192000000 10353201456 10767212544 1397467200 14.572 100 75 148 3 1459565568 42810160 19288208
17:55:49 1251 8192000000 10375534160 10788425728 1410046176 14.684 100 73 144 2 1477963280 49259648 18657456
17:56:57 7761 8192000000 10366060816 10784964608 1402549120 14.618 100 59 118 0 1473054192 54613296 15891776
17:58:09 6915 8192000000 10362669792 10782052352 1384648560 14.458 99 53 105 2 1457764880 58741168 14375152
17:59:23 10666 8192000000 10310109936 10733232128 1364332160 14.276 99 34 68 0 1424954432 51162992 9459280
18:00:43 9626 8192000000 10336173632 10767650816 1372531568 14.350 99 37 74 1 1426566160 44497120 9537472
18:01:56 15454 8192000000 10314232240 10738663424 1334474208 14.008 98 33 65 3 1397478896 53255280 9749408
18:03:14 21259 8192000000 10290160864 10751270912 1361587792 14.252 100 18 36 2 1414657472 47435984 5633696
18:04:44 17177 8192000000 10298746176 10739552256 1363169824 14.266 100 42 82 2 1410876480 36450240 11256416
18:06:04 77077 8192000000 10269222656 10731048960 1337300960 14.033 99 2 4 0 1361760688 24127824 331904
18:08:23 73592 8192000000 10294592880 10720550912 1344848560 14.101 98 28 56 0 1365547760 14509344 6189856
18:10:45 125513 8192000000 10293968672 10749640704 1332590912 13.991 96 11 21 0 1350549424 16045824 1912688
18:13:51 65649 8192000000 10297394384 10739748864 1362827472 14.263 98 9 18 2 1376189824 11646992 1715360
18:15:59 50049 8192000000 10312403248 10758950912 1373347424 14.357 99 9 18 0 1402764048 27075888 2340736
18:17:55 86552 8192000000 10324889536 10787467264 1395548752 14.555 100 15 30 1 1419124240 20042160 3533328
18:20:21 45552 8192000000 10312022816 10764382208 1395825136 14.558 100 31 61 2 1418555792 14730960 7999696
18:22:09 48256 8192000000 10319576832 10771312640 1405467104 14.644 100 33 65 1 1422848800 9925328 7456368
18:23:58 39006 8192000000 10318540880 10780319744 1398422112 14.581 99 23 46 4 1416787264 13017136 5348016
18:25:50 26809 8192000000 10325049504 10768867328 1395877712 14.558 99 50 100 0 1418598160 10606048 12114400
18:27:18 125567 8192000000 10280467696 10739224576 1331004944 13.976 94 11 22 0 1338780608 5155360 2620304
18:30:25 34896 8192000000 10302926048 10759692288 1387231008 14.481 99 16 33 0 1399440768 8550480 3659280
18:32:09 67693 8192000000 10301319392 10766626816 1404137024 14.632 100 10 20 0 1414083168 7472528 2473616
18:34:17 19927 8192000000 10281452720 10734006272 1359666304 14.234 98 34 69 1 1377895008 10054336 8174368
18:35:37 59464 8192000000 10279493792 10756513792 1361322848 14.249 98 10 20 0 1378568160 15029008 2216304
18:37:38 56850 8192000000 10267155040 10723786752 1334310240 14.006 96 13 26 0 1345035040 8231200 2493600
18:39:35 16323 8192000000 10294998192 10741248000 1359937488 14.237 98 44 88 1 1380457920 10889680 9630752
18:41:01 52621 8192000000 10282063040 10739957760 1340643408 14.063 97 24 48 1 1358327392 11763120 5920864
18:42:58 57692 8192000000 10267609360 10726682624 1323394416 13.907 97 12 25 0 1339668608 13422560 2851632
18:45:04 43929 8192000000 10263982992 10719010816 1347886352 14.128 99 14 28 0 1364904880 13830320 3188208
18:46:49 33888 8192000000 10257019552 10697015296 1319704096 13.874 97 20 39 1 1331621888 7285328 4632464
18:48:29 15521 8192000000 10263809712 10696462336 1329181936 13.960 99 37 74 1 1349750016 11768432 8799648
18:49:48 91014 8192000000 10241093424 10692624384 1300438656 13.699 96 9 18 0 1311718544 9358528 1921360
18:52:53 32801 8192000000 10255653072 10702479360 1337427728 14.034 99 34 66 1 1354903456 9937632 7538096
18:54:26 54629 8192000000 10247942560 10710450176 1347085824 14.121 99 10 20 0 1357271776 7946176 2239776
18:56:29 54253 8192000000 10258366336 10715742208 1361873056 14.254 100 15 30 1 1372612512 7599872 3139584
18:59:25 64027 8192000000 10264207248 10723098624 1357526416 14.215 99 15 30 1 1369733632 8765904 3441312
19:01:29 40864 8192000000 10268464672 10725363712 1358315904 14.222 99 12 24 0 1370337488 9354528 2667056
19:03:11 76256 8192000000 10276145856 10736177152 1366545616 14.296 99 16 31 2 1381033072 10745104 3742352
19:06:15 82497 8192000000 10285318128 10743840768 1355416384 14.196 98 19 38 2 1368220768 8696272 4108112
19:08:41 53695 8192000000 10305458880 10744410112 1348171136 14.131 97 15 28 1 1364567632 13164224 3232272
19:10:37 93226 8192000000 10305387248 10774867968 1392893760 14.532 100 10 18 2 1409387760 14910112 1583888
19:13:11 77006 8192000000 10283763056 10736365568 1347201776 14.122 97 27 54 1 1359818640 6474624 6142240
19:15:28 58388 8192000000 10276435648 10743304192 1375490528 14.376 100 10 20 0 1387377952 9503952 2383472
19:18:25 35815 8192000000 10290218880 10736820224 1355363072 14.196 98 31 57 0 1370412928 8723328 6326528
19:20:04 70888 8192000000 10266784688 10729414656 1337219568 14.032 97 11 22 0 1348622288 8774640 2628080
19:22:15 55372 8192000000 10270497840 10722553856 1348134240 14.131 98 15 30 0 1359729536 8720016 2875280
19:24:11 53567 8192000000 10274716720 10714755072 1341489984 14.071 98 37 72 2 1360738000 10523296 8724720
19:26:05 125175 8192000000 10267840944 10739200000 1306593760 13.755 96 15 30 0 1325797120 15997008 3206352
19:29:11 57524 8192000000 10261187120 10714394624 1296081696 13.660 95 12 24 0 1309914240 11632240 2200304
19:31:09 88721 8192000000 10253029696 10704244736 1315063968 13.832 97 16 32 0 1332765264 14086608 3614688
19:33:48 51208 8192000000 10232198768 10665824256 1282132736 13.532 96 32 62 0 1297767888 9212048 6423104
19:35:40 87434 8192000000 10226301264 10685075456 1278220176 13.497 95 10 21 0 1290537280 9830880 2486224
19:38:08 30485 8192000000 9342403184 9641541632 487470464 5.616 36 2 4 0 492562832 5007296 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-06-2022 19:40:51 to 05-06-2022 19:41:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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:02, from 05-06-2022 19:41:07 to 05-06-2022 19:41:09.

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          247072
MON$OLDEST_ACTIVE               248250
MON$OLDEST_SNAPSHOT             248250
MON$NEXT_TRANSACTION            248250
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                       2043616
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Sun Jun  5 19:38:44 2022
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sun Jun  5 19:38:45 2022
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
--- end of diff output ---

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

05.06.22 19:41:10. 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-06-05 19:41:13 No severe PSQL-related problems occured
05.06.22 19:41:14. End of report.