Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.04.2022 16:43
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 16424000 kB
MemAvailable 31572364 kB
Buffers 0 kB
Cached 15314680 kB
SwapCached 3224 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-29 16:42:24
DTS_END                         2022-04-29 16:42:24.0730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54950
TRN_ID                          232150
ATT_ID                          603
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: 9088.63 29190 1090636
customer order: creation 165.45 382 19854
customer order: refuse 14.48 399 1737
order to supplier: creation 43.83 1385 5260
order to supplier: removal 3.43 112076 411
invoice (draft): creation 43.93 3871 5271
invoice (draft): removal 53.53 834 6423
invoice accept: apply 39.72 18755 4766
invoice accept: cancel 48.27 946 5792
customer reserve: creation 3726.23 183 447147
customer reserve: removal 4664.54 64 559745
realization accept: apply 79.40 199 9528
realization accept: cancel 81.42 117 9770
payment from customer: creation 56.45 124 6774
payment from customer: removal 5.63 109 675
payment to supplier: creation 47.12 156 5654
payment to supplier: removal 3.33 84 399
service: total inventory turnovers 4.53 1196 544
service: total monetary turnovers 6.59 29 791
service: aggregate perf. data 0.76 22528 91
service: refresh index statistics 0.03 420364 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-04-2022 16:44:11 to 29-04-2022 16:44:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2185
TEST_TIME 2 2932
TEST_TIME 3 3646
TEST_TIME 4 3950
TEST_TIME 5 3626
TEST_TIME 6 3645
TEST_TIME 7 3855
TEST_TIME 8 4330
TEST_TIME 9 4812
TEST_TIME 10 4694
TEST_TIME 11 4940
TEST_TIME 12 5004
TEST_TIME 13 5344
TEST_TIME 14 5377
TEST_TIME 15 5480
TEST_TIME 16 6109
TEST_TIME 17 6691
TEST_TIME 18 6814
TEST_TIME 19 6881
TEST_TIME 20 7363
TEST_TIME 21 7145
TEST_TIME 22 7431
TEST_TIME 23 7977
TEST_TIME 24 8088
TEST_TIME 25 8274
TEST_TIME 26 8230
TEST_TIME 27 8392
TEST_TIME 28 8476
TEST_TIME 29 8648
TEST_TIME 30 8968
TEST_TIME 31 10466
TEST_TIME 32 10280
TEST_TIME 33 10104
TEST_TIME 34 10118
TEST_TIME 35 10067
TEST_TIME 36 9970
TEST_TIME 37 9863
TEST_TIME 38 9844
TEST_TIME 39 9763
TEST_TIME 40 9681
TEST_TIME 41 9632
TEST_TIME 42 9723
TEST_TIME 43 9682
TEST_TIME 44 9901
TEST_TIME 45 9856
TEST_TIME 46 9750
TEST_TIME 47 9734
TEST_TIME 48 9737
TEST_TIME 49 9694
TEST_TIME 50 9849
TEST_TIME 51 9829
TEST_TIME 52 9671
TEST_TIME 53 9554
TEST_TIME 54 9543
TEST_TIME 55 9474
TEST_TIME 56 9362
TEST_TIME 57 9393
TEST_TIME 58 9364
TEST_TIME 59 9272
TEST_TIME 60 9305
TEST_TIME 61 9378
TEST_TIME 62 9293
TEST_TIME 63 9260
TEST_TIME 64 9210
TEST_TIME 65 9111
TEST_TIME 66 9148
TEST_TIME 67 9218
TEST_TIME 68 9152
TEST_TIME 69 9142
TEST_TIME 70 9323
TEST_TIME 71 9422
TEST_TIME 72 9340
TEST_TIME 73 9317
TEST_TIME 74 9392
TEST_TIME 75 9394
TEST_TIME 76 9383
TEST_TIME 77 9411
TEST_TIME 78 9387
TEST_TIME 79 9450
TEST_TIME 80 9498
TEST_TIME 81 9435
TEST_TIME 82 9352
TEST_TIME 83 9322
TEST_TIME 84 9316
TEST_TIME 85 9277
TEST_TIME 86 9253
TEST_TIME 87 9253
TEST_TIME 88 9220
TEST_TIME 89 9169
TEST_TIME 90 9111
TEST_TIME 91 9048
TEST_TIME 92 9109
TEST_TIME 93 9157
TEST_TIME 94 9217
TEST_TIME 95 9219
TEST_TIME 96 9214
TEST_TIME 97 9206
TEST_TIME 98 9267
TEST_TIME 99 9273
TEST_TIME 100 9252
TEST_TIME 101 9247
TEST_TIME 102 9358
TEST_TIME 103 9361
TEST_TIME 104 9389
TEST_TIME 105 9378
TEST_TIME 106 9418
TEST_TIME 107 9390
TEST_TIME 108 9366
TEST_TIME 109 9342
TEST_TIME 110 9304
TEST_TIME 111 9276
TEST_TIME 112 9302
TEST_TIME 113 9272
TEST_TIME 114 9264
TEST_TIME 115 9230
TEST_TIME 116 9227
TEST_TIME 117 9196
TEST_TIME 118 9148
TEST_TIME 119 9158
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-04-2022 16:44:15 to 29-04-2022 16:44:18.

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 1505098 1505098 0 0.00 0 14487 6 0 0
doc_list_biud 1505098 1505098 0 0.00 0 18646 30 0 0
sp_add_invoice_to_stock 4910 4766 144 2.93 42 210232 18756 0 144
sp_cancel_adding_invoice 5792 5792 0 0.00 3 97348 947 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 15759 399 0 0
sp_cancel_customer_reserve 559745 559745 0 0.00 1 18046 65 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 9514 109 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 2 4511 85 0 0
sp_cancel_supplier_invoice 6432 6423 9 0.13 1 15881 834 0 9
sp_cancel_supplier_order 411 411 0 0.00 9 1169652 112077 0 0
sp_cancel_write_off 9770 9770 0 0.00 1 11889 118 0 0
sp_client_order 19854 19854 0 0.00 3 15744 382 0 0
sp_customer_reserve 447147 447147 0 0.00 0 123254 183 0 0
sp_fill_shopping_cart 30374 30374 0 0.00 1 18086 41 0 0
sp_fill_shopping_cart_clo_res 447146 447146 0 0.00 0 14019 13 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 29183 298 0 0
sp_kill_cost_storno 16636 16636 0 0.00 0 14464 68 0 0
sp_kill_qstorno_ret_qs2qd 1136094 1136094 0 0.00 0 18041 35 0 0
sp_kill_qty_storno 598159 598159 0 0.00 0 18644 76 0 0
sp_lock_selected_doc 19850 19850 0 0.00 0 7928 6 0 0
sp_make_cost_storno 26709 26709 0 0.00 0 11156 58 0 0
sp_multiply_rows_for_pdistr 26709 26709 0 0.00 0 7010 30 0 0
sp_multiply_rows_for_qdistr 19854 19854 0 0.00 0 11364 156 0 0
sp_pay_from_customer 6774 6774 0 0.00 4 16652 124 0 0
sp_pay_to_supplier 5654 5654 0 0.00 4 21688 156 0 0
sp_payment_common 12428 12428 0 0.00 1 21673 37 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 15741 363 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 18636 379 0 0
sp_qd_handle_on_reserve_upd_sts 9528 9528 0 0.00 0 15658 65 0 0
sp_reserve_write_off 9528 9528 0 0.00 7 16524 199 0 0
sp_supplier_invoice 5271 5271 0 0.00 6 106720 3872 0 0
sp_supplier_order 5260 5260 0 0.00 5 33652 1385 0 0
srv_aggregate_perf_data 91 91 0 0.00 2743 187914 22528 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2159 118805 44984 0 0
srv_find_qd_qs_mism 1044883 1044883 0 0.00 0 10967 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 40 49648 1197 0 0
srv_make_money_saldo 791 791 0 0.00 1 1425 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 240555 506795 420364 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 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 4766 4766 0 0.00 0 11384 168 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 5516 141 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 1624 11 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 3257 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1844 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1362 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 2305 20 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 33357 2028 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 1680 7 0 0
t$perf-norm:sp_client_order 19854 19854 0 0.00 0 28253 21 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 1286 6 0 0
t$perf-norm:sp_pay_from_customer 6774 6774 0 0.00 0 32185 18 0 0
t$perf-norm:sp_pay_to_supplier 5654 5654 0 0.00 0 28252 17 0 0
t$perf-norm:sp_reserve_write_off 9528 9528 0 0.00 0 32243 15 0 0
t$perf-norm:sp_supplier_invoice 5271 5271 0 0.00 0 10403 20 0 0
t$perf-norm:sp_supplier_order 5260 5260 0 0.00 0 32176 28 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 201 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 268 5 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 735 20 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 1262 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 81 22 0 0
v_add_invoice_to_stock 9794 9506 288 2.94 0 6415 21 0 288
v_all_customers 36188 36188 0 0.00 0 19 1 0 0
v_all_suppliers 29046 29046 0 0.00 0 38 1 0 0
v_all_wares 39708 39708 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1627 8 0 0
v_cancel_client_order 3474 3474 0 0.00 0 3640 9 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 3617 11 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 1096 4 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 4505 10 0 0
v_cancel_supplier_invoice 768 750 18 2.34 0 2845 25 0 18
v_cancel_supplier_order 822 822 0 0.00 0 4570 16 0 0
v_cancel_write_off 1912 1912 0 0.00 0 4468 17 0 0
v_max_id_clo_ord 5260 5260 0 0.00 0 373 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 1961 12 0 0
v_max_id_ord_sup 5260 5260 0 0.00 0 3863 4 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 8192 13 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 1733 4 0 0
v_min_id_clo_ord 5260 5260 0 0.00 0 4171 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 1101 3 0 0
v_min_id_ord_sup 5259 5259 0 0.00 0 7610 7 0 0
v_min_non_paid_invoice 5654 5654 0 0.00 0 21670 32 0 0
v_min_non_paid_realizn 6774 6774 0 0.00 0 16646 25 0 0
v_our_firm 9774 9774 0 0.00 0 19 1 0 0
v_reserve_write_off 19056 19056 0 0.00 0 5414 8 0 0
x_lock_depdocs_on_canc_invoice 11840 11840 0 0.00 0 16892 128 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 7435 392 0 0
x_make_qstorno_1000_1200 5260 5260 0 0.00 1 33346 1302 0 0
x_make_qstorno_1200_2000 5271 5271 0 0.00 3 106620 3719 0 0
x_make_qstorno_2100_3300 447147 447147 0 0.00 0 123253 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-04-2022 16:44:19 to 29-04-2022 16:44:23.

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:12:27 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:28 33084 8192000000 9901832464 10212896768 984252496 10.726 100 86 172 1 1012849744 5867984 22729264
14:15:03 30329 8192000000 9950469520 10273435648 1029575056 11.164 100 88 175 0 1059723616 8027280 22121280
14:16:40 17775 8192000000 9980736816 10313179136 1059703392 11.454 100 78 156 0 1088632640 9331008 19598240
14:18:00 11843 8192000000 10030935072 10377887744 1120026096 12.027 100 73 147 0 1147811312 10360096 17425120
14:19:18 9931 8192000000 10081254320 10441715712 1165761664 12.457 100 73 146 0 1194457840 11631616 17064560
14:20:31 7080 8192000000 10122370816 10493415424 1211521840 12.883 100 74 148 2 1239218320 11346880 16349600
14:21:40 7010 8192000000 10156658736 10528972800 1241904000 13.164 100 88 174 1 1277508848 16185232 19419616
14:22:52 3850 8192000000 10182906416 10566488064 1274108896 13.459 100 85 170 3 1304097072 11741344 18246832
14:23:57 2149 8192000000 10205097440 10587852800 1290796864 13.611 100 89 178 0 1323860672 13468736 19595072
14:25:02 2141 8192000000 10223483568 10608340992 1301417184 13.708 100 94 189 1 1335382384 12491232 21473968
14:26:04 1171 8192000000 10205741744 10606542848 1308117104 13.769 100 72 144 1 1333846256 9552144 16177008
14:27:07 14238 8192000000 10191190048 10607816704 1309588720 13.782 100 16 32 0 1319025280 6017264 3419296
14:28:29 64821 8192000000 10202877648 10624516096 1314543616 13.827 100 22 44 1 1328990672 9541600 4905456
14:30:34 1598 8192000000 10225227472 10631057408 1317850832 13.857 100 47 94 2 1334644880 5985072 10808976
14:31:36 9482 8192000000 10216517792 10635808768 1324430288 13.917 100 12 24 0 1332571888 5390656 2750944
14:32:48 14589 8192000000 10208789376 10623467520 1316010704 13.841 100 26 50 0 1327540848 5647776 5882368
14:34:06 18091 8192000000 10211096720 10601795584 1290724352 13.611 100 72 142 5 1313567760 5362352 17481056
14:35:25 9031 8192000000 10151308064 10554839040 1240711888 13.153 98 25 50 1 1252411936 6129008 5571040
14:36:34 6520 8192000000 10128419312 10529910784 1221944416 12.980 98 12 24 0 1230193776 5669008 2580352
14:37:41 67247 8192000000 10136847408 10537013248 1184587632 12.633 94 20 38 1 1193721936 4373248 4761056
14:39:53 11240 8192000000 10168770144 10578935808 1276916560 13.485 100 23 46 1 1286960400 4700208 5343632
14:41:05 11586 8192000000 10176199008 10589634560 1291383392 13.617 100 17 33 2 1300729648 4972560 4373696
14:42:17 92582 8192000000 10232978608 10635624448 1318656448 13.865 100 42 84 0 1340857744 11756832 10444464
14:45:07 15235 8192000000 10251768288 10662694912 1342377696 14.079 100 50 100 1 1364934064 11127104 11429264
14:46:25 42362 8192000000 10268942400 10693005312 1366103808 14.292 100 32 64 2 1385133808 11178416 7851584
14:48:10 38390 8192000000 10289430544 10712375296 1378039216 14.399 100 40 80 1 1402354560 15032336 9283008
14:49:57 25720 8192000000 10299711728 10727305216 1386127376 14.471 100 34 68 5 1409991968 15704160 8160432
14:51:33 4316 8192000000 10328368144 10746626048 1402761152 14.620 100 68 135 2 1440554176 21970304 15822720
14:52:43 14531 8192000000 10323122672 10746249216 1392800560 14.531 99 55 110 1 1428545376 21844672 13900144
14:54:04 5400 8192000000 10340146144 10758668288 1398893008 14.585 99 69 139 3 1442190912 26407232 16890672
14:55:16 7478 8192000000 10341759568 10763026432 1398045856 14.578 99 61 122 0 1441275200 27345808 15883536
14:56:31 14168 8192000000 10350022080 10776674304 1413780288 14.718 100 59 118 1 1459557632 30550784 15226560
14:57:49 4548 8192000000 10364921360 10787979264 1419575600 14.769 100 62 125 1 1472694256 37072352 16046304
14:59:00 3405 8192000000 10339290224 10755260416 1380890320 14.425 98 73 140 0 1430542208 33190864 16461024
15:00:10 2158 8192000000 10354195952 10769305600 1403112096 14.623 100 85 170 1 1456365344 32181520 21071728
15:01:20 4746 8192000000 10336765952 10748649472 1376523424 14.385 99 81 159 1 1432783504 36530976 19729104
15:02:31 6039 8192000000 10337666688 10752266240 1378067792 14.399 100 62 124 1 1439853344 46644352 15141200
15:03:50 5025 8192000000 10351841264 10767773696 1390407120 14.509 100 69 137 1 1453514176 44664480 18442576
15:04:58 11076 8192000000 10343798592 10775220224 1389147184 14.498 100 39 78 3 1451034992 51138672 10749136
15:06:19 16348 8192000000 10349929520 10776334336 1392041760 14.524 99 40 78 0 1449677296 47460656 10174880
15:07:39 26640 8192000000 10344089584 10781290496 1376742464 14.387 98 29 58 2 1430309376 45619616 7947296
15:09:10 6231 8192000000 10347933616 10772201472 1381546016 14.430 100 64 128 3 1453127136 54003728 17577392
15:10:23 13780 8192000000 10319627248 10754764800 1360971152 14.246 100 26 53 0 1428942016 60838256 7132608
15:11:53 23733 8192000000 10316756640 10753490944 1356731600 14.208 100 25 50 0 1429019824 64918688 7369536
15:13:46 45393 8192000000 10280449536 10717708288 1352139728 14.167 99 21 42 0 1374247776 17197920 4910128
15:15:46 59396 8192000000 10279276288 10727337984 1362148608 14.257 99 27 54 0 1381847136 13487264 6211264
15:17:45 32631 8192000000 10281875632 10737000448 1370066352 14.328 100 10 21 0 1381731344 9459440 2205552
15:19:18 53838 8192000000 10285666320 10727419904 1350654432 14.153 98 13 26 0 1366217872 12955344 2608096
15:21:17 57277 8192000000 10300382448 10744770560 1380423184 14.420 100 28 56 1 1404632064 17200768 7008112
15:23:17 65453 8192000000 10293529664 10748686336 1333955488 14.003 96 22 44 2 1359200688 19916272 5328928
15:25:33 85249 8192000000 10293229920 10758889472 1366839168 14.299 99 13 26 1 1392250416 22264896 3146352
15:28:03 88027 8192000000 10305582944 10753056768 1381145872 14.427 99 15 29 1 1392740384 8807488 2787024
15:31:49 52531 8192000000 10314556176 10769678336 1393873632 14.540 99 15 30 1 1414373408 16816192 3683584
15:33:42 118653 8192000000 10313228624 10754359296 1377164080 14.391 98 25 48 0 1396098032 13207696 5726256
15:36:51 42761 8192000000 10308373936 10756329472 1388175440 14.490 99 45 87 1 1407679488 9728480 9775568
15:38:38 32518 8192000000 10314844528 10758316032 1392264464 14.526 99 58 114 1 1415653216 10446480 12942272
15:40:11 49018 8192000000 10313572736 10768560128 1386478560 14.474 98 33 66 1 1403218720 8743584 7996576
15:42:07 42632 8192000000 10325341728 10788069376 1414183120 14.721 100 37 73 1 1437486528 15260304 8043104
15:45:06 42344 8192000000 10288585520 10754056192 1375034944 14.372 98 11 21 1 1383625104 6256816 2333344
15:46:49 70398 8192000000 10300909632 10766176256 1398470496 14.581 100 16 32 0 1413138880 11200096 3468288
15:49:06 51606 8192000000 10288956528 10755198976 1362095936 14.256 98 7 14 0 1373045920 9720832 1229152
15:51:25 29771 8192000000 10305144704 10755932160 1377681968 14.396 99 24 46 0 1400334688 17108240 5544480
15:52:56 54559 8192000000 10272769424 10726973440 1343458656 14.089 97 10 21 0 1360268656 14263840 2546160
15:55:11 52977 8192000000 10275301200 10721849344 1345306240 14.105 98 29 58 1 1363780416 11834640 6639536
15:57:14 118805 8192000000 10274079264 10728288256 1297951888 13.677 94 11 22 1 1314056224 13415264 2689072
16:00:19 21745 8192000000 10269347600 10709266432 1317613808 13.855 96 35 68 1 1335997232 10888672 7494752
16:01:43 52514 8192000000 10281951696 10726858752 1348545264 14.134 99 23 46 1 1363593776 9777744 5270768
16:04:09 73841 8192000000 10254904608 10702057472 1322091264 13.896 97 25 50 0 1334740224 6667840 5981120
16:06:23 49200 8192000000 10260317840 10707668992 1326726624 13.938 97 29 58 0 1342333376 9426352 6180400
16:08:16 53436 8192000000 10239577152 10691469312 1308604848 13.773 96 26 51 0 1325195520 10667520 5923152
16:11:18 65103 8192000000 10275386800 10727284736 1351628208 14.162 99 15 30 0 1378887488 23762736 3496544
16:13:24 49708 8192000000 10253451504 10701918208 1330485984 13.972 98 14 28 1 1356885360 23015312 3384064
16:15:38 58815 8192000000 10258592384 10724585472 1329084688 13.959 97 20 40 0 1349438144 15503712 4849744
16:17:40 96521 8192000000 10291223120 10742870016 1358591904 14.225 99 11 22 0 1368401504 7690560 2119040
16:20:16 51976 8192000000 10306601040 10750603264 1383498480 14.448 100 22 42 0 1399042688 10962640 4581568
16:22:09 29814 8192000000 10310079056 10755649536 1394285024 14.544 100 24 48 0 1408222832 9016352 4921456
16:23:39 49594 8192000000 10304792560 10779922432 1399083616 14.587 100 16 32 2 1416926272 14155760 3686896
16:25:44 100633 8192000000 10305991776 10765144064 1361833792 14.254 97 13 26 0 1375466576 10856656 2776128
16:28:32 76831 8192000000 10299034832 10751311872 1328502080 13.954 95 26 52 1 1347828656 13211344 6115232
16:30:49 82647 8192000000 10299417632 10758037504 1357276528 14.213 98 17 34 1 1369214880 8083952 3854400
16:33:12 32013 8192000000 10308989600 10767147008 1395470832 14.555 100 19 38 2 1413837072 13903648 4462592
16:35:13 52829 8192000000 10275496304 10730340352 1324319472 13.916 95 15 30 0 1333900832 6747328 2834032
16:37:06 43151 8192000000 10271653056 10722504704 1331742368 13.983 97 14 28 0 1351263024 16612736 2907920
16:38:49 74416 8192000000 10275981328 10737373184 1373515472 14.359 100 15 30 1 1389617552 12723616 3378464
16:41:04 89340 8192000000 9748255408 10132918272 853952672 9.440 63 8 16 0 864085408 8923104 1209632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-04-2022 16:44:25 to 29-04-2022 16:44:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 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 29-04-2022 16:44:41 to 29-04-2022 16:44:43.

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          231785
MON$OLDEST_ACTIVE               232449
MON$OLDEST_SNAPSHOT             232449
MON$NEXT_TRANSACTION            232449
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                       2056288
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.33580 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33580 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33580 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33580 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Apr 29 16:42:38 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

29.04.22 16:44:44. 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-29 16:44:46 No severe PSQL-related problems occured
29.04.22 16:44:48. End of report.