Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2022 22: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 16550796 kB
MemAvailable 31336192 kB
Buffers 0 kB
Cached 15156976 kB
SwapCached 1220 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.9
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-01-06 22:38:02
DTS_END                         2022-01-06 22:38:02.8920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44438
TRN_ID                          241023
ATT_ID                          646
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: 9233.15 23468 1107978
customer order: creation 174.96 362 20995
customer order: refuse 15.57 354 1868
order to supplier: creation 46.24 1307 5549
order to supplier: removal 3.27 93266 392
invoice (draft): creation 47.57 3452 5708
invoice (draft): removal 52.31 738 6277
invoice accept: apply 42.38 17634 5086
invoice accept: cancel 47.06 826 5647
customer reserve: creation 3860.16 177 463219
customer reserve: removal 4646.20 51 557544
realization accept: apply 84.78 168 10174
realization accept: cancel 82.24 115 9869
payment from customer: creation 59.33 106 7120
payment from customer: removal 5.97 98 716
payment to supplier: creation 48.76 126 5851
payment to supplier: removal 3.96 84 475
service: total inventory turnovers 4.93 1160 591
service: total monetary turnovers 6.61 27 793
service: aggregate perf. data 0.83 19380 100
service: refresh index statistics 0.03 329926 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2022 22:39:58 to 06-01-2022 22:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9987
TEST_TIME 2 7875
TEST_TIME 3 7076
TEST_TIME 4 6305
TEST_TIME 5 5722
TEST_TIME 6 5309
TEST_TIME 7 5470
TEST_TIME 8 5569
TEST_TIME 9 6162
TEST_TIME 10 5957
TEST_TIME 11 6618
TEST_TIME 12 6954
TEST_TIME 13 7415
TEST_TIME 14 7590
TEST_TIME 15 7957
TEST_TIME 16 8072
TEST_TIME 17 8234
TEST_TIME 18 8567
TEST_TIME 19 8438
TEST_TIME 20 9061
TEST_TIME 21 9187
TEST_TIME 22 10003
TEST_TIME 23 9974
TEST_TIME 24 9944
TEST_TIME 25 9830
TEST_TIME 26 9930
TEST_TIME 27 10088
TEST_TIME 28 10490
TEST_TIME 29 10246
TEST_TIME 30 10245
TEST_TIME 31 10125
TEST_TIME 32 10008
TEST_TIME 33 10347
TEST_TIME 34 10277
TEST_TIME 35 10470
TEST_TIME 36 10396
TEST_TIME 37 10232
TEST_TIME 38 10200
TEST_TIME 39 10023
TEST_TIME 40 9964
TEST_TIME 41 9891
TEST_TIME 42 9929
TEST_TIME 43 9832
TEST_TIME 44 9862
TEST_TIME 45 9929
TEST_TIME 46 9888
TEST_TIME 47 9791
TEST_TIME 48 9667
TEST_TIME 49 9609
TEST_TIME 50 9590
TEST_TIME 51 9595
TEST_TIME 52 9736
TEST_TIME 53 9692
TEST_TIME 54 9684
TEST_TIME 55 9657
TEST_TIME 56 9803
TEST_TIME 57 9703
TEST_TIME 58 9687
TEST_TIME 59 9747
TEST_TIME 60 9710
TEST_TIME 61 9774
TEST_TIME 62 9777
TEST_TIME 63 9859
TEST_TIME 64 9833
TEST_TIME 65 9693
TEST_TIME 66 9647
TEST_TIME 67 9654
TEST_TIME 68 9591
TEST_TIME 69 9551
TEST_TIME 70 9513
TEST_TIME 71 9478
TEST_TIME 72 9450
TEST_TIME 73 9396
TEST_TIME 74 9384
TEST_TIME 75 9397
TEST_TIME 76 9334
TEST_TIME 77 9337
TEST_TIME 78 9342
TEST_TIME 79 9328
TEST_TIME 80 9289
TEST_TIME 81 9354
TEST_TIME 82 9317
TEST_TIME 83 9390
TEST_TIME 84 9437
TEST_TIME 85 9482
TEST_TIME 86 9561
TEST_TIME 87 9563
TEST_TIME 88 9555
TEST_TIME 89 9554
TEST_TIME 90 9621
TEST_TIME 91 9555
TEST_TIME 92 9468
TEST_TIME 93 9458
TEST_TIME 94 9427
TEST_TIME 95 9393
TEST_TIME 96 9412
TEST_TIME 97 9430
TEST_TIME 98 9426
TEST_TIME 99 9444
TEST_TIME 100 9428
TEST_TIME 101 9393
TEST_TIME 102 9445
TEST_TIME 103 9493
TEST_TIME 104 9455
TEST_TIME 105 9465
TEST_TIME 106 9507
TEST_TIME 107 9520
TEST_TIME 108 9485
TEST_TIME 109 9490
TEST_TIME 110 9417
TEST_TIME 111 9402
TEST_TIME 112 9380
TEST_TIME 113 9385
TEST_TIME 114 9385
TEST_TIME 115 9379
TEST_TIME 116 9387
TEST_TIME 117 9348
TEST_TIME 118 9303
TEST_TIME 119 9314
TEST_TIME 120 9238

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2022 22:40:01 to 06-01-2022 22:40:05.

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 1537502 1537502 0 0.00 0 26998 6 0 0
doc_list_biud 1537501 1537501 0 0.00 0 38662 24 0 0
sp_add_invoice_to_stock 5204 5086 118 2.26 29 197837 17635 0 118
sp_cancel_adding_invoice 5647 5647 0 0.00 4 57623 827 0 0
sp_cancel_client_order 1868 1868 0 0.00 4 10042 354 0 0
sp_cancel_customer_reserve 557544 557544 0 0.00 1 29878 51 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 5263 99 0 0
sp_cancel_pay_to_supplier 475 475 0 0.00 2 4126 84 0 0
sp_cancel_supplier_invoice 6286 6277 9 0.14 1 38672 738 0 9
sp_cancel_supplier_order 392 392 0 0.00 6 935959 93267 0 0
sp_cancel_write_off 9869 9869 0 0.00 1 15460 115 0 0
sp_client_order 20995 20995 0 0.00 3 27273 363 0 0
sp_customer_reserve 463219 463219 0 0.00 0 87843 177 0 0
sp_fill_shopping_cart 32236 32236 0 0.00 1 16897 38 0 0
sp_fill_shopping_cart_clo_res 463209 463209 0 0.00 0 15879 14 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 21095 273 0 0
sp_kill_cost_storno 16707 16707 0 0.00 0 7772 54 0 0
sp_kill_qstorno_ret_qs2qd 1131626 1131626 0 0.00 0 38657 28 0 0
sp_kill_qty_storno 596845 596845 0 0.00 0 38660 62 0 0
sp_lock_selected_doc 21269 21269 0 0.00 0 6893 5 0 0
sp_make_cost_storno 28220 28220 0 0.00 0 26991 51 0 0
sp_multiply_rows_for_pdistr 28220 28220 0 0.00 0 7009 29 0 0
sp_multiply_rows_for_qdistr 20995 20995 0 0.00 0 27058 166 0 0
sp_pay_from_customer 7120 7120 0 0.00 4 21641 107 0 0
sp_pay_to_supplier 5851 5851 0 0.00 5 22018 126 0 0
sp_payment_common 12971 12971 0 0.00 1 9190 23 0 0
sp_qd_handle_on_cancel_clo 1868 1868 0 0.00 0 10035 329 0 0
sp_qd_handle_on_invoice_upd_sts 10721 10721 0 0.00 0 23270 313 0 0
sp_qd_handle_on_reserve_upd_sts 10174 10174 0 0.00 0 11555 46 0 0
sp_reserve_write_off 10174 10174 0 0.00 6 15231 169 0 0
sp_supplier_invoice 5708 5708 0 0.00 6 94506 3453 0 0
sp_supplier_order 5549 5549 0 0.00 5 32321 1307 0 0
srv_aggregate_perf_data 100 100 0 0.00 2793 347744 19381 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2280 149042 48388 0 0
srv_find_qd_qs_mism 1060104 1060104 0 0.00 0 14975 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 38 76752 1160 0 0
srv_make_money_saldo 793 793 0 0.00 1 2250 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 171302 547454 329926 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 5086 5086 0 0.00 0 10077 169 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 4639 114 0 0
t$perf-norm:sp_cancel_client_order 1868 1868 0 0.00 0 1845 11 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 8970 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 784 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 475 475 0 0.00 0 944 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1217 15 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 47852 1753 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 365 4 0 0
t$perf-norm:sp_client_order 20995 20995 0 0.00 0 23886 20 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 6092 14 0 0
t$perf-norm:sp_pay_from_customer 7120 7120 0 0.00 0 9533 14 0 0
t$perf-norm:sp_pay_to_supplier 5851 5851 0 0.00 0 7797 10 0 0
t$perf-norm:sp_reserve_write_off 10174 10174 0 0.00 0 23934 16 0 0
t$perf-norm:sp_supplier_invoice 5708 5708 0 0.00 0 7571 19 0 0
t$perf-norm:sp_supplier_order 5549 5549 0 0.00 0 5019 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 734 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1400 25 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 5822 40 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 19819 42 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10370 10134 236 2.27 0 5189 20 0 236
v_all_customers 38008 38008 0 0.00 0 20 1 0 0
v_all_suppliers 30848 30848 0 0.00 0 24 1 0 0
v_all_wares 41990 41990 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1914 11 0 0
v_cancel_client_order 3736 3736 0 0.00 0 3447 5 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 5169 11 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 3293 8 0 0
v_cancel_payment_to_supplier 950 950 0 0.00 0 2171 9 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 422 12 0 18
v_cancel_supplier_order 784 784 0 0.00 0 4227 11 0 0
v_cancel_write_off 2122 2122 0 0.00 0 5279 17 0 0
v_max_id_clo_ord 5547 5547 0 0.00 0 563 2 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 21508 39 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 7943 4 0 0
v_max_non_paid_invoice 1668 1668 0 0.00 0 391 2 0 0
v_max_non_paid_realizn 3842 3842 0 0.00 0 1327 4 0 0
v_min_id_clo_ord 5547 5547 0 0.00 0 8103 8 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 6006 11 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 6868 12 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 9187 16 0 0
v_min_non_paid_realizn 7120 7120 0 0.00 0 5378 16 0 0
v_our_firm 10538 10538 0 0.00 0 15 1 0 0
v_reserve_write_off 20348 20348 0 0.00 0 8934 7 0 0
x_lock_depdocs_on_canc_invoice 11514 11514 0 0.00 0 23255 115 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 12315 355 0 0
x_make_qstorno_1000_1200 5549 5549 0 0.00 1 32145 1227 0 0
x_make_qstorno_1200_2000 5708 5708 0 0.00 2 94497 3317 0 0
x_make_qstorno_2100_3300 463219 463219 0 0.00 0 87842 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2022 22:40:06 to 06-01-2022 22:40:10.

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
20:08:06 3 8192000000 8800845360 8812728320 6869232 0.083 2 2 3 0 7009040 63616 76192
20:09:07 27062 8192000000 9920782160 10235801600 1000990576 10.888 100 90 180 0 1030851952 6206896 23654480
20:10:42 18090 8192000000 9957108432 10281639936 1040238000 11.267 100 92 184 0 1071968864 8070848 23660016
20:12:11 21165 8192000000 9982389088 10318286848 1066187792 11.516 100 78 156 0 1094912496 9537744 19186960
20:13:37 12909 8192000000 10041876976 10391351296 1127370288 12.097 100 75 150 1 1156141888 10833776 17937824
20:14:53 9947 8192000000 10101834256 10464452608 1182244080 12.611 100 81 162 0 1214060304 13678576 18137648
20:16:05 5568 8192000000 10134243168 10507902976 1224770672 13.006 100 78 156 0 1255130528 12444720 17915136
20:17:11 3830 8192000000 10164241104 10546466816 1253074096 13.266 100 79 158 1 1283367488 12652960 17640432
20:18:17 1937 8192000000 10188042240 10577264640 1280372576 13.516 100 77 156 1 1310080912 12696320 17012016
20:19:20 2915 8192000000 10210274656 10604384256 1302040320 13.714 100 71 140 1 1330389680 12893648 15455712
20:20:24 4317 8192000000 10206712944 10611937280 1311563408 13.800 100 46 93 0 1331034240 9551456 9919376
20:21:30 6943 8192000000 10212208752 10621243392 1316962704 13.849 100 29 58 0 1330944896 7420608 6561584
20:22:41 49231 8192000000 10222923968 10630152192 1319902240 13.876 100 37 75 1 1334553344 5870704 8780400
20:24:31 7641 8192000000 10214624320 10637766656 1327143744 13.941 100 23 46 2 1337881216 5711152 5026320
20:25:39 8503 8192000000 10221901920 10645708800 1331791536 13.983 100 31 61 1 1345796640 6271696 7733408
20:26:50 54725 8192000000 10220833152 10644299776 1322001024 13.895 99 35 70 0 1337261232 7637312 7622896
20:28:45 6642 8192000000 10202247712 10618265600 1295116704 13.651 98 37 73 1 1310072624 6453920 8502000
20:29:52 6702 8192000000 10146350208 10544783360 1218152896 12.945 95 14 28 0 1227003648 5493456 3357296
20:31:01 11289 8192000000 10145395040 10550751232 1239881984 13.145 98 14 28 2 1248937968 5978288 3077696
20:32:15 25818 8192000000 10134876624 10537959424 1231817232 13.071 98 22 44 2 1241764464 5074272 4872960
20:33:41 5986 8192000000 10161473456 10564468736 1256946160 13.302 99 24 49 2 1267243536 4923968 5373408
20:34:47 8802 8192000000 10184998960 10589806592 1289790080 13.602 100 16 31 3 1298694048 5284512 3619456
20:35:57 62642 8192000000 10190347488 10614345728 1306786288 13.757 100 41 82 1 1322933792 6617872 9529632
20:38:01 6832 8192000000 10227655680 10633220096 1321090176 13.887 100 37 72 2 1335020640 6033424 7897040
20:39:15 44672 8192000000 10255590576 10674995200 1348604288 14.135 100 47 94 2 1369313984 9979296 10730400
20:41:14 3301 8192000000 10297271728 10707738624 1375214400 14.374 100 60 121 1 1406071632 17088544 13768688
20:42:21 79080 8192000000 10300985360 10722115584 1384360656 14.456 100 42 83 2 1412215568 18150896 9704016
20:44:44 16230 8192000000 10300370000 10723938304 1384161968 14.454 100 38 76 0 1415758992 22839072 8757952
20:46:18 4362 8192000000 10319543968 10742185984 1384392896 14.456 99 59 115 0 1423709376 25348880 13967600
20:47:27 9682 8192000000 10339398736 10772475904 1413564640 14.716 100 41 82 2 1451160304 27324704 10270960
20:48:40 6520 8192000000 10355958176 10776973312 1418043040 14.755 100 67 134 0 1466936976 32481040 16412896
20:49:54 4024 8192000000 10361839040 10782851072 1415256160 14.731 100 70 140 0 1466660176 34265376 17138640
20:51:08 2770 8192000000 10364293552 10779672576 1415247264 14.731 100 92 182 1 1467773680 30771680 21754736
20:52:14 4115 8192000000 10344474864 10764992512 1399274480 14.589 100 68 136 1 1446945392 30332384 17338528
20:53:24 2278 8192000000 10320932176 10728239104 1375220656 14.374 100 75 146 2 1421016960 27376512 18419792
20:54:31 4091 8192000000 10322461760 10733400064 1381941072 14.434 100 67 134 0 1426936112 27657600 17337440
20:55:44 4308 8192000000 10325473776 10744356864 1383639840 14.449 100 69 138 0 1430175680 29399008 17136832
20:56:56 5076 8192000000 10307099984 10732347392 1374714720 14.369 100 40 80 0 1414552528 29316320 10521488
20:58:07 18106 8192000000 10295219888 10725240832 1335939440 14.021 97 32 63 0 1377959760 32940672 9079648
20:59:29 18817 8192000000 10279819584 10701959168 1321925712 13.894 98 37 75 1 1364394880 32067200 10401968
21:00:52 13920 8192000000 10275561584 10710372352 1355415808 14.196 99 30 60 2 1391296480 27840656 8040016
21:02:07 3215 8192000000 10262345120 10685063168 1339321648 14.051 100 36 72 1 1367223008 18673440 9227920
21:03:12 7606 8192000000 10273310960 10704625664 1352527024 14.170 100 24 48 2 1379924032 21303792 6093216
21:04:28 28092 8192000000 10274666432 10711265280 1340332912 14.060 99 15 30 1 1367176480 22908768 3934800
21:06:46 35488 8192000000 10286921072 10710441984 1343637888 14.090 99 45 84 2 1363814384 10294080 9882416
21:08:24 30126 8192000000 10276414448 10716831744 1368607408 14.315 100 26 52 0 1390770080 16030352 6132320
21:10:04 61287 8192000000 10306454272 10735120384 1372058336 14.345 100 26 53 1 1396679104 18418608 6202160
21:12:09 62927 8192000000 10297247072 10763137024 1368300672 14.312 99 7 14 0 1391046208 21290544 1454992
21:14:17 38970 8192000000 10298848992 10744725504 1383939008 14.452 99 11 22 0 1395827472 9713312 2175152
21:15:56 75307 8192000000 10294174496 10746896384 1386068224 14.471 100 34 67 1 1408527520 14614288 7845008
21:18:46 140141 8192000000 10276484560 10727448576 1330841168 13.975 96 19 38 1 1352066736 16850976 4374592
21:22:32 20738 8192000000 10301719360 10744901632 1390101728 14.507 100 56 112 0 1412407952 8466048 13840176
21:23:54 35161 8192000000 10302639392 10769182720 1403223408 14.624 100 14 28 0 1417699728 11158048 3318272
21:25:46 75631 8192000000 10324674368 10783428608 1417243904 14.748 100 17 34 0 1433709968 12157088 4308976
21:28:03 79280 8192000000 10293541712 10754134016 1378080992 14.399 98 13 26 0 1399244832 17697520 3466320
21:30:23 58389 8192000000 10300464304 10773643264 1396633936 14.565 100 10 20 0 1422033008 22248112 3150960
21:32:22 33139 8192000000 10295836800 10750996480 1391455648 14.519 100 21 42 0 1407766032 10686128 5624256
21:34:06 51815 8192000000 10271395520 10734915584 1363676848 14.270 99 14 28 1 1386958624 19802032 3479744
21:35:58 24908 8192000000 10292579136 10732687360 1382342144 14.437 100 46 92 0 1404543808 11883184 10318480
21:37:24 28129 8192000000 10272840752 10715365376 1343703760 14.091 98 26 51 0 1356442656 6862576 5876320
21:39:09 120733 8192000000 10280235536 10723303424 1273027824 13.449 92 25 50 0 1287635616 9480720 5127072
21:42:12 136926 8192000000 10272976976 10721513472 1295226448 13.652 94 17 33 0 1311103648 11852176 4025024
21:45:30 44320 8192000000 10226677872 10680696832 1310956048 13.795 97 19 38 0 1326148976 10585472 4607456
21:47:15 51256 8192000000 10251977200 10712395776 1353736128 14.181 100 8 16 1 1369450416 13846208 1868080
21:49:07 65934 8192000000 10254096688 10706358272 1343338144 14.087 99 12 24 0 1354054912 7431632 3285136
21:51:14 78402 8192000000 10244110640 10695954432 1323246496 13.906 97 15 30 1 1340042688 13508416 3287776
21:53:36 30552 8192000000 10261820400 10713579520 1355591488 14.198 100 11 22 0 1367316128 9314096 2410544
21:55:23 83496 8192000000 10246598224 10707537920 1282470240 13.536 94 9 18 0 1296391904 11834128 2087536
21:57:54 31212 8192000000 10260715744 10703093760 1342709952 14.082 99 32 64 1 1358071744 8481184 6880608
21:59:28 55317 8192000000 10282954384 10738278400 1374259536 14.365 100 26 52 1 1399127712 18709200 6158976
22:01:34 38968 8192000000 10278631104 10731294720 1359224768 14.230 99 17 34 0 1377441504 14390672 3826064
22:03:41 149042 8192000000 10287330720 10746015744 1327998816 13.949 96 13 26 0 1350823088 19882048 2942224
22:07:11 103105 8192000000 10297992144 10761449472 1374206752 14.365 99 15 30 0 1389323776 11977872 3139152
22:09:57 83917 8192000000 10292687200 10755817472 1392551024 14.529 100 20 40 0 1409034096 12068624 4414448
22:12:29 57120 8192000000 10310046480 10772148224 1395349280 14.554 99 10 20 0 1414815360 17501024 1965056
22:14:26 69435 8192000000 10298235104 10772905984 1382552016 14.439 99 5 10 1 1399975920 16432672 991232
22:16:36 115501 8192000000 10301100352 10731499520 1296397904 13.662 93 16 31 2 1314370960 14430000 3543056
22:19:35 52066 8192000000 10298031280 10748346368 1382410560 14.438 99 29 58 1 1401582848 12647584 6524704
22:21:32 65697 8192000000 10298133616 10729922560 1348869648 14.137 97 32 64 0 1365889040 9544128 7475264
22:23:39 104963 8192000000 10279130976 10732666880 1328098704 13.950 96 24 48 0 1345264944 11918544 5247696
22:26:24 19459 8192000000 10294430288 10736148480 1364867808 14.281 99 38 73 1 1379575088 5868848 8838432
22:27:44 85605 8192000000 10305247568 10749542400 1375010096 14.372 99 33 66 1 1400179872 17850752 7319024
22:30:10 82015 8192000000 10315642336 10763366400 1390338368 14.509 100 28 56 1 1413236704 16358128 6540208
22:32:36 67761 8192000000 10252574160 10709848064 1330350912 13.970 97 13 26 1 1343409312 9931824 3126576
22:34:47 52457 8192000000 10269247184 10717339648 1363351888 14.267 100 14 28 1 1377229088 10148016 3729184
22:36:49 64491 8192000000 10272677616 10732036096 1347535856 14.125 98 14 28 0 1365004912 13863904 3605152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-01-2022 22:40:11 to 06-01-2022 22:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 06-01-2022 22:40:27 to 06-01-2022 22:40:29.

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          241648
MON$OLDEST_ACTIVE               241649
MON$OLDEST_SNAPSHOT             241649
MON$NEXT_TRANSACTION            241649
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                       2024384
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:556: 
:557: mscs-test-161.budzdorov.ru    Thu Jan  6 22:38:19 2022
:558:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:559: 
--- end of diff output ---

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

06.01.22 22:40:30. 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-01-06 22:40:32 No severe PSQL-related problems occured
06.01.22 22:40:34. End of report.