Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.07.2022 19: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 15840952 kB
MemAvailable 30988120 kB
Buffers 0 kB
Cached 15847272 kB
SwapCached 2512 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-07-29 19:38:30
DTS_END                         2022-07-29 19:38:30.1120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35284
TRN_ID                          246411
ATT_ID                          682
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: 9448.72 20447 1133846
customer order: creation 178.22 358 21386
customer order: refuse 15.99 330 1919
order to supplier: creation 46.92 1213 5630
order to supplier: removal 3.44 99297 413
invoice (draft): creation 47.34 3616 5681
invoice (draft): removal 53.41 766 6409
invoice accept: apply 42.86 19253 5143
invoice accept: cancel 48.66 857 5839
customer reserve: creation 4003.63 189 480436
customer reserve: removal 4695.76 56 563491
realization accept: apply 85.78 188 10293
realization accept: cancel 92.38 114 11085
payment from customer: creation 61.20 120 7344
payment from customer: removal 6.60 91 792
payment to supplier: creation 50.48 140 6058
payment to supplier: removal 3.66 75 439
service: total inventory turnovers 4.82 1119 578
service: total monetary turnovers 6.66 37 799
service: aggregate perf. data 0.89 17788 107
service: refresh index statistics 0.03 263323 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-07-2022 19:39:37 to 29-07-2022 19:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4654
TEST_TIME 2 3704
TEST_TIME 3 4275
TEST_TIME 4 4349
TEST_TIME 5 4373
TEST_TIME 6 4735
TEST_TIME 7 5711
TEST_TIME 8 5994
TEST_TIME 9 6525
TEST_TIME 10 6562
TEST_TIME 11 6489
TEST_TIME 12 6434
TEST_TIME 13 6424
TEST_TIME 14 6474
TEST_TIME 15 6514
TEST_TIME 16 6916
TEST_TIME 17 7226
TEST_TIME 18 7561
TEST_TIME 19 7810
TEST_TIME 20 7856
TEST_TIME 21 8105
TEST_TIME 22 8302
TEST_TIME 23 8675
TEST_TIME 24 8815
TEST_TIME 25 8765
TEST_TIME 26 9011
TEST_TIME 27 9112
TEST_TIME 28 9074
TEST_TIME 29 9132
TEST_TIME 30 9418
TEST_TIME 31 9683
TEST_TIME 32 9681
TEST_TIME 33 10001
TEST_TIME 34 10481
TEST_TIME 35 10764
TEST_TIME 36 10591
TEST_TIME 37 10514
TEST_TIME 38 10517
TEST_TIME 39 10273
TEST_TIME 40 10283
TEST_TIME 41 10219
TEST_TIME 42 10257
TEST_TIME 43 10294
TEST_TIME 44 10166
TEST_TIME 45 10068
TEST_TIME 46 10028
TEST_TIME 47 9939
TEST_TIME 48 9892
TEST_TIME 49 10323
TEST_TIME 50 10166
TEST_TIME 51 10127
TEST_TIME 52 9992
TEST_TIME 53 9969
TEST_TIME 54 9976
TEST_TIME 55 9957
TEST_TIME 56 9888
TEST_TIME 57 9854
TEST_TIME 58 9793
TEST_TIME 59 9676
TEST_TIME 60 9656
TEST_TIME 61 9608
TEST_TIME 62 9651
TEST_TIME 63 9680
TEST_TIME 64 9706
TEST_TIME 65 9641
TEST_TIME 66 9678
TEST_TIME 67 9858
TEST_TIME 68 9830
TEST_TIME 69 9889
TEST_TIME 70 9899
TEST_TIME 71 9899
TEST_TIME 72 9896
TEST_TIME 73 9975
TEST_TIME 74 9961
TEST_TIME 75 10065
TEST_TIME 76 9951
TEST_TIME 77 9890
TEST_TIME 78 9827
TEST_TIME 79 9752
TEST_TIME 80 9799
TEST_TIME 81 9732
TEST_TIME 82 9681
TEST_TIME 83 9654
TEST_TIME 84 9624
TEST_TIME 85 9567
TEST_TIME 86 9589
TEST_TIME 87 9539
TEST_TIME 88 9500
TEST_TIME 89 9518
TEST_TIME 90 9481
TEST_TIME 91 9493
TEST_TIME 92 9478
TEST_TIME 93 9467
TEST_TIME 94 9503
TEST_TIME 95 9507
TEST_TIME 96 9517
TEST_TIME 97 9623
TEST_TIME 98 9655
TEST_TIME 99 9635
TEST_TIME 100 9718
TEST_TIME 101 9674
TEST_TIME 102 9704
TEST_TIME 103 9698
TEST_TIME 104 9635
TEST_TIME 105 9627
TEST_TIME 106 9633
TEST_TIME 107 9596
TEST_TIME 108 9578
TEST_TIME 109 9552
TEST_TIME 110 9518
TEST_TIME 111 9475
TEST_TIME 112 9452
TEST_TIME 113 9400
TEST_TIME 114 9467
TEST_TIME 115 9464
TEST_TIME 116 9464
TEST_TIME 117 9472
TEST_TIME 118 9491
TEST_TIME 119 9510
TEST_TIME 120 9453

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-07-2022 19:39:41 to 29-07-2022 19:39:44.

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 1578823 1578823 0 0.00 0 50150 7 0 0
doc_list_biud 1578822 1578822 0 0.00 0 51562 26 0 0
sp_add_invoice_to_stock 5262 5143 119 2.26 35 169874 19253 0 119
sp_cancel_adding_invoice 5839 5839 0 0.00 4 68170 857 0 0
sp_cancel_client_order 1919 1919 0 0.00 4 13923 331 0 0
sp_cancel_customer_reserve 563491 563491 0 0.00 0 27991 57 0 0
sp_cancel_pay_from_customer 792 792 0 0.00 3 9038 91 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 2 3910 75 0 0
sp_cancel_supplier_invoice 6413 6409 4 0.06 1 51568 766 0 4
sp_cancel_supplier_order 413 413 0 0.00 6 970523 99297 0 0
sp_cancel_write_off 11085 11085 0 0.00 1 35879 115 0 0
sp_client_order 21386 21386 0 0.00 3 31183 358 0 0
sp_customer_reserve 480436 480436 0 0.00 0 135074 190 0 0
sp_fill_shopping_cart 32688 32688 0 0.00 1 18204 37 0 0
sp_fill_shopping_cart_clo_res 480432 480432 0 0.00 0 22248 13 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 22422 286 0 0
sp_kill_cost_storno 18155 18155 0 0.00 0 22189 58 0 0
sp_kill_qstorno_ret_qs2qd 1144889 1144889 0 0.00 0 51559 30 0 0
sp_kill_qty_storno 604586 604586 0 0.00 0 51561 67 0 0
sp_lock_selected_doc 21651 21651 0 0.00 0 5546 5 0 0
sp_make_cost_storno 28832 28832 0 0.00 0 49941 57 0 0
sp_multiply_rows_for_pdistr 28832 28832 0 0.00 0 13374 27 0 0
sp_multiply_rows_for_qdistr 21386 21386 0 0.00 0 11555 146 0 0
sp_pay_from_customer 7344 7344 0 0.00 4 13798 120 0 0
sp_pay_to_supplier 6058 6058 0 0.00 4 50276 140 0 0
sp_payment_common 13402 13402 0 0.00 1 13696 37 0 0
sp_qd_handle_on_cancel_clo 1919 1919 0 0.00 0 13917 308 0 0
sp_qd_handle_on_invoice_upd_sts 10976 10976 0 0.00 0 30124 352 0 0
sp_qd_handle_on_reserve_upd_sts 10293 10293 0 0.00 0 22894 66 0 0
sp_reserve_write_off 10293 10293 0 0.00 6 22927 189 0 0
sp_supplier_invoice 5681 5681 0 0.00 6 85574 3617 0 0
sp_supplier_order 5630 5630 0 0.00 5 34311 1213 0 0
srv_aggregate_perf_data 107 107 0 0.00 2707 134119 17788 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1198 157783 36701 0 0
srv_find_qd_qs_mism 1084393 1084393 0 0.00 0 35791 6 0 0
srv_make_invnt_saldo 578 578 0 0.00 45 89235 1119 0 0
srv_make_money_saldo 799 799 0 0.00 1 3833 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 188682 340016 263324 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5143 5143 0 0.00 0 7885 167 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 5555 89 0 0
t$perf-norm:sp_cancel_client_order 1919 1919 0 0.00 0 698 10 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1693 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 792 792 0 0.00 0 1169 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1810 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1512 29 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 29838 1837 0 0
t$perf-norm:sp_cancel_write_off 1087 1087 0 0.00 0 1029 5 0 0
t$perf-norm:sp_client_order 21386 21386 0 0.00 0 13135 19 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 3945 18 0 0
t$perf-norm:sp_pay_from_customer 7344 7344 0 0.00 0 10032 11 0 0
t$perf-norm:sp_pay_to_supplier 6058 6058 0 0.00 0 9805 12 0 0
t$perf-norm:sp_reserve_write_off 10293 10293 0 0.00 0 10349 13 0 0
t$perf-norm:sp_supplier_invoice 5681 5681 0 0.00 0 5508 24 0 0
t$perf-norm:sp_supplier_order 5630 5630 0 0.00 0 6628 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 222 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 556 8 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 726 20 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 3482 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10512 10274 238 2.26 0 8780 19 0 238
v_all_customers 38954 38954 0 0.00 0 117 1 0 0
v_all_suppliers 31282 31282 0 0.00 0 26 1 0 0
v_all_wares 42772 42772 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 461 6 0 0
v_cancel_client_order 3838 3838 0 0.00 0 1447 4 0 0
v_cancel_customer_prepayment 1584 1584 0 0.00 0 4913 10 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 4910 10 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 3902 11 0 0
v_cancel_supplier_invoice 836 828 8 0.95 0 589 14 0 8
v_cancel_supplier_order 826 826 0 0.00 0 1510 6 0 0
v_cancel_write_off 2174 2174 0 0.00 0 3624 19 0 0
v_max_id_clo_ord 5630 5630 0 0.00 0 1791 2 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 1994 16 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 3173 3 0 0
v_max_non_paid_invoice 1719 1719 0 0.00 0 709 4 0 0
v_max_non_paid_realizn 3853 3853 0 0.00 0 2828 4 0 0
v_min_id_clo_ord 5630 5630 0 0.00 0 5960 8 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 1257 8 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 6200 7 0 0
v_min_non_paid_invoice 6058 6058 0 0.00 0 13686 24 0 0
v_min_non_paid_realizn 7344 7344 0 0.00 0 13607 36 0 0
v_our_firm 10800 10800 0 0.00 0 28 1 0 0
v_reserve_write_off 20586 20586 0 0.00 0 3796 5 0 0
x_lock_depdocs_on_canc_invoice 11834 11834 0 0.00 0 22313 130 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 5185 386 0 0
x_make_qstorno_1000_1200 5630 5630 0 0.00 1 34304 1131 0 0
x_make_qstorno_1200_2000 5681 5681 0 0.00 3 85449 3488 0 0
x_make_qstorno_2100_3300 480436 480436 0 0.00 0 135073 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-07-2022 19:39:45 to 29-07-2022 19:39:49.

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
17:08:33 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:35 28703 8192000000 9907644912 10218737664 989456816 10.776 100 86 172 0 1018842288 6097184 23288288
17:11:08 18862 8192000000 9943894240 10264059904 1027403536 11.143 100 92 184 0 1059831040 8416048 24011456
17:12:35 13398 8192000000 9967905264 10297434112 1052839712 11.388 100 81 162 0 1083028400 9876320 20312368
17:13:51 6596 8192000000 10031259392 10376388608 1115686224 11.986 100 81 162 1 1145771088 10860528 19224336
17:15:00 7350 8192000000 10077171472 10434224128 1158456208 12.389 100 81 159 0 1188561296 11953488 18151600
17:16:10 3822 8192000000 10114969600 10484207616 1200818656 12.784 100 71 137 0 1227823616 11857344 15147616
17:17:17 977 8192000000 10138963920 10512601088 1231280112 13.066 100 81 162 2 1262366448 13423488 17662848
17:18:20 1549 8192000000 10185048032 10563014656 1264299952 13.369 100 88 176 1 1302277728 17962144 20015632
17:19:23 912 8192000000 10196301232 10581090304 1285130272 13.560 100 80 160 1 1314704240 11919488 17654480
17:20:26 14437 8192000000 10190298064 10592673792 1294023408 13.641 100 52 102 0 1316200320 11100496 11076416
17:21:49 2221 8192000000 10197041888 10604302336 1306575408 13.755 100 37 72 1 1323647664 8701152 8371104
17:22:53 15115 8192000000 10195038560 10612854784 1315414368 13.835 100 8 16 0 1322478672 4915856 2148448
17:24:10 8686 8192000000 10198107440 10617298944 1318418592 13.862 100 27 56 0 1330873536 6256768 6198176
17:25:19 55456 8192000000 10214802720 10634924032 1321510944 13.890 100 18 36 0 1330586192 5030960 4044288
17:27:17 7013 8192000000 10221056768 10640437248 1327172032 13.942 100 22 43 0 1338321456 6721632 4427792
17:28:30 11877 8192000000 10202997168 10619023360 1285544192 13.564 97 20 41 1 1298972320 9316400 4111728
17:29:43 51453 8192000000 10168962096 10577494016 1239585968 13.142 96 46 92 0 1255869920 5078112 11205840
17:31:36 6901 8192000000 10141101920 10535030784 1230773888 13.061 99 38 74 1 1245150448 5183872 9192688
17:32:43 5990 8192000000 10162116416 10565382144 1265849504 13.384 100 27 52 1 1277652272 5398608 6404160
17:33:51 10155 8192000000 10158814480 10560991232 1263663936 13.364 100 21 40 0 1273743744 5294016 4785792
17:35:04 32801 8192000000 10170200576 10577952768 1276065344 13.477 100 36 73 0 1291435488 7074672 8295472
17:36:37 5987 8192000000 10185160320 10597732352 1292888832 13.631 100 23 46 0 1304325952 5981632 5455488
17:37:44 6323 8192000000 10195395760 10608996352 1302281712 13.716 100 27 54 0 1314504640 5408864 6814064
17:38:56 5431 8192000000 10236963040 10645245952 1333392544 13.998 100 44 85 0 1349001568 5736096 9872928
17:40:11 90657 8192000000 10260021408 10678517760 1349904528 14.147 100 42 84 0 1367297040 7560288 9832224
17:42:50 27944 8192000000 10266547296 10689114112 1357225968 14.212 99 42 81 0 1376690960 10597584 8867408
17:44:25 16620 8192000000 10284643072 10710089728 1377286416 14.392 100 42 84 0 1405690704 18657280 9747008
17:45:46 14969 8192000000 10309060352 10730848256 1385048512 14.462 100 56 112 0 1416310000 18705248 12556240
17:47:04 5207 8192000000 10309789296 10730229760 1386231776 14.472 100 54 108 0 1417569840 18205152 13132912
17:48:14 8132 8192000000 10308850960 10732249088 1389470368 14.501 100 62 121 0 1422468960 18422496 14576096
17:49:26 10425 8192000000 10313748768 10742587392 1393392512 14.536 100 38 74 2 1420698944 17367376 9939056
17:50:39 3487 8192000000 10339302992 10766028800 1409824976 14.682 100 56 112 1 1448058080 24862912 13370192
17:51:48 5446 8192000000 10330303840 10755334144 1399757008 14.593 100 56 110 1 1438826016 25644064 13424944
17:53:06 3880 8192000000 10292100256 10707021824 1354038800 14.184 100 64 128 0 1401164608 31796224 15329584
17:54:24 3776 8192000000 10329663456 10734727168 1378965984 14.407 100 91 179 0 1433540144 33264672 21309488
17:55:34 1196 8192000000 10317387136 10718957568 1361970416 14.255 100 89 178 0 1423143072 38793488 22379168
17:56:40 16497 8192000000 10295188512 10713899008 1348444032 14.133 99 38 77 1 1399057488 39695872 10917584
17:58:00 2133 8192000000 10338934048 10747363328 1374603120 14.368 100 71 142 0 1439042912 47318224 17121568
17:59:09 2830 8192000000 10331129312 10736181248 1361627120 14.252 100 74 149 0 1429239152 48395456 19216576
18:00:21 1681 8192000000 10335749872 10734731264 1358695568 14.226 99 95 186 4 1432751248 49815632 24240048
18:01:26 15076 8192000000 10316999712 10733449216 1372811040 14.352 100 63 124 4 1423113440 34724304 15578096
18:03:07 4934 8192000000 10313790512 10730745856 1368945360 14.318 100 58 116 1 1419958592 36117376 14895856
18:04:34 3755 8192000000 10329758336 10759397376 1383032832 14.444 100 45 90 1 1436653024 42214928 11405264
18:05:49 12787 8192000000 10321370960 10753724416 1372220576 14.347 99 30 58 1 1419203776 39485504 7497696
18:07:05 23297 8192000000 10320737040 10747027456 1366649056 14.297 100 30 59 0 1419713024 44877600 8186368
18:08:36 30096 8192000000 10323656720 10759315456 1379541888 14.412 100 29 58 1 1434356176 47061968 7752320
18:10:08 27803 8192000000 10327770352 10766295040 1375212000 14.374 100 17 33 0 1430065392 50500272 4353120
18:12:12 32690 8192000000 10324148416 10774806528 1400430352 14.599 100 12 24 1 1422707360 19325840 2951168
18:13:49 24730 8192000000 10316830416 10748735488 1386702080 14.476 99 57 109 1 1405419328 6811712 11905536
18:15:16 118474 8192000000 10289611296 10737852416 1359203232 14.230 98 19 38 1 1374329200 11143408 3982560
18:18:15 71696 8192000000 10298500448 10742374400 1364778032 14.280 98 26 52 0 1381778880 10865952 6134896
18:20:27 11758 8192000000 10317888368 10754539520 1393963360 14.541 100 50 98 1 1416259184 11397008 10898816
18:21:42 55034 8192000000 10292341712 10748342272 1382868112 14.442 99 7 13 0 1392651440 8513968 1269360
18:23:39 3683 8192000000 10327689392 10768621568 1404625200 14.636 100 53 102 2 1425516512 9578560 11312752
18:24:45 34796 8192000000 10323041456 10776002560 1399764208 14.593 99 20 38 0 1417426176 12313168 5348800
18:26:22 38517 8192000000 10345903184 10793160704 1396987168 14.568 99 54 106 1 1434039376 24548992 12503216
18:28:01 64330 8192000000 10304293168 10769842176 1386414416 14.474 99 16 32 0 1410027856 19629504 3983936
18:30:08 157783 8192000000 10286891744 10757177344 1380118224 14.418 99 29 55 4 1394206304 7144608 6943472
18:33:53 29552 8192000000 10292806592 10760081408 1398606576 14.583 100 14 28 1 1410842544 9188672 3047296
18:35:23 50105 8192000000 10268305552 10733010944 1347145712 14.122 97 17 34 0 1359175856 8093968 3936176
18:38:12 87073 8192000000 10272655552 10741018624 1331442368 13.980 96 14 26 0 1346260608 11944960 2873280
18:40:43 35549 8192000000 10283787616 10747359232 1375988592 14.381 99 13 26 1 1394563392 15777872 2796928
18:42:24 26291 8192000000 10263961680 10724110336 1347119456 14.122 98 12 24 0 1358490624 8695664 2675504
18:43:53 36547 8192000000 10273754032 10713907200 1311314464 13.798 96 37 74 0 1336094624 15997264 8782896
18:45:39 56545 8192000000 10253865312 10700316672 1305065488 13.741 96 12 22 0 1317892000 10448224 2378288
18:47:36 41522 8192000000 10255985104 10708566016 1332995920 13.994 98 26 52 0 1351697104 13157264 5543920
18:49:19 32924 8192000000 10245087328 10689720320 1306958896 13.758 97 25 50 1 1323292880 11179872 5154112
18:50:55 13750 8192000000 10258542832 10697658368 1340611392 14.063 99 33 66 0 1362190080 14215776 7362912
18:52:11 45670 8192000000 10256709056 10701508608 1317576336 13.855 97 16 32 0 1331460288 10490992 3392960
18:54:04 99304 8192000000 10253234448 10703151104 1339861344 14.056 99 9 18 0 1353135328 10982576 2291408
18:57:08 1957 8192000000 10256432960 10707283968 1342408080 14.079 99 29 58 1 1361538464 12602672 6527712
18:58:12 41406 8192000000 10247198384 10706190336 1348408848 14.133 100 20 41 1 1362775680 9261968 5104864
18:59:56 45142 8192000000 10244095344 10697412608 1335238960 14.014 99 16 32 1 1346463056 7096240 4127856
19:01:42 55098 8192000000 10258789152 10712039424 1335872848 14.020 98 29 58 0 1356394848 13997648 6524352
19:03:38 41648 8192000000 10271248656 10723958784 1354624192 14.189 99 26 51 0 1373555376 12428464 6502720
19:05:20 52039 8192000000 10278695168 10735239168 1380077248 14.417 100 21 40 1 1393322096 8820016 4424832
19:08:07 54916 8192000000 10271509408 10728050688 1353807296 14.182 98 13 26 1 1364976288 8666640 2502352
19:10:02 45725 8192000000 10283764272 10736721920 1385820368 14.469 100 23 44 2 1398272000 7700032 4751600
19:11:51 19932 8192000000 10300270448 10751221760 1388406496 14.492 100 36 72 0 1407766752 11474864 7885392
19:13:20 45644 8192000000 10288942400 10740695040 1336927824 14.030 96 26 52 0 1354928352 12078880 5921648
19:15:31 66428 8192000000 10296115872 10755727360 1382383376 14.438 100 17 34 0 1401171808 15624640 3163792
19:17:46 48246 8192000000 10305393200 10762780672 1395771152 14.557 100 21 40 1 1414054176 13810800 4472224
19:19:34 18527 8192000000 10305092448 10755596288 1395686144 14.557 100 29 58 0 1411300256 9159488 6454624
19:21:00 145931 8192000000 10296294528 10752159744 1373421184 14.358 98 26 52 0 1387663216 8399472 5842560
19:24:26 61734 8192000000 10268574848 10735128576 1337149936 14.032 96 8 16 0 1349370864 10222256 1998672
19:26:29 47513 8192000000 10272204960 10739081216 1382846016 14.442 100 10 20 0 1391663424 6531232 2286176
19:28:16 57993 8192000000 10253654720 10711138304 1307765184 13.766 95 10 19 0 1317729008 7697712 2266112
19:30:20 41275 8192000000 10268157200 10735403008 1365343680 14.285 100 6 12 0 1373453632 6897584 1212368
19:32:44 21428 8192000000 10261145584 10708975616 1332168736 13.987 98 20 40 0 1345327936 8938432 4220768
19:34:06 27404 8192000000 10261397776 10709807104 1335884464 14.020 98 27 54 1 1351183744 9484640 5814640
19:35:34 10780 8192000000 10263167296 10703376384 1318699440 13.865 97 40 80 0 1336161408 8951344 8510624
19:36:47 75099 8192000000 10282624800 10727149568 1338331440 14.042 98 17 33 0 1356013104 13983808 3697856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-07-2022 19:39:51 to 29-07-2022 19:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-07-2022 19:40:07 to 29-07-2022 19:40:09.

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          244890
MON$OLDEST_ACTIVE               246594
MON$OLDEST_SNAPSHOT             246594
MON$NEXT_TRANSACTION            246594
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                       2044448
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:447: 
:448: mscs-test-161.budzdorov.ru    Fri Jul 29 19:38:41 2022
:449:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:450: 
:451: 
:452: mscs-test-161.budzdorov.ru    Fri Jul 29 19:38:41 2022
:453:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:454: 
:455: 
:456: mscs-test-161.budzdorov.ru    Fri Jul 29 19:38:42 2022
:457:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:458: 
:459: 
:460: mscs-test-161.budzdorov.ru    Fri Jul 29 19:38:42 2022
:461:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:462: 
--- end of diff output ---

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

29.07.22 19:40:10. 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-07-29 19:40:12 No severe PSQL-related problems occured
29.07.22 19:40:14. End of report.