Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.04.2022 01: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 16636544 kB
MemAvailable 31527932 kB
Buffers 0 kB
Cached 15047628 kB
SwapCached 3200 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-04-27 01:38:13
DTS_END                         2022-04-27 01:38:13.2930
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/43906
TRN_ID                          236470
ATT_ID                          608
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: 9493.28 16768 1139194
customer order: creation 172.46 371 20695
customer order: refuse 14.27 345 1712
order to supplier: creation 46.83 1282 5619
order to supplier: removal 3.44 99446 413
invoice (draft): creation 47.17 3262 5660
invoice (draft): removal 53.53 767 6423
invoice accept: apply 42.95 17095 5154
invoice accept: cancel 47.96 822 5755
customer reserve: creation 4068.81 164 488257
customer reserve: removal 4682.83 58 561939
realization accept: apply 84.73 168 10168
realization accept: cancel 98.33 110 11799
payment from customer: creation 59.99 111 7199
payment from customer: removal 5.48 74 658
payment to supplier: creation 48.66 108 5839
payment to supplier: removal 3.63 99 435
service: total inventory turnovers 4.67 1134 560
service: total monetary turnovers 6.52 23 782
service: aggregate perf. data 1.03 15107 123
service: refresh index statistics 0.03 194810 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-04-2022 01:39:20 to 27-04-2022 01:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5315
TEST_TIME 2 5151
TEST_TIME 3 6225
TEST_TIME 4 5870
TEST_TIME 5 5805
TEST_TIME 6 6232
TEST_TIME 7 6048
TEST_TIME 8 6200
TEST_TIME 9 6775
TEST_TIME 10 6564
TEST_TIME 11 6398
TEST_TIME 12 6398
TEST_TIME 13 6626
TEST_TIME 14 6947
TEST_TIME 15 7111
TEST_TIME 16 7187
TEST_TIME 17 7506
TEST_TIME 18 8218
TEST_TIME 19 8569
TEST_TIME 20 8719
TEST_TIME 21 9277
TEST_TIME 22 9284
TEST_TIME 23 9791
TEST_TIME 24 9782
TEST_TIME 25 10109
TEST_TIME 26 10830
TEST_TIME 27 10960
TEST_TIME 28 10736
TEST_TIME 29 10706
TEST_TIME 30 10564
TEST_TIME 31 10817
TEST_TIME 32 10712
TEST_TIME 33 10404
TEST_TIME 34 10329
TEST_TIME 35 10317
TEST_TIME 36 10134
TEST_TIME 37 10069
TEST_TIME 38 10127
TEST_TIME 39 10039
TEST_TIME 40 10039
TEST_TIME 41 10124
TEST_TIME 42 10064
TEST_TIME 43 10127
TEST_TIME 44 10019
TEST_TIME 45 9920
TEST_TIME 46 10051
TEST_TIME 47 9983
TEST_TIME 48 10015
TEST_TIME 49 10238
TEST_TIME 50 10177
TEST_TIME 51 10190
TEST_TIME 52 10192
TEST_TIME 53 10213
TEST_TIME 54 10144
TEST_TIME 55 10148
TEST_TIME 56 10249
TEST_TIME 57 10208
TEST_TIME 58 10122
TEST_TIME 59 10081
TEST_TIME 60 10001
TEST_TIME 61 9917
TEST_TIME 62 9910
TEST_TIME 63 9850
TEST_TIME 64 9763
TEST_TIME 65 9735
TEST_TIME 66 9772
TEST_TIME 67 9719
TEST_TIME 68 9663
TEST_TIME 69 9684
TEST_TIME 70 9768
TEST_TIME 71 9779
TEST_TIME 72 9792
TEST_TIME 73 9750
TEST_TIME 74 9793
TEST_TIME 75 9905
TEST_TIME 76 9890
TEST_TIME 77 9920
TEST_TIME 78 9910
TEST_TIME 79 9952
TEST_TIME 80 9879
TEST_TIME 81 9815
TEST_TIME 82 9814
TEST_TIME 83 9760
TEST_TIME 84 9731
TEST_TIME 85 9679
TEST_TIME 86 9642
TEST_TIME 87 9605
TEST_TIME 88 9560
TEST_TIME 89 9578
TEST_TIME 90 9558
TEST_TIME 91 9561
TEST_TIME 92 9537
TEST_TIME 93 9506
TEST_TIME 94 9589
TEST_TIME 95 9620
TEST_TIME 96 9653
TEST_TIME 97 9665
TEST_TIME 98 9690
TEST_TIME 99 9662
TEST_TIME 100 9724
TEST_TIME 101 9785
TEST_TIME 102 9804
TEST_TIME 103 9802
TEST_TIME 104 9749
TEST_TIME 105 9698
TEST_TIME 106 9656
TEST_TIME 107 9657
TEST_TIME 108 9614
TEST_TIME 109 9629
TEST_TIME 110 9600
TEST_TIME 111 9553
TEST_TIME 112 9519
TEST_TIME 113 9563
TEST_TIME 114 9533
TEST_TIME 115 9516
TEST_TIME 116 9564
TEST_TIME 117 9548
TEST_TIME 118 9565
TEST_TIME 119 9561
TEST_TIME 120 9501

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-04-2022 01:39:24 to 27-04-2022 01:39:28.

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 1589361 1589361 0 0.00 0 37318 6 0 0
doc_list_biud 1589361 1589361 0 0.00 0 34305 26 0 0
sp_add_invoice_to_stock 5238 5154 84 1.60 32 153077 17096 0 84
sp_cancel_adding_invoice 5755 5755 0 0.00 5 112313 823 0 0
sp_cancel_client_order 1712 1712 0 0.00 4 11528 346 0 0
sp_cancel_customer_reserve 561939 561939 0 0.00 1 24163 58 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 6542 74 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 8910 99 0 0
sp_cancel_supplier_invoice 6428 6423 5 0.07 1 14991 767 0 5
sp_cancel_supplier_order 413 413 0 0.00 8 1133901 99447 0 0
sp_cancel_write_off 11799 11799 0 0.00 1 19636 111 0 0
sp_client_order 20695 20695 0 0.00 3 27771 372 0 0
sp_customer_reserve 488257 488257 0 0.00 0 71767 164 0 0
sp_fill_shopping_cart 31966 31966 0 0.00 1 13660 41 0 0
sp_fill_shopping_cart_clo_res 488256 488256 0 0.00 0 15273 14 0 0
sp_get_clo_for_invoice 5141 5141 0 0.00 0 25268 301 0 0
sp_kill_cost_storno 18647 18647 0 0.00 0 19509 55 0 0
sp_kill_qstorno_ret_qs2qd 1142513 1142513 0 0.00 0 24158 32 0 0
sp_kill_qty_storno 603345 603345 0 0.00 0 34304 68 0 0
sp_lock_selected_doc 21043 21043 0 0.00 0 7619 6 0 0
sp_make_cost_storno 28342 28342 0 0.00 0 13391 50 0 0
sp_multiply_rows_for_pdistr 28342 28342 0 0.00 0 13632 29 0 0
sp_multiply_rows_for_qdistr 20695 20695 0 0.00 0 9537 166 0 0
sp_pay_from_customer 7199 7199 0 0.00 4 26328 111 0 0
sp_pay_to_supplier 5839 5839 0 0.00 4 17817 109 0 0
sp_payment_common 13038 13038 0 0.00 1 26220 28 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 11519 319 0 0
sp_qd_handle_on_invoice_upd_sts 10891 10891 0 0.00 0 34302 312 0 0
sp_qd_handle_on_reserve_upd_sts 10168 10168 0 0.00 0 22881 58 0 0
sp_reserve_write_off 10168 10168 0 0.00 6 22903 169 0 0
sp_supplier_invoice 5660 5660 0 0.00 7 75592 3263 0 0
sp_supplier_order 5619 5619 0 0.00 5 49251 1283 0 0
srv_aggregate_perf_data 123 123 0 0.00 2578 173464 15108 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 965 157849 46295 0 0
srv_find_qd_qs_mism 1089292 1089292 0 0.00 0 37316 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 29 102623 1135 0 0
srv_make_money_saldo 782 782 0 0.00 1 1525 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 89834 318241 194811 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5154 5154 0 0.00 0 10002 153 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 9880 148 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 5496 16 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 469 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 2528 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 6386 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 356 7 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 43309 1861 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 8312 19 0 0
t$perf-norm:sp_client_order 20695 20695 0 0.00 0 12571 17 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 956 6 0 0
t$perf-norm:sp_pay_from_customer 7199 7199 0 0.00 0 5596 10 0 0
t$perf-norm:sp_pay_to_supplier 5839 5839 0 0.00 0 4013 8 0 0
t$perf-norm:sp_reserve_write_off 10168 10168 0 0.00 0 11081 13 0 0
t$perf-norm:sp_supplier_invoice 5660 5660 0 0.00 0 9606 25 0 0
t$perf-norm:sp_supplier_order 5619 5619 0 0.00 0 3571 12 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 4126 61 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 611 18 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 991 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10440 10272 168 1.60 0 7199 15 0 168
v_all_customers 37956 37956 0 0.00 0 98 1 0 0
v_all_suppliers 30756 30756 0 0.00 0 19 1 0 0
v_all_wares 41390 41390 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 302 6 0 0
v_cancel_client_order 3424 3424 0 0.00 0 2646 4 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 3389 7 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2651 5 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 3637 10 0 0
v_cancel_supplier_invoice 868 858 10 1.15 0 7292 23 0 10
v_cancel_supplier_order 826 826 0 0.00 0 291 3 0 0
v_cancel_write_off 2060 2060 0 0.00 0 1168 12 0 0
v_max_id_clo_ord 5619 5619 0 0.00 0 227 1 0 0
v_max_id_clo_res 987 987 0 0.00 0 1911 12 0 0
v_max_id_ord_sup 5652 5652 0 0.00 0 6423 7 0 0
v_max_non_paid_invoice 1732 1732 0 0.00 0 560 2 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 4612 5 0 0
v_min_id_clo_ord 5619 5619 0 0.00 0 3261 6 0 0
v_min_id_clo_res 987 987 0 0.00 0 7251 15 0 0
v_min_id_ord_sup 5652 5652 0 0.00 0 6259 11 0 0
v_min_non_paid_invoice 5839 5839 0 0.00 0 17461 18 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 26173 24 0 0
v_our_firm 10302 10302 0 0.00 0 99 1 0 0
v_reserve_write_off 20336 20336 0 0.00 0 9424 6 0 0
x_lock_depdocs_on_canc_invoice 11749 11749 0 0.00 0 15935 103 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 9220 320 0 0
x_make_qstorno_1000_1200 5619 5619 0 0.00 2 49236 1203 0 0
x_make_qstorno_1200_2000 5660 5660 0 0.00 3 75581 3111 0 0
x_make_qstorno_2100_3300 488257 488257 0 0.00 0 71766 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-04-2022 01:39:29 to 27-04-2022 01:39:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:17 4 8192000000 8801538720 8813580288 7562352 0.092 2 2 3 0 7703440 66144 74944
23:09:18 22732 8192000000 9904384256 10215067648 984354352 10.727 100 88 175 2 1013794400 6272656 23167392
23:10:44 19302 8192000000 9954380816 10276052992 1035632672 11.223 100 86 172 0 1065697664 8057504 22007488
23:12:06 24994 8192000000 9989598176 10323533824 1069650448 11.549 100 84 168 0 1100715648 10084816 20980384
23:13:40 11848 8192000000 10036328960 10381426688 1117012976 11.999 100 82 164 1 1147221696 10251088 19957632
23:14:53 10759 8192000000 10078905200 10441150464 1171619792 12.512 100 74 148 0 1200013088 11652288 16741008
23:16:07 6670 8192000000 10131801696 10503073792 1216521744 12.929 100 76 152 1 1246826656 13008864 17296048
23:17:19 3947 8192000000 10166324832 10545127424 1252513392 13.261 100 84 168 0 1284843168 13637952 18691824
23:18:25 2167 8192000000 10206652736 10589171712 1287918032 13.585 100 88 176 0 1320132016 12947424 19266560
23:19:29 23918 8192000000 10205964560 10601758720 1303702144 13.729 100 69 136 1 1331129440 12184576 15242720
23:20:55 1725 8192000000 10212799088 10610667520 1307722272 13.765 100 67 132 0 1333286768 10877360 14687136
23:22:00 3194 8192000000 10217394288 10624856064 1321935216 13.894 100 42 82 1 1339701792 8928832 8837744
23:23:06 9560 8192000000 10208657248 10627551232 1330184176 13.969 100 25 51 1 1341754592 5921424 5648992
23:24:18 18125 8192000000 10260820224 10656665600 1334792576 14.010 100 81 164 3 1364868912 10441472 19634864
23:25:37 6297 8192000000 10237499312 10646212608 1336683216 14.027 100 35 71 2 1352143152 6111376 9348560
23:26:43 6557 8192000000 10232464736 10654576640 1340761440 14.064 100 37 73 3 1355844096 6199552 8883104
23:27:53 15273 8192000000 10221020448 10639175680 1320290624 13.879 99 18 37 0 1329612240 4937568 4384048
23:29:50 4868 8192000000 10205539536 10616500224 1299430048 13.690 99 33 66 0 1313639360 7032496 7176816
23:30:56 7172 8192000000 10166571376 10573672448 1261359616 13.342 98 18 36 2 1271063520 5835008 3868896
23:32:04 9486 8192000000 10149607408 10550706176 1237757920 13.126 98 34 68 2 1251018640 5364816 7895904
23:33:16 3891 8192000000 10158096144 10560053248 1266664816 13.391 100 64 127 2 1288614192 6769264 15180112
23:34:22 5342 8192000000 10159102432 10564726784 1270394656 13.425 100 22 44 0 1280965920 5962288 4608976
23:35:28 5141 8192000000 10190822096 10593710080 1294587648 13.646 100 30 59 0 1307772176 5783472 7401056
23:36:34 8622 8192000000 10200314304 10611552256 1308510640 13.773 100 18 36 1 1317694320 5634080 3549600
23:37:45 82443 8192000000 10229969120 10641379328 1325462208 13.926 100 43 84 1 1342316192 7129888 9724096
23:40:14 8267 8192000000 10253027056 10668134400 1348709008 14.136 100 36 72 3 1366291824 9105696 8477120
23:41:27 8185 8192000000 10277833424 10695798784 1364623088 14.279 100 42 82 3 1390309216 15747712 9938416
23:42:58 5402 8192000000 10297265392 10708516864 1374469376 14.367 100 68 131 2 1403153296 14301424 14382496
23:44:12 14025 8192000000 10308264976 10730516480 1390530496 14.511 100 62 121 4 1424004800 19020544 14453760
23:45:36 10160 8192000000 10318110272 10739109888 1401996688 14.613 100 53 105 1 1432348256 18483904 11867664
23:46:47 2023 8192000000 10343738272 10756542464 1413321840 14.713 100 71 140 2 1452853200 21991360 17540000
23:47:54 14786 8192000000 10343173104 10768171008 1418691504 14.761 100 50 100 3 1452338864 21684768 11962592
23:49:13 12946 8192000000 10356379680 10785841152 1433422880 14.892 100 55 109 1 1470515424 22794528 14298016
23:50:30 6195 8192000000 10374286544 10795401216 1435547216 14.910 100 81 162 1 1486014720 30493296 19974208
23:51:44 5306 8192000000 10401856576 10817757184 1447485632 15.016 100 76 150 1 1503016000 36663520 18866848
23:52:54 963 8192000000 10390141536 10801590272 1430070656 14.862 100 90 179 0 1489609040 37125488 22412896
23:54:00 5018 8192000000 10360346736 10772398080 1409251424 14.677 100 80 160 2 1468483072 39195360 20036288
23:55:11 3339 8192000000 10384173552 10798301184 1425137760 14.818 100 84 167 0 1491755856 44933616 21684480
23:56:21 2427 8192000000 10372632544 10786279424 1400322896 14.598 99 74 148 0 1470540688 50161424 20056368
23:57:27 7741 8192000000 10342871808 10766307328 1393005696 14.533 100 50 100 2 1451127552 44466336 13655520
23:58:35 11529 8192000000 10310686672 10740113408 1357649072 14.216 99 45 90 3 1411500640 40704544 13147024
00:00:01 7895 8192000000 10304767088 10733428736 1352276960 14.168 98 35 68 0 1392158256 30972656 8908640
00:01:16 15738 8192000000 10301271728 10734608384 1365493696 14.287 100 25 50 0 1398160704 26078192 6588816
00:02:36 45539 8192000000 10275228304 10709360640 1320841520 13.884 98 19 38 1 1356212064 29935472 5435072
00:04:23 38239 8192000000 10265255008 10695282688 1336595120 14.027 98 16 32 2 1352497376 11986048 3916208
00:06:04 99244 8192000000 10278693344 10724048896 1334457008 14.007 98 21 42 3 1347953584 8970976 4525600
00:08:43 41618 8192000000 10291274896 10735927296 1357306432 14.213 99 14 27 1 1375878288 15063408 3508448
00:10:30 120380 8192000000 10280730672 10721456128 1348314816 14.132 98 27 54 1 1367476128 13227872 5933440
00:13:31 21495 8192000000 10286051600 10738925568 1368604112 14.315 99 25 50 0 1385908128 12084736 5219280
00:15:09 38973 8192000000 10313634240 10749394944 1376510080 14.385 99 55 111 1 1402662208 12404224 13747904
00:17:34 25711 8192000000 10326082608 10763927552 1395121808 14.552 100 57 115 4 1424007696 14693680 14192208
00:19:09 77424 8192000000 10315353232 10772885504 1384905584 14.460 99 15 30 1 1404649472 15999184 3744704
00:21:31 42744 8192000000 10301137760 10761052160 1397468384 14.572 100 15 30 0 1414408832 12983472 3956976
00:23:17 52527 8192000000 10312335824 10775359488 1398032096 14.577 100 14 28 0 1414849056 12700144 4116816
00:25:10 47336 8192000000 10331598848 10778632192 1394900720 14.550 99 41 80 0 1418519168 13695696 9922752
00:27:13 44891 8192000000 10311185712 10788298752 1402809184 14.620 100 9 18 0 1417785248 12575520 2400544
00:29:00 34680 8192000000 10331257168 10779754496 1406211936 14.650 100 45 90 2 1425585616 8745760 10627920
00:30:39 53504 8192000000 10312477904 10774638592 1400736000 14.602 100 19 40 0 1416002752 11162320 4104432
00:32:33 18746 8192000000 10346302352 10794602496 1414690400 14.726 100 38 74 1 1435861472 12838688 8332384
00:33:53 130237 8192000000 10305138144 10756251648 1323287936 13.906 93 19 39 0 1346462944 18521728 4653280
00:37:11 68153 8192000000 10291635952 10753699840 1353883808 14.182 97 25 50 1 1369211664 9333520 5994336
00:39:22 5503 8192000000 10248345600 10681610240 1308354016 13.771 95 39 79 0 1324730352 7363760 9012576
00:40:28 70808 8192000000 10248266336 10712121344 1327975984 13.949 97 14 29 0 1343958960 13320880 2662096
00:42:49 31108 8192000000 10239241792 10697314304 1337561680 14.035 99 10 20 0 1352267920 12457856 2248384
00:44:21 41652 8192000000 10250022752 10704023552 1320340240 13.880 98 11 21 1 1329940288 7105024 2495024
00:46:05 45386 8192000000 10276442000 10720595968 1325906080 13.930 97 19 38 1 1343579216 13029264 4643872
00:48:11 76513 8192000000 10268761920 10723647488 1327889984 13.948 97 17 34 0 1344953552 13136464 3927104
00:50:33 56080 8192000000 10289369744 10731397120 1333212256 13.996 97 18 36 0 1349866960 12721264 3933440
00:52:58 45709 8192000000 10245973328 10696826880 1291906496 13.622 95 30 60 1 1322125104 23225760 6992848
00:54:44 73803 8192000000 10268015040 10723229696 1324805104 13.920 98 12 23 1 1341931584 14480896 2645584
00:57:01 123170 8192000000 10242667360 10694639616 1293495776 13.636 95 7 14 1 1309205392 14385920 1323696
01:00:31 41044 8192000000 10250036256 10708066304 1342309552 14.078 99 8 16 2 1357176576 13075744 1791280
01:02:12 69967 8192000000 10252335856 10709250048 1325075376 13.923 97 15 30 2 1335924224 7744096 3104752
01:04:23 60158 8192000000 10266790992 10732257280 1371637824 14.342 100 13 26 0 1382548592 8139216 2771552
01:06:26 63713 8192000000 10275678784 10733707264 1370430400 14.331 100 17 34 0 1387098480 12615968 4052112
01:08:39 79052 8192000000 10286044896 10737823744 1382798880 14.442 100 24 48 0 1402073472 13697184 5577408
01:10:59 55419 8192000000 10320979072 10767896576 1384013472 14.452 99 23 46 0 1399983488 10873776 5096240
01:13:06 55440 8192000000 10307774800 10765930496 1381240800 14.428 99 25 49 2 1401046624 14002080 5803744
01:15:06 57023 8192000000 10300830752 10761162752 1374221696 14.365 98 20 40 1 1395739152 16917136 4600320
01:17:06 59655 8192000000 10314194992 10774016000 1392565376 14.529 99 11 22 0 1406713440 11285136 2862928
01:19:12 157848 8192000000 10307049328 10765402112 1366166048 14.293 97 9 18 1 1394166176 25614112 2386016
01:22:54 93506 8192000000 10303663584 10760855552 1388019648 14.488 99 23 44 1 1401651440 8565552 5066240
01:25:32 62463 8192000000 10311004544 10785067008 1393758752 14.539 99 18 36 1 1412589344 14507472 4323120
01:27:35 101114 8192000000 10315241792 10792853504 1397074208 14.569 99 8 16 0 1413442592 14602240 1766144
01:30:47 64251 8192000000 10287733504 10757906432 1378977664 14.407 99 15 31 0 1390855072 8509824 3367584
01:32:51 57824 8192000000 10283009072 10738323456 1325236192 13.924 95 22 44 1 1341883232 11829184 4817856
01:34:57 22759 8192000000 10275257968 10721554432 1330602400 13.973 97 26 52 0 1344491840 8097808 5791632
01:36:26 66000 8192000000 10269697296 10726637568 1318485888 13.863 96 14 28 0 1337010864 15451808 3073168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-04-2022 01:39:34 to 27-04-2022 01:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-04-2022 01:39:50 to 27-04-2022 01:39:52.

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          234700
MON$OLDEST_ACTIVE               236615
MON$OLDEST_SNAPSHOT             236615
MON$NEXT_TRANSACTION            236615
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                       2027744
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.33578 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33578 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33578 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Apr 27 01:38:22 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Apr 27 01:38:22 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Apr 27 01:38:22 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

27.04.22 01:39:53. 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-04-27 01:39:56 No severe PSQL-related problems occured
27.04.22 01:39:57. End of report.