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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15321872 kB
MemAvailable 30520768 kB
Buffers 0 kB
Cached 16376368 kB
SwapCached 2148 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.11
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-10-10 16:38:29
DTS_END                         2022-10-10 16:38:29.2930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35854
TRN_ID                          244597
ATT_ID                          684
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: 9467.80 22322 1136136
customer order: creation 172.97 356 20756
customer order: refuse 14.73 354 1767
order to supplier: creation 46.49 1241 5579
order to supplier: removal 3.59 105667 431
invoice (draft): creation 47.28 3320 5673
invoice (draft): removal 54.79 816 6575
invoice accept: apply 41.93 16947 5031
invoice accept: cancel 49.41 905 5929
customer reserve: creation 3906.46 165 468775
customer reserve: removal 4821.62 62 578594
realization accept: apply 83.43 195 10011
realization accept: cancel 94.58 115 11350
payment from customer: creation 58.93 132 7072
payment from customer: removal 5.76 123 691
payment to supplier: creation 49.90 143 5988
payment to supplier: removal 3.37 128 404
service: total inventory turnovers 4.89 1113 587
service: total monetary turnovers 6.87 33 824
service: aggregate perf. data 0.79 23090 95
service: refresh index statistics 0.03 291526 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-10-2022 16:39:29 to 10-10-2022 16:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2950
TEST_TIME 2 2520
TEST_TIME 3 3106
TEST_TIME 4 3633
TEST_TIME 5 4428
TEST_TIME 6 4647
TEST_TIME 7 4977
TEST_TIME 8 5160
TEST_TIME 9 5303
TEST_TIME 10 5240
TEST_TIME 11 5762
TEST_TIME 12 6299
TEST_TIME 13 6447
TEST_TIME 14 6835
TEST_TIME 15 7039
TEST_TIME 16 7316
TEST_TIME 17 7235
TEST_TIME 18 7322
TEST_TIME 19 7565
TEST_TIME 20 7635
TEST_TIME 21 7851
TEST_TIME 22 8307
TEST_TIME 23 8277
TEST_TIME 24 8301
TEST_TIME 25 8684
TEST_TIME 26 9098
TEST_TIME 27 8991
TEST_TIME 28 8803
TEST_TIME 29 8990
TEST_TIME 30 9183
TEST_TIME 31 9288
TEST_TIME 32 9459
TEST_TIME 33 10603
TEST_TIME 34 10525
TEST_TIME 35 10577
TEST_TIME 36 10540
TEST_TIME 37 10427
TEST_TIME 38 10392
TEST_TIME 39 10378
TEST_TIME 40 10335
TEST_TIME 41 10357
TEST_TIME 42 10219
TEST_TIME 43 10012
TEST_TIME 44 9993
TEST_TIME 45 10014
TEST_TIME 46 9894
TEST_TIME 47 9748
TEST_TIME 48 9723
TEST_TIME 49 9674
TEST_TIME 50 9814
TEST_TIME 51 9822
TEST_TIME 52 9880
TEST_TIME 53 9878
TEST_TIME 54 9837
TEST_TIME 55 9749
TEST_TIME 56 9718
TEST_TIME 57 9648
TEST_TIME 58 9673
TEST_TIME 59 9646
TEST_TIME 60 9656
TEST_TIME 61 9629
TEST_TIME 62 9595
TEST_TIME 63 9704
TEST_TIME 64 9740
TEST_TIME 65 9771
TEST_TIME 66 9769
TEST_TIME 67 9781
TEST_TIME 68 9716
TEST_TIME 69 9780
TEST_TIME 70 9891
TEST_TIME 71 9928
TEST_TIME 72 9954
TEST_TIME 73 9851
TEST_TIME 74 9765
TEST_TIME 75 9760
TEST_TIME 76 9707
TEST_TIME 77 9660
TEST_TIME 78 9717
TEST_TIME 79 9672
TEST_TIME 80 9670
TEST_TIME 81 9618
TEST_TIME 82 9620
TEST_TIME 83 9549
TEST_TIME 84 9521
TEST_TIME 85 9518
TEST_TIME 86 9471
TEST_TIME 87 9433
TEST_TIME 88 9412
TEST_TIME 89 9394
TEST_TIME 90 9396
TEST_TIME 91 9400
TEST_TIME 92 9412
TEST_TIME 93 9477
TEST_TIME 94 9504
TEST_TIME 95 9512
TEST_TIME 96 9533
TEST_TIME 97 9550
TEST_TIME 98 9551
TEST_TIME 99 9533
TEST_TIME 100 9587
TEST_TIME 101 9713
TEST_TIME 102 9703
TEST_TIME 103 9737
TEST_TIME 104 9654
TEST_TIME 105 9604
TEST_TIME 106 9660
TEST_TIME 107 9639
TEST_TIME 108 9591
TEST_TIME 109 9553
TEST_TIME 110 9514
TEST_TIME 111 9458
TEST_TIME 112 9394
TEST_TIME 113 9415
TEST_TIME 114 9509
TEST_TIME 115 9503
TEST_TIME 116 9500
TEST_TIME 117 9500
TEST_TIME 118 9504
TEST_TIME 119 9487
TEST_TIME 120 9473

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2022 16:39:33 to 10-10-2022 16:39:36.

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 1569574 1569574 0 0.00 0 40744 6 0 0
doc_list_biud 1569570 1569570 0 0.00 0 27161 29 0 0
sp_add_invoice_to_stock 5111 5031 80 1.56 26 177039 16947 0 80
sp_cancel_adding_invoice 5929 5929 0 0.00 4 66149 905 0 0
sp_cancel_client_order 1767 1767 0 0.00 3 11420 355 0 0
sp_cancel_customer_reserve 578594 578594 0 0.00 0 27018 62 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 18415 124 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 10150 128 0 0
sp_cancel_supplier_invoice 6578 6575 3 0.04 1 22522 816 0 3
sp_cancel_supplier_order 431 431 0 0.00 8 1002889 105668 0 0
sp_cancel_write_off 11350 11350 0 0.00 1 21923 115 0 0
sp_client_order 20756 20756 0 0.00 3 26765 356 0 0
sp_customer_reserve 468775 468775 0 0.00 0 79388 166 0 0
sp_fill_shopping_cart 32006 32006 0 0.00 1 12638 39 0 0
sp_fill_shopping_cart_clo_res 468762 468762 0 0.00 0 22643 13 0 0
sp_get_clo_for_invoice 5018 5018 0 0.00 0 24247 282 0 0
sp_kill_cost_storno 18374 18374 0 0.00 0 21893 60 0 0
sp_kill_qstorno_ret_qs2qd 1175544 1175544 0 0.00 0 27003 34 0 0
sp_kill_qty_storno 619673 619673 0 0.00 0 27161 73 0 0
sp_lock_selected_doc 20897 20897 0 0.00 0 7468 6 0 0
sp_make_cost_storno 28089 28089 0 0.00 0 19526 62 0 0
sp_multiply_rows_for_pdistr 28087 28087 0 0.00 0 23578 35 0 0
sp_multiply_rows_for_qdistr 20756 20756 0 0.00 0 12658 152 0 0
sp_pay_from_customer 7072 7072 0 0.00 4 25619 133 0 0
sp_pay_to_supplier 5988 5988 0 0.00 4 40754 144 0 0
sp_payment_common 13058 13058 0 0.00 1 23204 41 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 11412 333 0 0
sp_qd_handle_on_invoice_upd_sts 10946 10946 0 0.00 0 27160 341 0 0
sp_qd_handle_on_reserve_upd_sts 10010 10010 0 0.00 0 20782 62 0 0
sp_reserve_write_off 10011 10011 0 0.00 6 40771 195 0 0
sp_supplier_invoice 5673 5673 0 0.00 5 81073 3321 0 0
sp_supplier_order 5579 5579 0 0.00 5 53458 1241 0 0
srv_aggregate_perf_data 95 95 0 0.00 2924 265664 23090 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1365 159745 43676 0 0
srv_find_qd_qs_mism 1087547 1087547 0 0.00 0 12012 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 37 109975 1113 0 0
srv_make_money_saldo 824 824 0 0.00 1 5321 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 249868 369078 291527 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5031 5031 0 0.00 0 44503 191 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 0 5711 130 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 5091 18 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1366 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 635 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 2554 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 6921 36 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 31356 1656 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 922 8 0 0
t$perf-norm:sp_client_order 20756 20756 0 0.00 0 10406 20 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 3380 12 0 0
t$perf-norm:sp_pay_from_customer 7072 7072 0 0.00 0 28653 22 0 0
t$perf-norm:sp_pay_to_supplier 5988 5988 0 0.00 0 6178 12 0 0
t$perf-norm:sp_reserve_write_off 10011 10011 0 0.00 0 33346 20 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 7884 22 0 0
t$perf-norm:sp_supplier_order 5579 5579 0 0.00 0 40691 27 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 323 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 267 5 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 1320 24 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 13194 37 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10190 10030 160 1.57 0 6090 17 0 160
v_all_customers 37720 37720 0 0.00 0 27 1 0 0
v_all_suppliers 31384 31384 0 0.00 0 27 1 0 0
v_all_wares 41512 41512 0 0.00 0 147 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 4086 12 0 0
v_cancel_client_order 3534 3534 0 0.00 0 3844 5 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 5461 19 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 4146 12 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 5462 12 0 0
v_cancel_supplier_invoice 866 860 6 0.69 0 1592 15 0 6
v_cancel_supplier_order 862 862 0 0.00 0 2294 7 0 0
v_cancel_write_off 2012 2012 0 0.00 0 4230 16 0 0
v_max_id_clo_ord 5579 5579 0 0.00 0 424 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 11470 25 0 0
v_max_id_ord_sup 5671 5671 0 0.00 0 8709 6 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 959 3 0 0
v_max_non_paid_realizn 3725 3725 0 0.00 0 1395 4 0 0
v_min_id_clo_ord 5579 5579 0 0.00 0 5017 9 0 0
v_min_id_clo_res 993 993 0 0.00 0 1238 4 0 0
v_min_id_ord_sup 5671 5671 0 0.00 0 6975 10 0 0
v_min_non_paid_invoice 5986 5986 0 0.00 0 18703 30 0 0
v_min_non_paid_realizn 7072 7072 0 0.00 0 23195 39 0 0
v_our_firm 10486 10486 0 0.00 0 25 1 0 0
v_reserve_write_off 20020 20020 0 0.00 0 4067 4 0 0
x_lock_depdocs_on_canc_invoice 12074 12074 0 0.00 0 15696 122 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 30235 420 0 0
x_make_qstorno_1000_1200 5579 5579 0 0.00 2 53451 1165 0 0
x_make_qstorno_1200_2000 5673 5673 0 0.00 2 80703 3177 0 0
x_make_qstorno_2100_3300 468775 468775 0 0.00 0 79386 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-10-2022 16:39:37 to 10-10-2022 16:39:42.

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:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:34 27873 8192000000 9906757328 10216747008 979811248 10.682 100 87 174 0 1009248336 6478736 22958352
14:11:05 17679 8192000000 9940636208 10260766720 1023354048 11.104 100 87 174 0 1054404880 8211584 22839248
14:12:25 20342 8192000000 9979044464 10309083136 1060805888 11.464 100 82 164 0 1090441440 8746944 20888608
14:13:48 10933 8192000000 10031294464 10375864320 1116076080 11.990 100 80 160 0 1144785408 9704384 19004944
14:15:03 5080 8192000000 10080358704 10442092544 1169785360 12.495 100 72 143 0 1196914288 10597504 16531424
14:16:12 2155 8192000000 10127843760 10497728512 1219191728 12.954 100 77 154 0 1249314944 12988368 17134848
14:17:16 2064 8192000000 10161663776 10540793856 1255202240 13.286 100 82 162 0 1285719904 12741120 17776544
14:18:20 1666 8192000000 10202480336 10589437952 1293314560 13.634 100 81 161 0 1323301392 12424640 17562192
14:19:22 1672 8192000000 10222040496 10612252672 1306770912 13.757 100 86 172 3 1339482128 12895408 19815808
14:20:24 7849 8192000000 10223987792 10617761792 1311469472 13.799 100 44 86 0 1329403008 7883920 10049616
14:21:33 4698 8192000000 10199280384 10615926784 1314397552 13.826 100 22 44 0 1325692240 6440256 4854432
14:22:38 8951 8192000000 10204607856 10624786432 1323436544 13.908 100 19 38 0 1334049440 6397936 4214960
14:23:51 14322 8192000000 10214546048 10634756096 1329204544 13.960 100 19 38 0 1339455392 6075040 4175808
14:25:05 9965 8192000000 10235543920 10649808896 1332723056 13.992 100 56 110 0 1355963504 9143600 14096848
14:26:15 5922 8192000000 10233474304 10648358912 1330696880 13.973 100 49 95 1 1348731312 7086144 10948288
14:27:21 8711 8192000000 10208744000 10629005312 1297638368 13.674 98 16 28 0 1305794320 4554304 3601648
14:28:33 13141 8192000000 10184531776 10588114944 1217323840 12.937 93 14 29 0 1226045568 5244128 3477600
14:29:46 22373 8192000000 10164636944 10580119552 1246202768 13.203 97 42 83 0 1261936976 6156288 9577920
14:31:09 4144 8192000000 10137396256 10535849984 1219826480 12.960 97 31 60 2 1232779792 6213424 6739888
14:32:15 7848 8192000000 10145075504 10546184192 1241128560 13.157 99 30 60 1 1254266320 5488384 7649376
14:33:24 15757 8192000000 10165391040 10578219008 1267095360 13.395 99 21 41 0 1277387616 5485680 4806576
14:34:41 26077 8192000000 10177546512 10596503552 1292242704 13.625 100 15 30 1 1300378176 4528960 3606512
14:36:10 7542 8192000000 10200648416 10617827328 1307744000 13.766 100 24 46 0 1319043520 5250752 6048768
14:37:21 7828 8192000000 10208137312 10624159744 1314820864 13.830 100 26 53 0 1326501152 5207696 6472592
14:38:31 27030 8192000000 10245508512 10653999104 1339295664 14.051 100 36 74 0 1353479168 5961104 8222400
14:40:05 119293 8192000000 10253146208 10685816832 1358506688 14.224 100 24 47 0 1375369008 11299456 5562864
14:43:10 9957 8192000000 10284836464 10708475904 1374543344 14.368 100 41 80 1 1396922912 12977408 9402160
14:44:25 9782 8192000000 10294894752 10719481856 1385142080 14.463 100 42 80 1 1408531488 13639728 9749680
14:45:41 15079 8192000000 10315057248 10740670464 1401053152 14.604 100 51 100 0 1426998480 14458304 11487024
14:47:02 13361 8192000000 10333750320 10756493312 1401825552 14.611 99 52 104 0 1434319504 20530880 11963072
14:48:23 3815 8192000000 10340647344 10760556544 1401848384 14.611 99 73 144 0 1442850656 24331008 16671264
14:49:39 3394 8192000000 10340600208 10757353472 1394151136 14.543 99 71 137 0 1440398368 29618112 16629120
14:50:46 9767 8192000000 10335672464 10758336512 1394780080 14.548 100 66 128 1 1448168480 37336976 16051424
14:52:03 3498 8192000000 10325576496 10743472128 1376046992 14.381 99 62 123 0 1427684336 36398592 15238752
14:53:12 4617 8192000000 10316656656 10721370112 1374688848 14.369 100 79 155 2 1424543200 30461632 19392720
14:54:22 11630 8192000000 10270587952 10681761792 1316664256 13.846 96 53 105 2 1358319008 28350528 13304224
14:55:41 1363 8192000000 10309920032 10722365440 1368742928 14.316 100 68 135 0 1417171680 31194736 17234016
14:56:48 5476 8192000000 10342376752 10752512000 1391401120 14.518 100 73 145 0 1446199456 37131936 17666400
14:57:58 4866 8192000000 10339365568 10756263936 1392589136 14.529 100 60 121 1 1445503280 38151488 14762656
14:59:10 3524 8192000000 10347723104 10764054528 1396476720 14.564 100 66 129 0 1453119136 40612432 16029984
15:00:22 12668 8192000000 10355037744 10783838208 1401410352 14.608 100 48 96 0 1460623472 46346160 12866960
15:01:41 10484 8192000000 10316301520 10735661056 1365894256 14.290 99 32 64 0 1423612576 48257472 9460848
15:02:55 3899 8192000000 10358003088 10774216704 1377749392 14.396 99 59 118 0 1450661232 57347248 15564592
15:04:07 7201 8192000000 10382560672 10804961280 1408824320 14.673 100 55 110 1 1481112128 57672256 14615552
15:05:24 68495 8192000000 10362555328 10792468480 1397605232 14.574 99 42 84 0 1457905104 48648064 11651808
15:07:34 27550 8192000000 10368443360 10801651712 1410390336 14.687 100 39 76 1 1471811872 51412336 10009200
15:09:17 17315 8192000000 10338542848 10785177600 1382406048 14.438 100 22 42 2 1446238064 57337264 6494752
15:10:35 42544 8192000000 10339950960 10796826624 1393051312 14.533 100 19 38 0 1455283888 56947008 5285568
15:12:24 47727 8192000000 10300173104 10741133312 1360079696 14.238 98 9 18 0 1376564144 14569728 1914720
15:14:17 47443 8192000000 10294738064 10747138048 1368836464 14.317 99 19 37 0 1391209568 18374848 3998256
15:16:15 103174 8192000000 10301496192 10740908032 1353180640 14.176 97 15 30 0 1365509360 9553136 2775584
15:19:08 55315 8192000000 10304027984 10758942720 1380316912 14.419 99 20 40 0 1399777664 15404464 4056288
15:21:08 76088 8192000000 10314060736 10757566464 1386987392 14.479 99 33 63 4 1401374320 7036512 7350416
15:23:45 51829 8192000000 10313313936 10768375808 1404207792 14.632 100 33 65 2 1419033168 8073600 6751776
15:25:39 52017 8192000000 10332786704 10788339712 1417482368 14.750 100 27 54 0 1435556688 12099968 5974352
15:27:38 43258 8192000000 10333610336 10793672704 1410959488 14.692 99 20 41 0 1436361520 20108592 5293440
15:29:22 40024 8192000000 10325892304 10790858752 1413896752 14.719 100 34 68 0 1440310000 17806032 8607216
15:31:03 57204 8192000000 10290293904 10752716800 1359929216 14.237 97 12 24 0 1379392064 16455808 3007040
15:33:03 58482 8192000000 10285809536 10746490880 1366175056 14.293 98 16 31 1 1379381168 10105760 3100352
15:35:05 15287 8192000000 10285729392 10752741376 1386935664 14.479 100 13 23 0 1399330416 9957264 2437488
15:36:24 75644 8192000000 10258169680 10709372928 1302361920 13.717 94 8 16 0 1311684400 7854560 1467920
15:38:41 34505 8192000000 10267231664 10712793088 1303912240 13.731 95 14 27 0 1312447136 5719728 2815168
15:40:17 119885 8192000000 10269629232 10731483136 1321308384 13.889 97 9 18 1 1343774304 20500320 1965600
15:43:17 54209 8192000000 10265480912 10722938880 1321291696 13.888 97 16 32 0 1335159824 10390352 3477776
15:45:12 46727 8192000000 10263494912 10714935296 1325780464 13.929 97 16 32 0 1338677984 9069312 3828208
15:46:59 23775 8192000000 10267439424 10706522112 1326813984 13.938 98 31 60 0 1349403280 15543536 7045760
15:48:24 63873 8192000000 10262380944 10712813568 1313783616 13.820 97 17 34 0 1332349792 14584656 3981520
15:50:29 159744 8192000000 10247489680 10695811072 1289970736 13.604 95 28 56 0 1307872368 10542352 7359280
15:54:11 73300 8192000000 10268451664 10713001984 1333421264 13.998 98 26 51 0 1351907408 12803056 5683088
15:56:24 43295 8192000000 10271122224 10731540480 1349131280 14.140 99 26 52 0 1365611872 10693120 5787472
15:58:08 45364 8192000000 10254626048 10714230784 1339004848 14.048 98 17 34 0 1353547152 10547152 3995152
16:00:03 65389 8192000000 10256931968 10721882112 1345167024 14.104 99 14 29 0 1359423408 10613936 3642448
16:03:12 29234 8192000000 10245084144 10697551872 1304138880 13.733 96 28 54 1 1318281360 7574400 6568080
16:04:46 46602 8192000000 10235372272 10695131136 1338982496 14.048 99 9 18 0 1347802512 6950752 1869264
16:06:38 48678 8192000000 10268087856 10723831808 1351553312 14.161 99 17 34 1 1362829424 7054928 4221184
16:08:33 33064 8192000000 10301485856 10754822144 1390632304 14.512 100 13 26 0 1400062048 6575760 2853984
16:10:12 51520 8192000000 10299665824 10762510336 1386770192 14.477 99 24 48 1 1405704256 13315104 5618960
16:12:06 75665 8192000000 10291885712 10748973056 1354802944 14.191 97 12 24 0 1365722704 8808768 2110992
16:14:22 60657 8192000000 10303602624 10757582848 1387829392 14.486 99 23 44 0 1403060304 10080112 5150800
16:16:27 18265 8192000000 10312420480 10745028608 1371445760 14.340 99 61 122 0 1394783968 9657712 13680496
16:17:51 86005 8192000000 10298827968 10749681664 1326112288 13.932 96 22 41 1 1345936288 15856608 3967392
16:20:18 97052 8192000000 10268040096 10734903296 1323019792 13.904 96 10 20 0 1342279376 16927488 2332096
16:23:56 62095 8192000000 10262563056 10729848832 1346983088 14.120 99 16 32 0 1371939984 20937232 4019664
16:25:59 50768 8192000000 10250830624 10711093248 1353798880 14.182 100 6 12 0 1365449392 10337968 1312544
16:28:10 66540 8192000000 10260923344 10710315008 1343476032 14.089 98 15 30 0 1358937680 11849616 3612032
16:30:29 73610 8192000000 10256755504 10715455488 1314794928 13.830 96 17 34 0 1339631376 20708176 4128272
16:32:44 57428 8192000000 10254195856 10700828672 1323438096 13.908 97 18 36 2 1337509200 10567488 3503616
16:35:12 29945 8192000000 10266626720 10703699968 1337926000 14.039 99 26 48 1 1350535200 6876576 5732624
16:36:46 61513 8192000000 10272961936 10725482496 1359454592 14.232 100 28 56 0 1377612752 12042416 6115744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2022 16:39:44 to 10-10-2022 16:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2022 16:40:00 to 10-10-2022 16:40:02.

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          243010
MON$OLDEST_ACTIVE               244735
MON$OLDEST_SNAPSHOT             244735
MON$NEXT_TRANSACTION            244735
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                       2050144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:130: 
:131: mscs-test-161.budzdorov.ru    Mon Oct 10 16:38:40 2022
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:133: 
:134: 
:135: mscs-test-161.budzdorov.ru    Mon Oct 10 16:38:41 2022
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Mon Oct 10 16:38:41 2022
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Mon Oct 10 16:38:42 2022
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:145: 
--- end of diff output ---

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

10.10.22 16:40:03. 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-10-10 16:40:06 No severe PSQL-related problems occured
10.10.22 16:40:07. End of report.