Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.05.2022 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16165448 kB
MemAvailable 31383464 kB
Buffers 0 kB
Cached 15513220 kB
SwapCached 2588 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-05-22 16:38:16
DTS_END                         2022-05-22 16:38:16.8460
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/37560
TRN_ID                          246837
ATT_ID                          669
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: 9805.77 19308 1176692
customer order: creation 177.83 344 21340
customer order: refuse 14.68 334 1761
order to supplier: creation 47.18 1233 5661
order to supplier: removal 3.57 104169 428
invoice (draft): creation 47.07 3540 5648
invoice (draft): removal 57.67 772 6920
invoice accept: apply 42.56 17450 5107
invoice accept: cancel 51.78 766 6213
customer reserve: creation 3971.60 170 476592
customer reserve: removal 5080.32 57 609638
realization accept: apply 85.85 174 10302
realization accept: cancel 92.14 105 11057
payment from customer: creation 61.25 107 7350
payment from customer: removal 5.54 98 665
payment to supplier: creation 50.38 122 6045
payment to supplier: removal 3.47 110 416
service: total inventory turnovers 4.94 1202 593
service: total monetary turnovers 7.13 40 855
service: aggregate perf. data 0.81 23795 97
service: refresh index statistics 0.03 231571 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-05-2022 16:39:10 to 22-05-2022 16:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5883
TEST_TIME 2 5445
TEST_TIME 3 5643
TEST_TIME 4 5436
TEST_TIME 5 5708
TEST_TIME 6 5582
TEST_TIME 7 5673
TEST_TIME 8 5954
TEST_TIME 9 6300
TEST_TIME 10 6446
TEST_TIME 11 7077
TEST_TIME 12 7128
TEST_TIME 13 7218
TEST_TIME 14 7190
TEST_TIME 15 7115
TEST_TIME 16 7599
TEST_TIME 17 8292
TEST_TIME 18 8425
TEST_TIME 19 8654
TEST_TIME 20 8724
TEST_TIME 21 8845
TEST_TIME 22 8870
TEST_TIME 23 9143
TEST_TIME 24 9209
TEST_TIME 25 9423
TEST_TIME 26 10168
TEST_TIME 27 9952
TEST_TIME 28 9876
TEST_TIME 29 10768
TEST_TIME 30 10876
TEST_TIME 31 11231
TEST_TIME 32 11165
TEST_TIME 33 11048
TEST_TIME 34 11083
TEST_TIME 35 11202
TEST_TIME 36 11000
TEST_TIME 37 10923
TEST_TIME 38 10849
TEST_TIME 39 10850
TEST_TIME 40 10774
TEST_TIME 41 10621
TEST_TIME 42 10413
TEST_TIME 43 10349
TEST_TIME 44 10449
TEST_TIME 45 10370
TEST_TIME 46 10324
TEST_TIME 47 10249
TEST_TIME 48 10270
TEST_TIME 49 10198
TEST_TIME 50 10215
TEST_TIME 51 10239
TEST_TIME 52 10247
TEST_TIME 53 10188
TEST_TIME 54 10066
TEST_TIME 55 9973
TEST_TIME 56 9984
TEST_TIME 57 9933
TEST_TIME 58 9861
TEST_TIME 59 9966
TEST_TIME 60 9924
TEST_TIME 61 9947
TEST_TIME 62 10040
TEST_TIME 63 10079
TEST_TIME 64 10131
TEST_TIME 65 10191
TEST_TIME 66 10192
TEST_TIME 67 10203
TEST_TIME 68 10199
TEST_TIME 69 10213
TEST_TIME 70 10230
TEST_TIME 71 10348
TEST_TIME 72 10317
TEST_TIME 73 10200
TEST_TIME 74 10151
TEST_TIME 75 10206
TEST_TIME 76 10103
TEST_TIME 77 10069
TEST_TIME 78 10085
TEST_TIME 79 10059
TEST_TIME 80 10012
TEST_TIME 81 10020
TEST_TIME 82 9959
TEST_TIME 83 9938
TEST_TIME 84 9840
TEST_TIME 85 9838
TEST_TIME 86 9863
TEST_TIME 87 9842
TEST_TIME 88 9885
TEST_TIME 89 9873
TEST_TIME 90 9837
TEST_TIME 91 9833
TEST_TIME 92 9936
TEST_TIME 93 9916
TEST_TIME 94 9952
TEST_TIME 95 9984
TEST_TIME 96 9993
TEST_TIME 97 10012
TEST_TIME 98 9972
TEST_TIME 99 10043
TEST_TIME 100 10092
TEST_TIME 101 10046
TEST_TIME 102 9976
TEST_TIME 103 9923
TEST_TIME 104 9953
TEST_TIME 105 9898
TEST_TIME 106 9968
TEST_TIME 107 9980
TEST_TIME 108 9953
TEST_TIME 109 9918
TEST_TIME 110 9862
TEST_TIME 111 9883
TEST_TIME 112 9868
TEST_TIME 113 9860
TEST_TIME 114 9841
TEST_TIME 115 9847
TEST_TIME 116 9892
TEST_TIME 117 9881
TEST_TIME 118 9896
TEST_TIME 119 9877
TEST_TIME 120 9810

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-05-2022 16:39:13 to 22-05-2022 16:39:17.

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 1618129 1618129 0 0.00 0 24728 6 0 0
doc_list_biud 1618129 1618129 0 0.00 0 40115 27 0 0
sp_add_invoice_to_stock 5204 5107 97 1.86 31 176139 17450 0 97
sp_cancel_adding_invoice 6213 6213 0 0.00 3 62887 767 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 8577 334 0 0
sp_cancel_customer_reserve 609638 609638 0 0.00 1 22046 57 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 5581 99 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 4800 111 0 0
sp_cancel_supplier_invoice 6931 6920 11 0.15 1 40123 772 0 11
sp_cancel_supplier_order 428 428 0 0.00 11 1029846 104170 0 0
sp_cancel_write_off 11057 11057 0 0.00 1 9211 105 0 0
sp_client_order 21340 21340 0 0.00 3 26042 345 0 0
sp_customer_reserve 476592 476592 0 0.00 0 126982 170 0 0
sp_fill_shopping_cart 32637 32637 0 0.00 1 17757 36 0 0
sp_fill_shopping_cart_clo_res 476576 476576 0 0.00 0 21291 12 0 0
sp_get_clo_for_invoice 5091 5091 0 0.00 0 21282 272 0 0
sp_kill_cost_storno 18351 18351 0 0.00 0 10468 59 0 0
sp_kill_qstorno_ret_qs2qd 1237681 1237681 0 0.00 0 40111 31 0 0
sp_kill_qty_storno 651410 651410 0 0.00 0 40114 67 0 0
sp_lock_selected_doc 21308 21308 0 0.00 0 7126 5 0 0
sp_make_cost_storno 28788 28788 0 0.00 0 24711 55 0 0
sp_multiply_rows_for_pdistr 28788 28788 0 0.00 0 17856 29 0 0
sp_multiply_rows_for_qdistr 21340 21340 0 0.00 0 15908 155 0 0
sp_pay_from_customer 7350 7350 0 0.00 4 14400 108 0 0
sp_pay_to_supplier 6045 6045 0 0.00 5 18131 123 0 0
sp_payment_common 13395 13395 0 0.00 1 12652 25 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 8569 309 0 0
sp_qd_handle_on_invoice_upd_sts 11304 11304 0 0.00 0 27819 337 0 0
sp_qd_handle_on_reserve_upd_sts 10302 10302 0 0.00 0 20361 57 0 0
sp_reserve_write_off 10302 10302 0 0.00 6 20439 175 0 0
sp_supplier_invoice 5648 5648 0 0.00 7 105776 3540 0 0
sp_supplier_order 5661 5661 0 0.00 5 28197 1234 0 0
srv_aggregate_perf_data 97 97 0 0.00 2018 430315 23796 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1797 131960 44113 0 0
srv_find_qd_qs_mism 1127537 1127537 0 0.00 0 17867 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 29 65379 1202 0 0
srv_make_money_saldo 855 855 0 0.00 1 9908 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 164070 318621 231572 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 0 13432 177 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 3247 77 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 4858 20 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 748 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 4298 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 280 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 710 15 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 25278 1712 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 1338 7 0 0
t$perf-norm:sp_client_order 21340 21340 0 0.00 0 14273 24 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1675 9 0 0
t$perf-norm:sp_pay_from_customer 7350 7350 0 0.00 0 6899 12 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 4754 11 0 0
t$perf-norm:sp_reserve_write_off 10302 10302 0 0.00 0 4924 10 0 0
t$perf-norm:sp_supplier_invoice 5648 5648 0 0.00 0 8403 26 0 0
t$perf-norm:sp_supplier_order 5661 5661 0 0.00 0 8260 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 180 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 387 9 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 1458 25 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 2861 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10376 10182 194 1.86 0 8984 19 0 194
v_all_customers 39048 39048 0 0.00 0 17 1 0 0
v_all_suppliers 31596 31596 0 0.00 0 15 1 0 0
v_all_wares 42680 42680 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 3124 12 0 0
v_cancel_client_order 3522 3522 0 0.00 0 1672 4 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 2430 12 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1606 5 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2359 10 0 0
v_cancel_supplier_invoice 844 822 22 2.60 0 848 13 0 22
v_cancel_supplier_order 856 856 0 0.00 0 1787 7 0 0
v_cancel_write_off 2138 2138 0 0.00 0 2776 13 0 0
v_max_id_clo_ord 5661 5661 0 0.00 0 2177 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 1297 11 0 0
v_max_id_ord_sup 5636 5636 0 0.00 0 2874 2 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 1434 4 0 0
v_max_non_paid_realizn 3774 3774 0 0.00 0 9747 7 0 0
v_min_id_clo_ord 5661 5661 0 0.00 0 2339 6 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 2188 6 0 0
v_min_id_ord_sup 5636 5636 0 0.00 0 5203 6 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 5979 14 0 0
v_min_non_paid_realizn 7350 7350 0 0.00 0 8119 18 0 0
v_our_firm 10784 10784 0 0.00 0 14 1 0 0
v_reserve_write_off 20604 20604 0 0.00 0 3609 7 0 0
x_lock_depdocs_on_canc_invoice 12722 12722 0 0.00 0 21041 109 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 16743 385 0 0
x_make_qstorno_1000_1200 5661 5661 0 0.00 1 28188 1152 0 0
x_make_qstorno_1200_2000 5648 5648 0 0.00 3 102552 3408 0 0
x_make_qstorno_2100_3300 476592 476592 0 0.00 0 126980 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-05-2022 16:39:18 to 22-05-2022 16:39:22.

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:20 6 8192000000 8807519872 8821903360 12417488 0.151 4 3 4 0 12604112 77616 109008
14:09:20 37424 8192000000 9924771312 10238574592 992008656 10.801 100 83 166 0 1019645360 6020656 21616048
14:11:02 15605 8192000000 9961709728 10286333952 1044046272 11.304 100 86 171 1 1074244272 8502368 21695632
14:12:22 11036 8192000000 9995065568 10329174016 1080363680 11.651 100 87 174 1 1112052832 10022160 21666992
14:13:39 21827 8192000000 10042455232 10391322624 1127491344 12.098 100 79 159 0 1156888720 10625200 18772176
14:15:07 6607 8192000000 10091990592 10449469440 1177927264 12.571 100 84 168 0 1209873040 12463968 19481808
14:16:17 4981 8192000000 10141819360 10515394560 1230064416 13.055 100 74 149 0 1258102800 11715104 16323280
14:17:25 2779 8192000000 10167772096 10545401856 1256373920 13.297 100 88 172 1 1288131152 13404672 18352560
14:18:30 1610 8192000000 10206168864 10587013120 1285559488 13.564 100 91 182 0 1319068976 12958208 20551280
14:19:33 31839 8192000000 10194023232 10601914368 1301413664 13.708 100 34 69 0 1315898528 6860544 7624320
14:21:06 11796 8192000000 10201895584 10618576896 1318052512 13.859 100 32 64 0 1333500048 7935872 7511664
14:22:24 5335 8192000000 10211320352 10625368064 1323138336 13.905 100 29 56 1 1336295440 6460928 6696176
14:23:32 61086 8192000000 10221208944 10643480576 1331733232 13.983 100 23 46 2 1341450432 5142416 4574784
14:25:34 4365 8192000000 10235053968 10653298688 1343490864 14.089 100 41 82 0 1359665120 6743472 9430784
14:26:40 11024 8192000000 10227049744 10652336128 1340589776 14.063 100 23 46 1 1351660752 5399728 5671248
14:27:52 15082 8192000000 10196930448 10614599680 1284103696 13.550 97 15 30 1 1293130960 5686384 3340880
14:29:09 29872 8192000000 10170818864 10583543808 1259681216 13.327 97 25 50 1 1270824272 5291024 5852032
14:30:43 5172 8192000000 10138693488 10534772736 1233319184 13.085 99 25 50 1 1245394528 6411440 5663904
14:31:49 7794 8192000000 10115503024 10512965632 1164954832 12.450 93 17 35 1 1174121200 4846752 4319616
14:32:58 57586 8192000000 10176355568 10577989632 1278102688 13.496 100 45 89 2 1294443808 5138640 11202480
14:34:57 9955 8192000000 10191178288 10603749376 1297679632 13.674 100 34 68 1 1311993824 6372592 7941600
14:36:08 8418 8192000000 10193785840 10609078272 1306217296 13.752 100 36 72 0 1320857312 4909856 9730160
14:37:17 16503 8192000000 10203425344 10623987712 1317368576 13.853 100 8 16 0 1324940976 5276704 2295696
14:38:37 37625 8192000000 10237356208 10652160000 1334489312 14.008 100 37 74 2 1352810240 9679984 8640944
14:40:22 18955 8192000000 10274530160 10683686912 1349924400 14.147 99 59 118 0 1373628064 10438064 13265600
14:41:45 8476 8192000000 10290706976 10705563648 1375304112 14.375 100 54 107 1 1397814400 10274176 12236112
14:43:03 21784 8192000000 10300038720 10725117952 1392339680 14.527 100 42 82 1 1416708352 14639392 9729280
14:44:32 3385 8192000000 10316975952 10735058944 1397134768 14.569 100 74 147 0 1429042144 14550080 17357296
14:45:41 10732 8192000000 10306967360 10732072960 1392907152 14.532 100 43 85 0 1420900864 18000544 9993168
14:46:56 14946 8192000000 10326600640 10752323584 1411401408 14.696 100 49 98 1 1443572720 19632704 12538608
14:48:15 7092 8192000000 10321383584 10743164928 1392563872 14.529 100 63 123 1 1429928784 21664768 15700144
14:49:30 5982 8192000000 10329714592 10743336960 1392360432 14.527 100 79 157 2 1436783904 25748944 18674528
14:50:40 15580 8192000000 10305090848 10728153088 1375728000 14.378 99 54 108 2 1417431008 28381152 13321856
14:52:01 2421 8192000000 10313087760 10721533952 1373515184 14.359 100 78 154 3 1419289008 25636848 20136976
14:53:15 3725 8192000000 10335377104 10748485632 1391599632 14.520 100 69 136 0 1443985232 35265376 17120224
14:54:24 2207 8192000000 10355036544 10762174464 1390754736 14.513 100 78 150 0 1455021056 45155952 19110368
14:55:32 4016 8192000000 10345730736 10759766016 1393000256 14.533 100 71 141 1 1449286160 38445888 17840016
14:56:42 6126 8192000000 10342545792 10744856576 1380328560 14.419 100 89 178 1 1440414880 38017616 22068704
14:57:52 1795 8192000000 10371159376 10777968640 1409313328 14.678 100 81 160 0 1467804800 38946832 19544640
14:58:59 5258 8192000000 10348075440 10772934656 1394606016 14.547 99 56 110 1 1447776976 39240896 13930064
15:00:11 16684 8192000000 10354039184 10784804864 1412944480 14.710 100 31 62 1 1461934112 40070208 8919424
15:01:31 4563 8192000000 10361691808 10782547968 1402409632 14.616 100 63 125 1 1464699008 45358272 16931104
15:02:40 22640 8192000000 10337817568 10766163968 1369426640 14.322 98 33 66 0 1424667088 45412432 9828016
15:04:08 10556 8192000000 10342111376 10776576000 1380878112 14.424 100 30 60 1 1444598160 54700448 9019600
15:05:22 29579 8192000000 10325518688 10760982528 1382360496 14.438 100 45 88 0 1433078480 39401584 11316400
15:07:20 13780 8192000000 10329158896 10766958592 1376985360 14.390 100 21 42 0 1429739424 46420128 6333936
15:08:37 67133 8192000000 10300435840 10759708672 1366246096 14.293 98 13 26 0 1396221264 27163808 2811360
15:10:44 13278 8192000000 10317551696 10744242176 1390625360 14.511 100 62 120 2 1412004352 7274064 14104928
15:11:58 59905 8192000000 10323759680 10775683072 1380562864 14.422 99 11 23 0 1406749456 23754624 2431968
15:14:02 104474 8192000000 10332546528 10765975552 1400600688 14.600 100 24 48 2 1415829904 9150592 6078624
15:16:48 129929 8192000000 10318066176 10777657344 1375734896 14.378 98 15 30 2 1400650864 20981856 3934112
15:19:59 80088 8192000000 10313112880 10763010048 1391971488 14.523 99 27 52 0 1410718720 11189856 7557376
15:22:24 131960 8192000000 10332113824 10780233728 1401267760 14.606 99 43 86 2 1421512352 9955328 10289264
15:25:39 39706 8192000000 10326604160 10777239552 1398419808 14.581 99 28 56 0 1417195248 11530000 7245440
15:27:21 45533 8192000000 10303450224 10766692352 1378222992 14.401 98 26 52 3 1396701024 12254080 6223952
15:29:48 78227 8192000000 10292294544 10765848576 1388603664 14.493 99 7 14 0 1402949200 12610992 1734544
15:32:06 60980 8192000000 10268066896 10729254912 1337455216 14.034 97 10 20 0 1350445728 10838032 2152480
15:34:11 50707 8192000000 10270605056 10737741824 1352999680 14.174 98 13 25 0 1366647648 10900048 2747920
15:36:16 50042 8192000000 10274586480 10731671552 1351928672 14.165 98 24 47 1 1368444352 11295504 5220176
15:38:22 28431 8192000000 10297671456 10753675264 1392256672 14.526 100 23 46 0 1410271376 12663424 5351280
15:39:51 51116 8192000000 10299764672 10759266304 1377172400 14.391 99 12 24 1 1394457696 14704896 2580400
15:41:42 74587 8192000000 10279294528 10737496064 1334098480 14.004 97 12 24 0 1350326768 13883728 2344560
15:43:57 62119 8192000000 10274454128 10735136768 1324727536 13.919 96 7 14 0 1343194112 16919536 1547040
15:46:00 42819 8192000000 10294139824 10741895168 1364883664 14.281 99 29 59 1 1387071088 15877632 6309792
15:47:47 64782 8192000000 10292355008 10752901120 1361380112 14.250 98 11 22 1 1380725232 16505744 2839376
15:50:38 113542 8192000000 10251261088 10696970240 1309672368 13.783 95 27 54 0 1325389376 8935328 6781680
15:54:21 43877 8192000000 10277757664 10741342208 1370151184 14.328 100 13 26 1 1391464224 18462224 2850816
15:56:08 78098 8192000000 10250139632 10714959872 1323027840 13.904 97 16 32 2 1342728848 15369664 4331344
15:58:30 30528 8192000000 10271154944 10717016064 1342885120 14.083 98 36 71 2 1359851408 9534256 7432032
16:00:32 52963 8192000000 10280204112 10735349760 1357794848 14.218 98 20 40 0 1377541520 15209408 4537264
16:02:25 84160 8192000000 10271773776 10742571008 1364585168 14.279 99 10 20 0 1380268432 13394352 2288912
16:04:57 68945 8192000000 10285448112 10751836160 1380759952 14.423 100 14 28 0 1402002976 18215728 3027296
16:07:48 25088 8192000000 10287655104 10733993984 1369672720 14.324 99 23 44 3 1382880240 8043728 5163792
16:09:30 82105 8192000000 10252567408 10704912384 1320767472 13.884 95 9 18 1 1336366864 13140128 2459264
16:11:53 40722 8192000000 10295751968 10750177280 1378805040 14.406 100 41 82 0 1402135920 14517984 8812896
16:13:34 63772 8192000000 10279182320 10740576256 1345151904 14.104 97 18 36 2 1362909424 13882752 3874768
16:15:47 37502 8192000000 10302486544 10745237504 1347128144 14.122 97 36 71 0 1364904240 9884624 7891472
16:17:26 57069 8192000000 10296020784 10771574784 1375545696 14.377 99 6 12 0 1395991600 19081536 1364368
16:19:23 76480 8192000000 10302595328 10756911104 1367155504 14.302 98 33 66 1 1383112064 8666864 7289696
16:22:14 69839 8192000000 10292440144 10765213696 1375490496 14.376 99 11 22 0 1397439088 19234688 2713904
16:24:25 64512 8192000000 10279642576 10749108224 1359156656 14.230 99 12 24 0 1376268336 14530416 2581264
16:26:35 78054 8192000000 10271150272 10730778624 1336223776 14.023 97 10 20 0 1346499312 7952608 2322928
16:29:06 53010 8192000000 10256863008 10718998528 1350109456 14.148 99 11 22 0 1361266272 8797408 2359408
16:31:02 62104 8192000000 10261168912 10723139584 1352024720 14.166 98 8 16 1 1360586576 6901376 1660480
16:33:26 60478 8192000000 10268590976 10734096384 1340494032 14.062 97 16 32 1 1355880992 11435104 3951856
16:35:32 61905 8192000000 10267801936 10725072896 1310548128 13.791 95 23 46 0 1327247440 11590416 5108896
16:37:46 14702 8192000000 10287084208 10726277120 1352985104 14.174 99 51 102 2 1376675520 12600160 11090256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-05-2022 16:39:24 to 22-05-2022 16:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-05-2022 16:39:39 to 22-05-2022 16:39:41.

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          244734
MON$OLDEST_ACTIVE               247893
MON$OLDEST_SNAPSHOT             247893
MON$NEXT_TRANSACTION            247893
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                       2061152
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:190: 
:191: mscs-test-161.budzdorov.ru    Sun May 22 16:38:17 2022
:192:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:193: 
:194: 
:195: mscs-test-161.budzdorov.ru    Sun May 22 16:38:20 2022
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Sun May 22 16:38:23 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
:202: 
:203: mscs-test-161.budzdorov.ru    Sun May 22 16:38:23 2022
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:205: 
:206: 
:207: mscs-test-161.budzdorov.ru    Sun May 22 16:38:23 2022
:208:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:209: 
--- end of diff output ---

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

22.05.22 16:39:42. 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-05-22 16:39:45 No severe PSQL-related problems occured
22.05.22 16:39:46. End of report.