Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15967248 kB
MemAvailable 30842380 kB
Buffers 0 kB
Cached 15706364 kB
SwapCached 4888 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.8
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 2021-10-26 16:38:30
DTS_END                         2021-10-26 16:37:57.0260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56796
TRN_ID                          232730
ATT_ID                          274
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: 9304.15 22457 1116498
customer order: creation 161.74 356 19409
customer order: refuse 14.32 388 1718
order to supplier: creation 42.75 1230 5130
order to supplier: removal 3.37 112434 404
invoice (draft): creation 43.80 3584 5256
invoice (draft): removal 55.75 750 6690
invoice accept: apply 39.23 17567 4708
invoice accept: cancel 50.73 675 6087
customer reserve: creation 3677.85 169 441342
customer reserve: removal 4921.39 60 590567
realization accept: apply 78.63 193 9436
realization accept: cancel 93.33 99 11200
payment from customer: creation 55.50 124 6660
payment from customer: removal 5.16 71 619
payment to supplier: creation 45.58 166 5470
payment to supplier: removal 3.22 105 386
service: total inventory turnovers 4.58 1092 549
service: total monetary turnovers 6.39 30 767
service: aggregate perf. data 0.80 20445 96
service: refresh index statistics 0.03 289608 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2021 16:39:12 to 26-10-2021 16:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4382
TEST_TIME 2 3628
TEST_TIME 3 4779
TEST_TIME 4 4870
TEST_TIME 5 4895
TEST_TIME 6 5173
TEST_TIME 7 5664
TEST_TIME 8 5626
TEST_TIME 9 5395
TEST_TIME 10 5222
TEST_TIME 11 5235
TEST_TIME 12 5089
TEST_TIME 13 5214
TEST_TIME 14 5257
TEST_TIME 15 5405
TEST_TIME 16 5695
TEST_TIME 17 6191
TEST_TIME 18 6249
TEST_TIME 19 6343
TEST_TIME 20 6352
TEST_TIME 21 7182
TEST_TIME 22 7762
TEST_TIME 23 7986
TEST_TIME 24 8094
TEST_TIME 25 8425
TEST_TIME 26 8748
TEST_TIME 27 9495
TEST_TIME 28 9641
TEST_TIME 29 10454
TEST_TIME 30 10484
TEST_TIME 31 10287
TEST_TIME 32 10554
TEST_TIME 33 10366
TEST_TIME 34 10373
TEST_TIME 35 10096
TEST_TIME 36 9937
TEST_TIME 37 9817
TEST_TIME 38 9730
TEST_TIME 39 9827
TEST_TIME 40 9685
TEST_TIME 41 9578
TEST_TIME 42 9404
TEST_TIME 43 9500
TEST_TIME 44 9592
TEST_TIME 45 9586
TEST_TIME 46 9522
TEST_TIME 47 9366
TEST_TIME 48 9281
TEST_TIME 49 9332
TEST_TIME 50 9234
TEST_TIME 51 9226
TEST_TIME 52 9223
TEST_TIME 53 9126
TEST_TIME 54 9083
TEST_TIME 55 8993
TEST_TIME 56 9021
TEST_TIME 57 9173
TEST_TIME 58 9135
TEST_TIME 59 9187
TEST_TIME 60 9232
TEST_TIME 61 9251
TEST_TIME 62 9343
TEST_TIME 63 9420
TEST_TIME 64 9434
TEST_TIME 65 9525
TEST_TIME 66 9540
TEST_TIME 67 9603
TEST_TIME 68 9630
TEST_TIME 69 9618
TEST_TIME 70 9640
TEST_TIME 71 9610
TEST_TIME 72 9535
TEST_TIME 73 9449
TEST_TIME 74 9468
TEST_TIME 75 9415
TEST_TIME 76 9404
TEST_TIME 77 9452
TEST_TIME 78 9398
TEST_TIME 79 9391
TEST_TIME 80 9344
TEST_TIME 81 9266
TEST_TIME 82 9266
TEST_TIME 83 9236
TEST_TIME 84 9256
TEST_TIME 85 9293
TEST_TIME 86 9315
TEST_TIME 87 9339
TEST_TIME 88 9322
TEST_TIME 89 9366
TEST_TIME 90 9378
TEST_TIME 91 9480
TEST_TIME 92 9448
TEST_TIME 93 9427
TEST_TIME 94 9484
TEST_TIME 95 9598
TEST_TIME 96 9640
TEST_TIME 97 9622
TEST_TIME 98 9546
TEST_TIME 99 9517
TEST_TIME 100 9464
TEST_TIME 101 9426
TEST_TIME 102 9376
TEST_TIME 103 9334
TEST_TIME 104 9337
TEST_TIME 105 9301
TEST_TIME 106 9276
TEST_TIME 107 9267
TEST_TIME 108 9286
TEST_TIME 109 9246
TEST_TIME 110 9220
TEST_TIME 111 9182
TEST_TIME 112 9142
TEST_TIME 113 9176
TEST_TIME 114 9135
TEST_TIME 115 9190
TEST_TIME 116 9224
TEST_TIME 117 9281
TEST_TIME 118 9235
TEST_TIME 119 9246
TEST_TIME 120 9308

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2021 16:39:16 to 26-10-2021 16:39:19.

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 1525479 1525479 0 0.00 0 19053 6 0 0
doc_list_biud 1525479 1525479 0 0.00 0 47922 29 0 0
sp_add_invoice_to_stock 4818 4708 110 2.28 42 167110 17568 0 110
sp_cancel_adding_invoice 6087 6087 0 0.00 5 39074 676 0 0
sp_cancel_client_order 1718 1718 0 0.00 5 13648 388 0 0
sp_cancel_customer_reserve 590567 590567 0 0.00 1 47923 61 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 3 6690 72 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 2 6293 105 0 0
sp_cancel_supplier_invoice 6703 6690 13 0.19 1 27561 750 0 13
sp_cancel_supplier_order 404 404 0 0.00 6 1038786 112434 0 0
sp_cancel_write_off 11200 11200 0 0.00 1 14665 99 0 0
sp_client_order 19409 19409 0 0.00 4 20696 356 0 0
sp_customer_reserve 441342 441342 0 0.00 0 90065 170 0 0
sp_fill_shopping_cart 29784 29784 0 0.00 1 13182 40 0 0
sp_fill_shopping_cart_clo_res 441336 441336 0 0.00 0 14777 15 0 0
sp_get_clo_for_invoice 4702 4702 0 0.00 0 22542 350 0 0
sp_kill_cost_storno 18292 18292 0 0.00 0 18041 54 0 0
sp_kill_qstorno_ret_qs2qd 1199428 1199428 0 0.00 0 47907 33 0 0
sp_kill_qty_storno 630804 630804 0 0.00 0 47921 70 0 0
sp_lock_selected_doc 19644 19644 0 0.00 0 9368 7 0 0
sp_make_cost_storno 26268 26268 0 0.00 0 17675 58 0 0
sp_multiply_rows_for_pdistr 26268 26268 0 0.00 0 12881 36 0 0
sp_multiply_rows_for_qdistr 19409 19409 0 0.00 0 17812 158 0 0
sp_pay_from_customer 6660 6660 0 0.00 4 37894 125 0 0
sp_pay_to_supplier 5470 5470 0 0.00 5 37824 167 0 0
sp_payment_common 12130 12130 0 0.00 1 37885 45 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 13637 357 0 0
sp_qd_handle_on_invoice_upd_sts 10789 10789 0 0.00 0 23052 328 0 0
sp_qd_handle_on_reserve_upd_sts 9436 9436 0 0.00 0 13611 65 0 0
sp_reserve_write_off 9436 9436 0 0.00 6 16838 193 0 0
sp_supplier_invoice 5256 5256 0 0.00 5 69368 3585 0 0
sp_supplier_order 5130 5130 0 0.00 5 51978 1230 0 0
srv_aggregate_perf_data 96 96 0 0.00 2542 258962 20446 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1540 161213 50424 0 0
srv_find_qd_qs_mism 1071581 1071581 0 0.00 0 19052 4 0 0
srv_make_invnt_saldo 549 549 0 0.00 43 45717 1092 0 0
srv_make_money_saldo 767 767 0 0.00 0 3926 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 59710 461086 289609 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4708 4708 0 0.00 0 15826 171 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 5699 105 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 4953 21 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 859 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 3586 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 77 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 2616 21 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 18956 1573 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 4112 24 0 0
t$perf-norm:sp_client_order 19409 19409 0 0.00 0 10831 18 0 0
t$perf-norm:sp_customer_reserve 891 891 0 0.00 0 1274 5 0 0
t$perf-norm:sp_pay_from_customer 6660 6660 0 0.00 0 10721 11 0 0
t$perf-norm:sp_pay_to_supplier 5470 5470 0 0.00 0 4229 12 0 0
t$perf-norm:sp_reserve_write_off 9436 9436 0 0.00 0 6838 12 0 0
t$perf-norm:sp_supplier_invoice 5256 5256 0 0.00 0 5648 23 0 0
t$perf-norm:sp_supplier_order 5130 5130 0 0.00 0 4547 16 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 183 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 81 3 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 3237 37 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 856 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 10 0 0
v_add_invoice_to_stock 9620 9400 220 2.28 0 4980 19 0 220
v_all_customers 35822 35822 0 0.00 0 17 1 0 0
v_all_suppliers 28764 28764 0 0.00 0 21 1 0 0
v_all_wares 38816 38816 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 1256 7 0 0
v_cancel_client_order 3436 3436 0 0.00 0 3245 6 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 618 4 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 457 3 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 3380 16 0 0
v_cancel_supplier_invoice 768 742 26 3.38 0 2121 17 0 26
v_cancel_supplier_order 808 808 0 0.00 0 5998 13 0 0
v_cancel_write_off 1986 1986 0 0.00 0 3437 17 0 0
v_max_id_clo_ord 5128 5128 0 0.00 0 2041 2 0 0
v_max_id_clo_res 891 891 0 0.00 0 3749 17 0 0
v_max_id_ord_sup 5247 5247 0 0.00 0 5342 3 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 4744 7 0 0
v_max_non_paid_realizn 3280 3280 0 0.00 0 1772 4 0 0
v_min_id_clo_ord 5128 5128 0 0.00 0 4595 10 0 0
v_min_id_clo_res 891 891 0 0.00 0 2937 10 0 0
v_min_id_ord_sup 5247 5247 0 0.00 0 6444 10 0 0
v_min_non_paid_invoice 5470 5470 0 0.00 0 37807 43 0 0
v_min_non_paid_realizn 6660 6660 0 0.00 0 6786 26 0 0
v_our_firm 9754 9754 0 0.00 0 16 1 0 0
v_reserve_write_off 18872 18872 0 0.00 0 3830 5 0 0
x_lock_depdocs_on_canc_invoice 12406 12406 0 0.00 0 18612 118 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 21752 402 0 0
x_make_qstorno_1000_1200 5130 5130 0 0.00 1 51972 1148 0 0
x_make_qstorno_1200_2000 5256 5256 0 0.00 2 69359 3440 0 0
x_make_qstorno_2100_3300 441342 441342 0 0.00 0 90064 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2021 16:39:20 to 26-10-2021 16:39:24.

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:07:59 3 8192000000 8802310576 8814628864 7583888 0.092 2 2 3 0 7724976 66144 74944
14:09:00 19784 8192000000 9913041488 10225815552 997598624 10.855 100 87 174 0 1026707792 6569168 22540000
14:10:27 23489 8192000000 9952673872 10278301696 1036677856 11.233 100 89 178 0 1068463184 8983856 22801472
14:11:55 16188 8192000000 9984563344 10319335424 1063264752 11.488 100 82 162 1 1093904528 10194736 20445040
14:13:19 13420 8192000000 10034369552 10384498688 1119152320 12.019 100 76 152 0 1148703376 11546272 18004784
14:14:38 9202 8192000000 10090866208 10452738048 1175278272 12.546 100 75 148 0 1204694208 12823904 16592032
14:15:50 4536 8192000000 10132833152 10504814592 1216886688 12.933 100 84 169 0 1249761312 13295776 19578848
14:16:58 2909 8192000000 10158366864 10536648704 1249673296 13.235 100 84 168 0 1282392912 14262064 18457552
14:18:03 2174 8192000000 10201384288 10580877312 1277114208 13.487 100 85 171 0 1309427936 13598048 18715680
14:19:07 13416 8192000000 10192999872 10586206208 1289615536 13.601 100 72 141 1 1315720240 9707616 16397088
14:20:21 4001 8192000000 10194859680 10603220992 1300740384 13.702 100 38 76 1 1317581136 7627440 9213312
14:21:26 13596 8192000000 10215105568 10612801536 1311178896 13.797 100 57 117 2 1331027168 6542528 13305744
14:22:40 8007 8192000000 10208803376 10625810432 1317392128 13.853 100 20 40 0 1328057056 5736288 4928640
14:23:49 8061 8192000000 10203220576 10625581056 1320126880 13.878 100 28 56 2 1331771312 5082848 6561584
14:24:57 46187 8192000000 10236665520 10645405696 1328763056 13.956 100 60 119 2 1349778480 5802128 15213296
14:26:47 5232 8192000000 10221129424 10639429632 1329444384 13.962 100 27 54 1 1342593344 6867488 6281472
14:27:56 6295 8192000000 10205005232 10618298368 1299411008 13.690 99 35 70 0 1313455952 5716528 8328416
14:29:02 24110 8192000000 10142302624 10551078912 1152364416 12.332 89 21 42 1 1161691472 4755728 4571328
14:30:27 12889 8192000000 10125838416 10526650368 1188520720 12.670 94 26 52 1 1199512384 4785616 6206048
14:31:40 6158 8192000000 10083482272 10472353792 1191394944 12.696 96 23 46 1 1202816656 6365904 5055808
14:32:50 6018 8192000000 10151096240 10552983552 1248456144 13.224 99 27 53 3 1261193456 6184176 6553136
14:33:56 18037 8192000000 10155315120 10564509696 1258718144 13.318 99 16 32 1 1267497440 5546000 3233296
14:35:38 7115 8192000000 10178510752 10589020160 1285973744 13.568 100 20 40 0 1296400288 5849408 4577136
14:36:49 9399 8192000000 10187805664 10600226816 1294124096 13.642 100 26 52 1 1305132912 5424288 5584528
14:38:00 5280 8192000000 10230602896 10636562432 1324038464 13.913 100 55 110 2 1344282160 5778512 14465184
14:39:15 67276 8192000000 10247810400 10661429248 1336781888 14.028 100 53 106 1 1357919232 8947728 12189616
14:41:28 18311 8192000000 10244577312 10671853568 1349061248 14.139 100 25 50 2 1365553936 10188704 6303984
14:42:49 8248 8192000000 10274104112 10690744320 1350744624 14.154 99 49 98 1 1378840080 16790288 11305168
14:44:04 13670 8192000000 10295147056 10716626944 1370871136 14.335 100 53 106 2 1408621648 25847360 11903152
14:45:23 10751 8192000000 10305527968 10730545152 1386801584 14.477 100 45 90 1 1416968848 19536176 10631088
14:46:38 34763 8192000000 10320109008 10745593856 1397370464 14.572 100 44 88 1 1432072896 23909808 10792624
14:48:19 21348 8192000000 10325829200 10758201344 1406531328 14.653 100 37 73 2 1441669536 25626704 9511504
14:49:55 13747 8192000000 10355187360 10780631040 1424176000 14.810 100 48 96 0 1465836368 29338528 12321840
14:51:14 7744 8192000000 10381022912 10800750592 1429966992 14.861 100 67 135 2 1486965792 40412464 16586336
14:52:26 6950 8192000000 10372252032 10789269504 1417380416 14.749 100 67 133 1 1478018320 43846032 16791872
14:53:37 3803 8192000000 10362440096 10775998464 1404504512 14.635 100 67 134 1 1469998016 47917824 17575680
14:54:46 2518 8192000000 10368483392 10777378816 1407346384 14.660 100 86 172 0 1476904800 48159888 21398528
14:55:54 2462 8192000000 10371105376 10777493504 1395592528 14.556 100 81 163 1 1472994800 57108144 20294128
14:57:01 1538 8192000000 10359531344 10758447104 1367193680 14.302 99 90 178 0 1453408720 63005504 23209536
14:58:08 2231 8192000000 10377105472 10781003776 1378695840 14.405 99 88 176 1 1475387888 73659520 23032528
14:59:15 12068 8192000000 10343963264 10769813504 1381661632 14.431 100 48 96 0 1453451680 58874672 12915376
15:00:30 13027 8192000000 10343013536 10769293312 1374071280 14.364 99 44 88 1 1445120736 58955088 12094368
15:02:22 15062 8192000000 10332352048 10778091520 1370494736 14.331 99 27 54 1 1436267792 57051296 8721760
15:03:48 20844 8192000000 10311203264 10767294464 1359385728 14.232 99 20 40 1 1415608448 49879936 6342784
15:05:35 36225 8192000000 10298645120 10726690816 1341538880 14.071 99 21 42 0 1389844656 42573312 5732464
15:07:11 42083 8192000000 10259508032 10701017088 1324294256 13.916 97 14 28 0 1341586656 14176688 3115712
15:08:57 100659 8192000000 10294448736 10732298240 1346562736 14.117 98 20 39 2 1369234608 18585216 4086656
15:12:27 161213 8192000000 10298575872 10737958912 1352187408 14.167 98 28 56 0 1365718240 7071152 6459680
15:16:09 33588 8192000000 10281758704 10735276032 1375023616 14.372 100 27 55 1 1393234528 11308976 6901936
15:17:55 68311 8192000000 10307930688 10754887680 1395752896 14.557 100 23 46 1 1412911888 11782448 5376544
15:20:10 56133 8192000000 10303973392 10769264640 1397372016 14.572 100 22 45 1 1417728720 14978912 5377792
15:22:07 63615 8192000000 10281401152 10736476160 1373102144 14.355 99 15 30 1 1384264208 8097584 3064480
15:24:19 95078 8192000000 10293950432 10751844352 1380778192 14.424 99 26 52 0 1396058448 9455872 5824384
15:27:30 34241 8192000000 10307576064 10762608640 1396734176 14.566 100 29 52 2 1415489088 12193440 6561472
15:29:05 66733 8192000000 10313833840 10780794880 1414038144 14.720 100 21 42 0 1430287616 11448960 4800512
15:31:18 46089 8192000000 10317361936 10781380608 1418362256 14.758 100 17 34 0 1434522464 11658816 4501392
15:33:04 59805 8192000000 10334293520 10800271360 1421162832 14.783 100 38 75 1 1449472960 19044432 9265696
15:35:12 36524 8192000000 10315613520 10777976832 1411771456 14.700 100 19 38 2 1430089008 13954752 4362800
15:36:54 46782 8192000000 10309607904 10761621504 1375333872 14.375 98 28 56 1 1396547856 14035936 7178048
15:38:43 58924 8192000000 10301219280 10753150976 1375228368 14.374 98 16 32 1 1397110768 18193232 3689168
15:40:46 25390 8192000000 10310427952 10758782976 1382150944 14.436 99 25 51 0 1397230832 9911392 5168496
15:42:20 66228 8192000000 10282222464 10739798016 1279999088 13.513 92 9 18 0 1299372128 17761216 1611824
15:44:26 89608 8192000000 10288114032 10746429440 1344823488 14.101 98 16 32 0 1376887040 28635120 3428432
15:47:02 81086 8192000000 10267621152 10713948160 1297224672 13.670 95 20 40 1 1312802160 11619952 3957536
15:49:24 105758 8192000000 10272514560 10709360640 1326592656 13.936 97 44 88 1 1348021648 11156080 10272912
15:52:13 43674 8192000000 10269774576 10709336064 1360165664 14.239 100 47 93 5 1378797104 8621712 10009728
15:54:28 65432 8192000000 10243643552 10704379904 1310164144 13.788 96 4 8 0 1320788608 9814848 809616
15:56:36 55216 8192000000 10230243696 10681921536 1314790720 13.830 97 16 32 1 1326773600 8386000 3596880
15:58:59 92361 8192000000 10257864048 10727575552 1363077344 14.265 100 11 22 0 1378643296 12759632 2806320
16:01:56 59455 8192000000 10263802256 10728935424 1346081072 14.112 98 13 26 1 1363247392 13980080 3186240
16:03:58 95288 8192000000 10267927072 10726408192 1314183264 13.824 96 12 24 0 1327174192 10479712 2511216
16:06:33 72092 8192000000 10292397600 10749947904 1376740032 14.387 100 16 32 1 1395507712 14954576 3813104
16:08:46 93863 8192000000 10296673360 10745167872 1347499088 14.125 97 18 36 0 1363292768 12087520 3706160
16:11:20 68500 8192000000 10308859504 10773729280 1381784912 14.433 99 10 20 0 1404303376 20532704 1985760
16:13:41 149671 8192000000 10297576016 10746826752 1368868032 14.317 98 19 38 0 1392890752 19273696 4749024
16:17:13 36570 8192000000 10292368960 10757128192 1391927856 14.523 100 8 16 1 1402702864 9214256 1560752
16:18:52 73821 8192000000 10292531920 10756337664 1373180208 14.356 98 9 18 0 1384085904 8631152 2274544
16:21:06 70744 8192000000 10291415440 10756395008 1385541744 14.466 99 15 31 0 1397022400 8405392 3075264
16:23:17 98443 8192000000 10287065888 10758418432 1383126336 14.444 99 27 54 0 1402184032 12886272 6171424
16:26:04 36939 8192000000 10279817024 10723295232 1320891680 13.885 95 41 81 1 1337317520 6949344 9476496
16:27:41 70495 8192000000 10279110944 10743472128 1371381216 14.339 99 14 28 0 1387003776 12355664 3266896
16:29:58 79150 8192000000 10276111824 10739142656 1327117136 13.941 96 13 26 0 1344675456 14455008 3103312
16:32:18 85289 8192000000 10281668256 10750480384 1334067200 14.004 96 14 28 1 1363655232 26311728 3276304
16:34:49 49574 8192000000 10284540384 10740875264 1323727696 13.910 96 8 16 0 1343694016 18432672 1533648
16:36:46 23470 8192000000 10283658624 10734931968 1344991936 14.102 99 24 48 2 1371740336 21186928 5561472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2021 16:39:26 to 26-10-2021 16:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2021 16:39:41 to 26-10-2021 16:39: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          241096
MON$OLDEST_ACTIVE               241097
MON$OLDEST_SNAPSHOT             241097
MON$NEXT_TRANSACTION            241097
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                       2043936
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
--- end of diff output ---

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

26.10.21 16:39: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
2021-10-26 16:39:47 No severe PSQL-related problems occured
26.10.21 16:39:48. End of report.