Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.04.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 16661912 kB
MemAvailable 31555112 kB
Buffers 0 kB
Cached 15031564 kB
SwapCached 2612 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-25 16:38:15
DTS_END                         2022-04-25 16:38:15.3050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50926
TRN_ID                          240087
ATT_ID                          655
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: 9160.74 20723 1099289
customer order: creation 173.04 368 20765
customer order: refuse 15.41 363 1849
order to supplier: creation 45.54 1281 5465
order to supplier: removal 3.37 105978 404
invoice (draft): creation 45.58 3504 5470
invoice (draft): removal 53.84 811 6461
invoice accept: apply 41.62 17999 4994
invoice accept: cancel 48.36 773 5803
customer reserve: creation 3748.11 181 449773
customer reserve: removal 4690.93 59 562912
realization accept: apply 82.91 176 9949
realization accept: cancel 82.18 107 9862
payment from customer: creation 60.23 101 7228
payment from customer: removal 5.54 105 665
payment to supplier: creation 48.30 120 5796
payment to supplier: removal 3.52 69 422
service: total inventory turnovers 4.77 1337 572
service: total monetary turnovers 6.63 23 795
service: aggregate perf. data 0.83 17737 100
service: refresh index statistics 0.03 263366 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-04-2022 16:39:06 to 25-04-2022 16:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4658
TEST_TIME 2 4182
TEST_TIME 3 4937
TEST_TIME 4 4861
TEST_TIME 5 4997
TEST_TIME 6 5775
TEST_TIME 7 5455
TEST_TIME 8 5514
TEST_TIME 9 5317
TEST_TIME 10 5323
TEST_TIME 11 5577
TEST_TIME 12 6080
TEST_TIME 13 6231
TEST_TIME 14 6440
TEST_TIME 15 6755
TEST_TIME 16 6980
TEST_TIME 17 7763
TEST_TIME 18 7919
TEST_TIME 19 7945
TEST_TIME 20 8461
TEST_TIME 21 8749
TEST_TIME 22 9218
TEST_TIME 23 9327
TEST_TIME 24 9490
TEST_TIME 25 9522
TEST_TIME 26 9975
TEST_TIME 27 10440
TEST_TIME 28 10612
TEST_TIME 29 11019
TEST_TIME 30 10878
TEST_TIME 31 10865
TEST_TIME 32 10597
TEST_TIME 33 10414
TEST_TIME 34 10370
TEST_TIME 35 10292
TEST_TIME 36 10176
TEST_TIME 37 10045
TEST_TIME 38 9834
TEST_TIME 39 9797
TEST_TIME 40 9673
TEST_TIME 41 9555
TEST_TIME 42 9611
TEST_TIME 43 9652
TEST_TIME 44 9742
TEST_TIME 45 9617
TEST_TIME 46 9689
TEST_TIME 47 9522
TEST_TIME 48 9497
TEST_TIME 49 9595
TEST_TIME 50 9510
TEST_TIME 51 9449
TEST_TIME 52 9435
TEST_TIME 53 9433
TEST_TIME 54 9424
TEST_TIME 55 9325
TEST_TIME 56 9275
TEST_TIME 57 9234
TEST_TIME 58 9225
TEST_TIME 59 9119
TEST_TIME 60 9190
TEST_TIME 61 9145
TEST_TIME 62 9243
TEST_TIME 63 9197
TEST_TIME 64 9240
TEST_TIME 65 9330
TEST_TIME 66 9326
TEST_TIME 67 9397
TEST_TIME 68 9309
TEST_TIME 69 9352
TEST_TIME 70 9469
TEST_TIME 71 9414
TEST_TIME 72 9490
TEST_TIME 73 9454
TEST_TIME 74 9485
TEST_TIME 75 9406
TEST_TIME 76 9357
TEST_TIME 77 9384
TEST_TIME 78 9317
TEST_TIME 79 9279
TEST_TIME 80 9286
TEST_TIME 81 9309
TEST_TIME 82 9274
TEST_TIME 83 9201
TEST_TIME 84 9199
TEST_TIME 85 9184
TEST_TIME 86 9171
TEST_TIME 87 9133
TEST_TIME 88 9138
TEST_TIME 89 9144
TEST_TIME 90 9251
TEST_TIME 91 9235
TEST_TIME 92 9201
TEST_TIME 93 9184
TEST_TIME 94 9378
TEST_TIME 95 9406
TEST_TIME 96 9375
TEST_TIME 97 9418
TEST_TIME 98 9446
TEST_TIME 99 9426
TEST_TIME 100 9401
TEST_TIME 101 9338
TEST_TIME 102 9321
TEST_TIME 103 9342
TEST_TIME 104 9290
TEST_TIME 105 9305
TEST_TIME 106 9268
TEST_TIME 107 9220
TEST_TIME 108 9194
TEST_TIME 109 9156
TEST_TIME 110 9129
TEST_TIME 111 9202
TEST_TIME 112 9203
TEST_TIME 113 9205
TEST_TIME 114 9204
TEST_TIME 115 9190
TEST_TIME 116 9210
TEST_TIME 117 9205
TEST_TIME 118 9227
TEST_TIME 119 9217
TEST_TIME 120 9163

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-04-2022 16:39:10 to 25-04-2022 16:39:13.

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 1516978 1516978 0 0.00 0 21924 6 0 0
doc_list_biud 1516978 1516978 0 0.00 0 32046 28 0 0
sp_add_invoice_to_stock 5085 4994 91 1.78 22 166209 17999 0 91
sp_cancel_adding_invoice 5803 5803 0 0.00 5 78389 773 0 0
sp_cancel_client_order 1849 1849 0 0.00 3 28985 363 0 0
sp_cancel_customer_reserve 562912 562912 0 0.00 1 18927 60 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 8990 105 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 3199 69 0 0
sp_cancel_supplier_invoice 6469 6461 8 0.12 2 32051 812 0 8
sp_cancel_supplier_order 404 404 0 0.00 5 1106908 105979 0 0
sp_cancel_write_off 9862 9862 0 0.00 1 10300 108 0 0
sp_client_order 20765 20765 0 0.00 3 23545 369 0 0
sp_customer_reserve 449773 449773 0 0.00 0 84986 181 0 0
sp_fill_shopping_cart 31681 31681 0 0.00 1 15443 41 0 0
sp_fill_shopping_cart_clo_res 449770 449770 0 0.00 0 29156 15 0 0
sp_get_clo_for_invoice 4984 4984 0 0.00 0 32561 384 0 0
sp_kill_cost_storno 16752 16752 0 0.00 0 9584 56 0 0
sp_kill_qstorno_ret_qs2qd 1142551 1142551 0 0.00 0 32043 33 0 0
sp_kill_qty_storno 602224 602224 0 0.00 0 32045 70 0 0
sp_lock_selected_doc 20762 20762 0 0.00 0 20846 5 0 0
sp_make_cost_storno 27957 27957 0 0.00 0 14462 49 0 0
sp_multiply_rows_for_pdistr 27957 27957 0 0.00 0 8043 26 0 0
sp_multiply_rows_for_qdistr 20765 20765 0 0.00 0 11696 154 0 0
sp_pay_from_customer 7228 7228 0 0.00 4 13238 101 0 0
sp_pay_to_supplier 5796 5796 0 0.00 4 12122 121 0 0
sp_payment_common 13024 13024 0 0.00 1 12069 28 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 20236 322 0 0
sp_qd_handle_on_invoice_upd_sts 10787 10787 0 0.00 0 14328 319 0 0
sp_qd_handle_on_reserve_upd_sts 9949 9949 0 0.00 0 11445 65 0 0
sp_reserve_write_off 9949 9949 0 0.00 6 11894 177 0 0
sp_supplier_invoice 5470 5470 0 0.00 5 83190 3504 0 0
sp_supplier_order 5465 5465 0 0.00 5 28173 1282 0 0
srv_aggregate_perf_data 100 100 0 0.00 2772 210780 17738 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1457 133006 46432 0 0
srv_find_qd_qs_mism 1052555 1052555 0 0.00 0 21924 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 34 182780 1338 0 0
srv_make_money_saldo 795 795 0 0.00 0 1212 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 189943 369687 263366 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4994 4994 0 0.00 0 12592 165 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 10691 90 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 1606 12 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2811 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 2771 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1565 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 4514 46 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 23553 1715 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 10932 21 0 0
t$perf-norm:sp_client_order 20765 20765 0 0.00 0 18984 17 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 7838 19 0 0
t$perf-norm:sp_pay_from_customer 7228 7228 0 0.00 0 6204 8 0 0
t$perf-norm:sp_pay_to_supplier 5796 5796 0 0.00 0 5619 9 0 0
t$perf-norm:sp_reserve_write_off 9949 9949 0 0.00 0 6571 13 0 0
t$perf-norm:sp_supplier_invoice 5470 5470 0 0.00 0 6850 22 0 0
t$perf-norm:sp_supplier_order 5465 5465 0 0.00 0 7626 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 2706 46 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 460 13 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 1143 15 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 1726 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 4 0 0
v_add_invoice_to_stock 10150 9968 182 1.79 0 8752 18 0 182
v_all_customers 37896 37896 0 0.00 0 15 0 0 0
v_all_suppliers 30134 30134 0 0.00 0 22 1 0 0
v_all_wares 41530 41530 0 0.00 0 119 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 590 6 0 0
v_cancel_client_order 3698 3698 0 0.00 0 8744 12 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 2145 7 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 3309 12 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3160 10 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 1494 12 0 16
v_cancel_supplier_order 808 808 0 0.00 0 2404 7 0 0
v_cancel_write_off 2020 2020 0 0.00 0 3341 14 0 0
v_max_id_clo_ord 5465 5465 0 0.00 0 851 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 5522 18 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 3544 5 0 0
v_max_non_paid_invoice 1644 1644 0 0.00 0 5097 9 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 2147 4 0 0
v_min_id_clo_ord 5465 5465 0 0.00 0 6141 9 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 4539 16 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 6292 8 0 0
v_min_non_paid_invoice 5796 5796 0 0.00 0 6856 18 0 0
v_min_non_paid_realizn 7228 7228 0 0.00 0 7655 19 0 0
v_our_firm 10434 10434 0 0.00 0 11 1 0 0
v_reserve_write_off 19898 19898 0 0.00 0 3470 6 0 0
x_lock_depdocs_on_canc_invoice 11888 11888 0 0.00 0 38332 125 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 18085 493 0 0
x_make_qstorno_1000_1200 5465 5465 0 0.00 2 28161 1199 0 0
x_make_qstorno_1200_2000 5470 5470 0 0.00 2 80709 3351 0 0
x_make_qstorno_2100_3300 449773 449773 0 0.00 0 84968 163 0 0

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

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:19 15 8192000000 8812281664 8828067840 15916368 0.193 4 4 6 0 16162624 88016 158240
14:09:20 25194 8192000000 9898270224 10208342016 980144352 10.686 100 92 185 0 1011268944 5958640 25165952
14:10:48 26243 8192000000 9952887792 10273193984 1032061440 11.188 100 92 184 0 1064020976 8663888 23295648
14:12:18 12983 8192000000 9982668656 10312445952 1063048432 11.486 100 84 168 0 1093730096 9902848 20778816
14:13:37 13545 8192000000 10026900624 10375397376 1113724432 11.968 100 77 154 0 1142900896 11278816 17897648
14:14:54 9825 8192000000 10073376512 10430066688 1155652896 12.363 100 69 138 1 1183974272 12828880 15492496
14:16:07 4557 8192000000 10123846864 10491113472 1210008192 12.869 100 66 128 0 1234561200 10384528 14168480
14:17:13 2620 8192000000 10170214896 10546978816 1258922352 13.320 100 79 156 1 1288921504 12061904 17937248
14:18:17 1667 8192000000 10200552928 10580406272 1281219328 13.524 100 91 182 1 1315897888 14637984 20040576
14:19:20 35669 8192000000 10195808544 10594955264 1296846864 13.667 100 39 78 1 1312336960 6054288 9435808
14:20:58 6984 8192000000 10193866048 10607579136 1307249136 13.761 100 13 26 0 1315941760 5742176 2950448
14:22:06 9738 8192000000 10201000176 10623115264 1318128608 13.860 100 15 30 2 1328211536 6238528 3844400
14:23:17 59115 8192000000 10237716000 10639261696 1322808528 13.902 100 38 79 1 1337056912 4546432 9701952
14:25:16 3568 8192000000 10220891728 10639437824 1328174592 13.951 100 36 71 5 1343029424 6890128 7964704
14:26:22 6225 8192000000 10227951728 10648473600 1335361456 14.016 100 24 48 0 1346259696 5077280 5820960
14:27:28 13661 8192000000 10205264848 10623987712 1292318144 13.625 98 15 30 1 1300853440 5088448 3446848
14:28:42 26008 8192000000 10183079968 10602545152 1257557664 13.308 95 21 42 0 1271731984 9931488 4242832
14:30:11 5174 8192000000 10170559104 10568085504 1249235712 13.231 98 33 65 2 1262564304 5931312 7397280
14:31:18 10846 8192000000 10118318272 10514014208 1163925264 12.440 94 19 38 1 1173835424 5009632 4900528
14:32:35 53839 8192000000 10134558464 10539417600 1228542496 13.041 98 30 60 1 1240975520 4792992 7640032
14:34:29 6043 8192000000 10173863440 10577854464 1277939488 13.494 100 31 62 0 1290971664 5444528 7587648
14:35:38 9697 8192000000 10191831904 10601938944 1299323200 13.689 100 19 39 0 1309423360 5753280 4346880
14:36:52 9226 8192000000 10196313456 10613264384 1307324960 13.762 100 14 28 1 1316112256 5796944 2990352
14:38:06 62436 8192000000 10236811232 10641408000 1323043760 13.904 100 50 97 1 1342255152 8708944 10502448
14:40:15 10046 8192000000 10253376288 10666913792 1345872000 14.110 100 54 108 0 1371464880 13273424 12319456
14:41:31 21124 8192000000 10282056752 10695241728 1363152768 14.266 100 57 114 0 1391483760 15708064 12622928
14:42:55 11571 8192000000 10286701136 10703732736 1371433280 14.340 100 45 90 1 1399862736 18052480 10376976
14:44:10 27162 8192000000 10306173696 10731565056 1393159408 14.534 100 39 78 2 1423458256 20909712 9389136
14:45:44 9285 8192000000 10313810912 10734850048 1391280608 14.517 100 48 96 0 1427335088 24427936 11626544
14:47:01 9249 8192000000 10324167040 10744590336 1399554880 14.591 100 57 112 0 1435069248 22210304 13304064
14:48:25 8416 8192000000 10338786880 10755899392 1407630000 14.663 100 59 118 1 1448998464 26695088 14673376
14:49:39 14373 8192000000 10342431568 10763714560 1408844320 14.674 100 58 115 0 1455101920 30777152 15480448
14:50:59 3649 8192000000 10347892752 10765778944 1407403024 14.661 100 65 129 1 1454963184 31048352 16511808
14:52:08 3535 8192000000 10360774480 10773118976 1405490128 14.644 100 69 137 1 1462073568 39745376 16838064
14:53:17 2773 8192000000 10354328864 10774355968 1404608384 14.636 100 61 121 2 1459883488 39274656 16000448
14:54:30 1518 8192000000 10341544560 10749046784 1381860928 14.433 100 80 160 3 1443162944 41331072 19970944
14:55:35 9730 8192000000 10308863984 10735702016 1368397408 14.313 99 46 92 2 1415489760 35005168 12087184
14:56:50 1457 8192000000 10305612928 10716647424 1345697472 14.109 98 68 136 3 1395862944 31663248 18502224
14:57:59 3166 8192000000 10319072544 10725486592 1358807232 14.227 100 70 141 0 1419085008 41381808 18895968
14:59:05 8759 8192000000 10314682976 10738016256 1369860512 14.326 100 46 92 2 1422778448 40785984 12131952
15:00:21 16113 8192000000 10299867616 10728599552 1360903984 14.245 100 29 58 0 1408144032 39008784 8231264
15:01:40 12572 8192000000 10304786400 10730192896 1357943248 14.219 99 29 58 0 1402307024 36673200 7690576
15:02:56 17640 8192000000 10289459984 10729275392 1336871456 14.029 98 25 50 1 1384303520 39779152 7652912
15:04:18 7365 8192000000 10309059536 10733395968 1356429888 14.205 100 48 97 0 1413143728 43625760 13088080
15:05:27 20791 8192000000 10285519312 10723262464 1349853632 14.146 100 36 72 2 1391442032 32499984 9088416
15:06:54 36363 8192000000 10282120448 10729189376 1353253664 14.177 99 27 54 0 1388621184 28797872 6569648
15:08:43 64620 8192000000 10262343072 10700701696 1328188432 13.951 97 34 68 1 1350241760 14067216 7986112
15:11:00 111112 8192000000 10275121600 10725425152 1338205376 14.041 97 24 48 0 1351293232 7721968 5365888
15:13:51 96260 8192000000 10291035840 10745274368 1381864016 14.433 100 15 30 0 1391746608 6863248 3019344
15:16:43 29073 8192000000 10317722880 10755473408 1399292784 14.589 100 44 88 1 1422927200 13441904 10192512
15:18:13 22357 8192000000 10329935632 10780463104 1416370176 14.741 100 24 48 1 1442536368 20129536 6036656
15:19:41 30255 8192000000 10336885728 10780594176 1405825552 14.647 99 47 93 2 1437231664 20069856 11336256
15:21:18 88050 8192000000 10305363520 10773594112 1389132480 14.498 99 16 32 0 1418976016 25945888 3897648
15:24:05 20453 8192000000 10314669872 10766458880 1405129728 14.641 100 30 60 0 1424915664 12572304 7213632
15:25:26 107365 8192000000 10305925488 10767921152 1384695712 14.459 99 23 46 1 1409872928 19316112 5861104
15:28:25 26358 8192000000 10294002128 10750988288 1386530512 14.475 99 18 35 0 1400415200 9627648 4257040
15:29:53 110173 8192000000 10291672768 10764476416 1387800592 14.486 99 15 30 1 1406684992 15063600 3820800
15:32:45 76421 8192000000 10312300032 10767081472 1407643360 14.663 100 21 42 1 1422173424 9165216 5364848
15:35:07 86041 8192000000 10292278800 10745798656 1373319792 14.357 99 32 62 2 1394777568 14033360 7424416
15:37:33 38305 8192000000 10262509472 10721763328 1350316320 14.150 98 6 12 0 1368552928 16846992 1389616
15:39:23 44758 8192000000 10261943088 10717229056 1328593328 13.954 96 15 30 1 1341574368 9938208 3042832
15:41:08 36031 8192000000 10263901104 10717409280 1349914736 14.147 98 11 22 0 1358555376 6645264 1995376
15:42:48 101633 8192000000 10268988240 10734637056 1295230080 13.652 94 7 14 0 1310457056 13886256 1340720
15:45:36 58329 8192000000 10280046992 10723430400 1336217680 14.023 98 17 34 0 1352812144 12819488 3774976
15:47:37 28388 8192000000 10274097648 10714492928 1333384304 13.998 98 33 66 0 1354086128 13126592 7575232
15:49:08 54823 8192000000 10275163856 10707099648 1320959504 13.885 97 51 99 3 1342208816 10120176 11129136
15:51:07 48986 8192000000 10260105920 10699579392 1301263504 13.707 96 24 47 0 1317180464 11136800 4780160
15:53:14 104718 8192000000 10267197296 10710257664 1333792976 14.001 98 22 44 1 1355036560 15826560 5417024
15:55:59 53364 8192000000 10265468400 10721669120 1316301408 13.843 96 16 31 2 1338202656 18092112 3809136
15:57:53 105007 8192000000 10250116512 10725330944 1354387104 14.187 100 12 24 0 1378714656 21613520 2714032
16:00:38 76372 8192000000 10249451360 10707734528 1361519152 14.251 100 7 14 0 1370725536 7449808 1756576
16:04:00 34422 8192000000 10264981664 10721800192 1345658496 14.108 98 26 52 0 1358566688 6928784 5979408
16:05:39 62311 8192000000 10274158368 10728894464 1353061088 14.175 99 31 62 0 1372219808 12115296 7043424
16:07:53 83631 8192000000 10276372448 10734469120 1323231200 13.906 96 13 26 1 1342006144 15989312 2785632
16:10:18 65001 8192000000 10306700800 10755612672 1372858224 14.353 99 23 46 0 1392487216 14833504 4795488
16:12:37 89366 8192000000 10320990864 10778370048 1392041088 14.524 100 6 12 1 1410812064 17376304 1394672
16:15:07 130751 8192000000 10311398192 10770792448 1391377280 14.518 99 9 18 0 1410977040 17734512 1865248
16:18:26 133006 8192000000 10306107264 10763112448 1379014640 14.408 98 33 66 3 1395488464 9417520 7056304
16:21:43 57540 8192000000 10306161456 10773118976 1404221536 14.633 100 16 32 1 1420848832 12649488 3977808
16:23:51 57133 8192000000 10311549760 10773422080 1406767104 14.655 100 16 30 1 1419440688 8792736 3880848
16:25:57 72896 8192000000 10306515952 10773078016 1408263344 14.669 100 11 22 1 1423612896 12408512 2941040
16:28:12 59065 8192000000 10298253072 10766340096 1395595504 14.556 99 23 46 1 1415492016 14423408 5473104
16:30:13 14043 8192000000 10281086800 10727809024 1361743504 14.253 98 24 48 1 1374338000 6840080 5754416
16:31:33 38770 8192000000 10275517440 10713079808 1336257360 14.024 97 17 34 1 1348933664 8786768 3889536
16:33:15 46646 8192000000 10261091232 10706067456 1316513488 13.845 96 27 54 1 1333506080 10564096 6428496
16:35:10 55007 8192000000 10269436432 10718281728 1338906448 14.048 98 24 48 0 1352763568 8667296 5189824
16:37:05 33616 8192000000 10258417200 10701750272 1300750576 13.702 95 17 34 0 1313969712 9664496 3554640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-04-2022 16:39:20 to 25-04-2022 16:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-04-2022 16:39:36 to 25-04-2022 16:39:38.

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          237037
MON$OLDEST_ACTIVE               240223
MON$OLDEST_SNAPSHOT             240223
MON$NEXT_TRANSACTION            240223
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                       2028512
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1632: 
:1633: mscs-test-161.budzdorov.ru    Mon Apr 25 16:38:16 2022
:1634:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1635: 
:1636: 
:1637: mscs-test-161.budzdorov.ru    Mon Apr 25 16:38:16 2022
:1638:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1639: 
:1640: 
:1641: mscs-test-161.budzdorov.ru    Mon Apr 25 16:38:18 2022
:1642:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1643: 
:1644: 
:1645: mscs-test-161.budzdorov.ru    Mon Apr 25 16:38:23 2022
:1646:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1647: 
--- end of diff output ---

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

25.04.22 16:39:39. 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-25 16:39:41 No severe PSQL-related problems occured
25.04.22 16:39:42. End of report.