Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.09.2021 01: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 16130060 kB
MemAvailable 30779464 kB
Buffers 0 kB
Cached 15543472 kB
SwapCached 488 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-09-20 01:37:52
DTS_END                         2021-09-20 01:37:52.3790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51650
TRN_ID                          244451
ATT_ID                          713
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: 9435.84 16417 1132301
customer order: creation 181.26 374 21751
customer order: refuse 15.69 368 1883
order to supplier: creation 48.85 1231 5862
order to supplier: removal 3.57 88503 428
invoice (draft): creation 47.58 3329 5710
invoice (draft): removal 53.63 750 6435
invoice accept: apply 43.27 18232 5192
invoice accept: cancel 48.30 720 5796
customer reserve: creation 3942.56 182 473107
customer reserve: removal 4739.11 51 568693
realization accept: apply 87.46 174 10495
realization accept: cancel 87.43 103 10492
payment from customer: creation 63.65 106 7638
payment from customer: removal 6.28 84 754
payment to supplier: creation 51.06 134 6127
payment to supplier: removal 3.78 66 454
service: total inventory turnovers 4.83 979 579
service: total monetary turnovers 6.66 44 799
service: aggregate perf. data 0.85 20698 102
service: refresh index statistics 0.03 192221 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-09-2021 01:38:38 to 20-09-2021 01:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6777
TEST_TIME 2 6349
TEST_TIME 3 5857
TEST_TIME 4 6115
TEST_TIME 5 6273
TEST_TIME 6 6072
TEST_TIME 7 5984
TEST_TIME 8 5939
TEST_TIME 9 5702
TEST_TIME 10 5792
TEST_TIME 11 6346
TEST_TIME 12 6358
TEST_TIME 13 6354
TEST_TIME 14 6595
TEST_TIME 15 6881
TEST_TIME 16 7174
TEST_TIME 17 7639
TEST_TIME 18 8056
TEST_TIME 19 8436
TEST_TIME 20 8777
TEST_TIME 21 9136
TEST_TIME 22 9197
TEST_TIME 23 10181
TEST_TIME 24 10277
TEST_TIME 25 10560
TEST_TIME 26 10467
TEST_TIME 27 10746
TEST_TIME 28 10504
TEST_TIME 29 10223
TEST_TIME 30 10211
TEST_TIME 31 10181
TEST_TIME 32 10233
TEST_TIME 33 9995
TEST_TIME 34 9976
TEST_TIME 35 9919
TEST_TIME 36 9898
TEST_TIME 37 10109
TEST_TIME 38 9993
TEST_TIME 39 9878
TEST_TIME 40 9819
TEST_TIME 41 9780
TEST_TIME 42 9639
TEST_TIME 43 9626
TEST_TIME 44 9492
TEST_TIME 45 9507
TEST_TIME 46 9446
TEST_TIME 47 9528
TEST_TIME 48 9468
TEST_TIME 49 9412
TEST_TIME 50 9476
TEST_TIME 51 9493
TEST_TIME 52 9626
TEST_TIME 53 9626
TEST_TIME 54 9670
TEST_TIME 55 9718
TEST_TIME 56 9704
TEST_TIME 57 9749
TEST_TIME 58 10081
TEST_TIME 59 10000
TEST_TIME 60 10109
TEST_TIME 61 9997
TEST_TIME 62 9856
TEST_TIME 63 9835
TEST_TIME 64 9784
TEST_TIME 65 9743
TEST_TIME 66 9737
TEST_TIME 67 9659
TEST_TIME 68 9621
TEST_TIME 69 9599
TEST_TIME 70 9538
TEST_TIME 71 9499
TEST_TIME 72 9467
TEST_TIME 73 9533
TEST_TIME 74 9520
TEST_TIME 75 9490
TEST_TIME 76 9609
TEST_TIME 77 9618
TEST_TIME 78 9575
TEST_TIME 79 9614
TEST_TIME 80 9684
TEST_TIME 81 9676
TEST_TIME 82 9685
TEST_TIME 83 9682
TEST_TIME 84 9600
TEST_TIME 85 9566
TEST_TIME 86 9502
TEST_TIME 87 9545
TEST_TIME 88 9488
TEST_TIME 89 9423
TEST_TIME 90 9400
TEST_TIME 91 9382
TEST_TIME 92 9406
TEST_TIME 93 9393
TEST_TIME 94 9402
TEST_TIME 95 9406
TEST_TIME 96 9371
TEST_TIME 97 9410
TEST_TIME 98 9450
TEST_TIME 99 9498
TEST_TIME 100 9516
TEST_TIME 101 9479
TEST_TIME 102 9569
TEST_TIME 103 9528
TEST_TIME 104 9517
TEST_TIME 105 9581
TEST_TIME 106 9568
TEST_TIME 107 9506
TEST_TIME 108 9468
TEST_TIME 109 9458
TEST_TIME 110 9410
TEST_TIME 111 9368
TEST_TIME 112 9330
TEST_TIME 113 9342
TEST_TIME 114 9439
TEST_TIME 115 9415
TEST_TIME 116 9409
TEST_TIME 117 9470
TEST_TIME 118 9453
TEST_TIME 119 9429
TEST_TIME 120 9445

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-09-2021 01:38:41 to 20-09-2021 01:38:45.

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 1573616 1573616 0 0.00 0 20334 6 0 0
doc_list_biud 1573616 1573616 0 0.00 0 23801 24 0 0
sp_add_invoice_to_stock 5348 5192 156 2.91 14 149314 18233 0 156
sp_cancel_adding_invoice 5796 5796 0 0.00 5 47653 720 0 0
sp_cancel_client_order 1883 1883 0 0.00 4 13925 369 0 0
sp_cancel_customer_reserve 568693 568693 0 0.00 1 23556 52 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 3 6236 84 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 8236 67 0 0
sp_cancel_supplier_invoice 6444 6435 9 0.13 2 21108 751 0 9
sp_cancel_supplier_order 428 428 0 0.00 7 1149516 88504 0 0
sp_cancel_write_off 10492 10492 0 0.00 1 14075 104 0 0
sp_client_order 21751 21751 0 0.00 4 25605 374 0 0
sp_customer_reserve 473107 473107 0 0.00 0 66100 182 0 0
sp_fill_shopping_cart 33306 33306 0 0.00 1 18391 37 0 0
sp_fill_shopping_cart_clo_res 473103 473103 0 0.00 0 22356 14 0 0
sp_get_clo_for_invoice 5171 5171 0 0.00 0 15370 296 0 0
sp_kill_cost_storno 17496 17496 0 0.00 0 5439 47 0 0
sp_kill_qstorno_ret_qs2qd 1154741 1154741 0 0.00 0 23542 29 0 0
sp_kill_qty_storno 609391 609391 0 0.00 0 23783 62 0 0
sp_lock_selected_doc 21807 21807 0 0.00 0 9774 6 0 0
sp_make_cost_storno 29429 29429 0 0.00 0 20329 56 0 0
sp_multiply_rows_for_pdistr 29429 29429 0 0.00 0 10789 27 0 0
sp_multiply_rows_for_qdistr 21751 21751 0 0.00 0 19217 171 0 0
sp_pay_from_customer 7638 7638 0 0.00 4 16356 106 0 0
sp_pay_to_supplier 6127 6127 0 0.00 5 10806 135 0 0
sp_payment_common 13765 13765 0 0.00 1 16346 33 0 0
sp_qd_handle_on_cancel_clo 1883 1883 0 0.00 0 13902 329 0 0
sp_qd_handle_on_invoice_upd_sts 10965 10965 0 0.00 0 23781 291 0 0
sp_qd_handle_on_reserve_upd_sts 10495 10495 0 0.00 0 19973 59 0 0
sp_reserve_write_off 10495 10495 0 0.00 6 19993 174 0 0
sp_supplier_invoice 5710 5710 0 0.00 7 86210 3329 0 0
sp_supplier_order 5862 5862 0 0.00 6 44949 1231 0 0
srv_aggregate_perf_data 102 102 0 0.00 2419 249281 20698 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2056 133126 44287 0 0
srv_find_qd_qs_mism 1084027 1084027 0 0.00 0 19587 6 0 0
srv_make_invnt_saldo 579 579 0 0.00 48 33180 979 0 0
srv_make_money_saldo 799 799 0 0.00 1 16700 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 121970 284630 192221 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5192 5192 0 0.00 0 9468 148 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 1 3431 93 0 0
t$perf-norm:sp_cancel_client_order 1883 1883 0 0.00 0 2982 13 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 5262 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 1100 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 2471 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 972 13 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 24701 1391 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 3278 13 0 0
t$perf-norm:sp_client_order 21751 21751 0 0.00 0 9688 15 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 3925 12 0 0
t$perf-norm:sp_pay_from_customer 7638 7638 0 0.00 0 14712 14 0 0
t$perf-norm:sp_pay_to_supplier 6127 6127 0 0.00 0 5266 8 0 0
t$perf-norm:sp_reserve_write_off 10495 10495 0 0.00 0 14358 10 0 0
t$perf-norm:sp_supplier_invoice 5710 5710 0 0.00 0 9371 21 0 0
t$perf-norm:sp_supplier_order 5862 5862 0 0.00 0 3927 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1193 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 568 10 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 1302 19 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 6794 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10650 10338 312 2.92 0 8694 20 0 312
v_all_customers 39270 39270 0 0.00 0 17 1 0 0
v_all_suppliers 31678 31678 0 0.00 0 91 1 0 0
v_all_wares 43502 43502 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 4642 19 0 0
v_cancel_client_order 3766 3766 0 0.00 0 6864 7 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 1854 7 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 1959 5 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 541 3 0 0
v_cancel_supplier_invoice 814 796 18 2.21 0 3708 26 0 18
v_cancel_supplier_order 856 856 0 0.00 0 704 5 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2015 13 0 0
v_max_id_clo_ord 5862 5862 0 0.00 0 1209 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 2858 17 0 0
v_max_id_ord_sup 5692 5692 0 0.00 0 3905 4 0 0
v_max_non_paid_invoice 1842 1842 0 0.00 0 3315 4 0 0
v_max_non_paid_realizn 4106 4106 0 0.00 0 5625 6 0 0
v_min_id_clo_ord 5862 5862 0 0.00 0 4204 6 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 4644 9 0 0
v_min_id_ord_sup 5692 5692 0 0.00 0 7518 12 0 0
v_min_non_paid_invoice 6127 6127 0 0.00 0 6708 18 0 0
v_min_non_paid_realizn 7638 7638 0 0.00 0 16340 19 0 0
v_our_firm 11296 11296 0 0.00 0 17 1 0 0
v_reserve_write_off 20990 20990 0 0.00 0 4795 6 0 0
x_lock_depdocs_on_canc_invoice 11833 11833 0 0.00 0 23236 114 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 7995 292 0 0
x_make_qstorno_1000_1200 5862 5862 0 0.00 2 44942 1159 0 0
x_make_qstorno_1200_2000 5710 5710 0 0.00 3 86159 3190 0 0
x_make_qstorno_2100_3300 473107 473107 0 0.00 0 66054 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-09-2021 01:38:46 to 20-09-2021 01:38:50.

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
23:07:56 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:56 44200 8192000000 10412628720 10739249152 1453777152 15.071 100 91 182 0 1488931312 6272160 28882000
23:10:49 22403 8192000000 10465083264 10798338048 1517249200 15.626 100 88 176 0 1554822032 9416688 28156144
23:12:14 17641 8192000000 10523843472 10867937280 1570312736 16.085 100 84 168 0 1606797168 10287840 26196592
23:13:35 11687 8192000000 10581182992 10947866624 1650153216 16.766 100 76 152 0 1683391440 11102544 22135680
23:14:49 7370 8192000000 10659051296 11026550784 1713261920 17.296 100 79 158 0 1749308080 13763472 22282688
23:15:57 6088 8192000000 10707876512 11088371712 1768078256 17.751 100 70 140 0 1800966416 12346480 20541680
23:17:05 2277 8192000000 10752474304 11135270912 1814497600 18.133 100 84 167 0 1850794112 12689584 23606928
23:18:09 2052 8192000000 10793112384 11179798528 1854362864 18.458 100 92 185 1 1894212016 14142304 25706848
23:19:11 7288 8192000000 10786490496 11187339264 1861449104 18.515 100 74 148 1 1892336480 8739488 22147888
23:20:19 4963 8192000000 10795080928 11207274496 1872605760 18.605 100 32 64 0 1888971648 7481632 8884256
23:21:26 3351 8192000000 10796722912 11220217856 1880845888 18.672 100 41 82 0 1898779264 6464080 11469296
23:22:30 9723 8192000000 10792930464 11224440832 1885057216 18.706 100 18 36 0 1897062016 7022192 4982608
23:23:42 29382 8192000000 10833837776 11247603712 1897577712 18.807 100 49 98 1 1918610976 7485984 13547280
23:25:15 5145 8192000000 10818606976 11253239808 1906250384 18.877 100 23 47 1 1918596160 5324304 7021472
23:26:20 5890 8192000000 10823652912 11258798080 1908374096 18.894 100 30 59 2 1923041568 6725264 7942208
23:27:29 10360 8192000000 10802579600 11236556800 1863650368 18.533 98 15 30 0 1872699920 4664304 4385248
23:28:39 18810 8192000000 10750305376 11175043072 1779937056 17.849 95 21 40 1 1790486336 4338944 6210336
23:29:59 3819 8192000000 10733716112 11143671808 1779925136 17.849 97 28 56 1 1794029984 5700176 8404672
23:31:04 8867 8192000000 10711753600 11136327680 1761843744 17.700 97 29 58 0 1777177360 5729104 9604512
23:32:15 64376 8192000000 10728553712 11137302528 1756445632 17.655 97 38 75 1 1773802416 5422416 11934368
23:34:22 4929 8192000000 10770416016 11194040320 1848034832 18.406 100 25 49 1 1861739168 6646864 7057472
23:35:27 5714 8192000000 10784057360 11215847424 1870384944 18.587 100 22 44 1 1882871232 5182080 7304208
23:36:40 11602 8192000000 10794437600 11229134848 1881433456 18.677 100 17 34 1 1892421648 5597856 5390336
23:37:54 16920 8192000000 10829792368 11254939648 1907150480 18.884 100 52 103 1 1928318128 5268064 15899584
23:39:17 22324 8192000000 10861435664 11289825280 1935175632 19.108 100 58 116 0 1961755008 10301856 16277520
23:40:48 18052 8192000000 10894460864 11318829056 1949227184 19.220 100 48 94 1 1976204992 14339888 12637920
23:42:10 17398 8192000000 10912935792 11344138240 1971180560 19.395 100 49 98 0 2001907664 17465952 13261152
23:43:30 24062 8192000000 10921665312 11354234880 1981561824 19.477 100 49 98 1 2015136000 18419152 15155024
23:45:01 16351 8192000000 10934441504 11379113984 1993278672 19.570 100 35 69 0 2025495360 21459824 10756864
23:46:21 5264 8192000000 10962823056 11391934464 2010642544 19.707 100 61 119 2 2050681936 22099344 17940048
23:47:34 2054 8192000000 10999985280 11424620544 2028274304 19.845 100 79 158 0 2080601408 27786912 24540192
23:48:41 4418 8192000000 10976889328 11405582336 2013272240 19.727 100 61 122 1 2060558128 29108352 18177536
23:49:52 5920 8192000000 10989544464 11423182848 2026322384 19.830 100 59 119 1 2076542128 31939584 18280160
23:50:59 4873 8192000000 10988025168 11418038272 2010026976 19.702 99 69 132 3 2068657648 37734544 20896128
23:52:13 6142 8192000000 11016302704 11442122752 2029433856 19.854 100 82 164 0 2099119600 44034880 25650864
23:53:23 5101 8192000000 10999669008 11424280576 1992802880 19.566 99 73 147 1 2060348288 45111696 22433712
23:54:39 9416 8192000000 10924887040 11363446784 1901756816 18.840 96 41 82 0 1957804832 42646992 13401024
23:55:52 14045 8192000000 10925261776 11355975680 1949490000 19.222 100 55 110 0 2008885440 41608528 17786912
23:57:07 27799 8192000000 10887477856 11337150464 1915789472 18.953 99 24 48 1 1962593824 38313536 8490816
23:58:43 24224 8192000000 10889772736 11328335872 1873341232 18.611 97 29 57 2 1920916576 37363824 10211520
00:00:13 20715 8192000000 10859044368 11312414720 1865040048 18.544 97 21 42 1 1905393808 32408624 7945136
00:01:35 40151 8192000000 10841066848 11287384064 1871322000 18.595 98 15 29 3 1893352368 17332832 4697536
00:03:19 36243 8192000000 10840538080 11290980352 1901943872 18.842 100 35 69 1 1930285168 18481216 9860080
00:04:57 91748 8192000000 10867199280 11314171904 1922116528 19.004 100 20 39 1 1937738176 10504080 5117568
00:08:10 49696 8192000000 10881178848 11318775808 1905067824 18.867 97 21 42 0 1918938016 7856624 6013568
00:10:00 58214 8192000000 10893269648 11357511680 1964610272 19.343 100 15 30 1 1982943568 14247040 4086256
00:12:00 14153 8192000000 10901865648 11354464256 1964120896 19.339 100 22 43 3 1983039008 12561120 6356992
00:13:29 53367 8192000000 10909959280 11367374848 1956467216 19.278 99 16 32 0 1978256160 16916240 4872704
00:15:25 16682 8192000000 10879277328 11346083840 1949265296 19.221 99 14 28 0 1962466208 9193280 4007632
00:16:49 50611 8192000000 10915753024 11368599552 1980250368 19.467 100 20 38 1 1995721696 10077488 5393840
00:18:43 59566 8192000000 10926374320 11385270272 1994263600 19.577 100 13 26 2 2004965232 7479344 3222288
00:20:53 42033 8192000000 10911835840 11385974784 1990153664 19.545 100 18 36 1 2007620128 12782432 4684032
00:22:36 45388 8192000000 10914354960 11383836672 1995976256 19.591 100 15 28 1 2011137488 10858704 4302528
00:24:21 42520 8192000000 10897191456 11385274368 1934638448 19.104 97 10 20 1 1950686464 13248624 2799392
00:26:04 44786 8192000000 10936532240 11403366400 1991795984 19.558 99 25 50 1 2012877296 13816880 7264432
00:27:54 34048 8192000000 10964356304 11410280448 2018796192 19.771 100 57 112 1 2046375488 10938848 16640448
00:29:28 52466 8192000000 10927266368 11397648384 1940537248 19.151 96 22 44 0 1956538576 9852912 6148416
00:31:21 46721 8192000000 10921093792 11384659968 1942041152 19.163 97 21 42 0 1959973008 12213808 5718048
00:33:08 43124 8192000000 10933009152 11381080064 1954967728 19.266 98 27 54 1 1975806192 12960768 7877696
00:34:52 36682 8192000000 10940905024 11417632768 2002504768 19.642 100 21 42 1 2028216544 19083936 6627840
00:37:07 65297 8192000000 10881550640 11333812224 1872364368 18.603 94 16 31 0 1889504608 13123504 4016736
00:39:13 72874 8192000000 10889118480 11351162880 1898322576 18.813 96 29 57 0 1914985840 7701344 8961920
00:41:31 16879 8192000000 10912010448 11368333312 1974580816 19.422 100 29 58 0 1991087792 8629456 7877520
00:42:49 39259 8192000000 10862692464 11323224064 1874265472 18.619 95 12 24 1 1888307264 10938208 3103584
00:44:29 46518 8192000000 10795916352 11254456320 1780884576 17.857 92 13 24 1 1792010864 7401520 3724768
00:46:15 37815 8192000000 10810769200 11286646784 1898078832 18.811 100 6 12 0 1908560944 8632000 1850112
00:49:09 65411 8192000000 10826856448 11295064064 1847340064 18.401 96 14 28 0 1863011264 11300896 4370304
00:51:35 50688 8192000000 10842056032 11302367232 1874776336 18.623 97 23 46 1 1894180800 12923072 6481392
00:53:34 81044 8192000000 10834972576 11296452608 1870505648 18.588 97 15 30 0 1887808576 13092352 4210576
00:55:56 91483 8192000000 10837061456 11327066112 1890068992 18.746 98 29 58 1 1913517968 14625920 8823056
00:58:32 70265 8192000000 10867199936 11333009408 1885416400 18.709 97 12 24 0 1898268960 9606640 3245920
01:00:55 122822 8192000000 10879745504 11339395072 1930326544 19.069 99 29 56 1 1946007232 7739296 7941392
01:04:16 59166 8192000000 10878710032 11363188736 1965020272 19.346 100 17 34 1 1979544928 9608640 4916016
01:06:27 54040 8192000000 10879879856 11354619904 1946485104 19.198 99 15 29 1 1960833120 9886912 4461104
01:08:21 73455 8192000000 10887450768 11364884480 1962364960 19.325 100 16 32 0 1974391904 7778400 4248544
01:10:43 53071 8192000000 10918182592 11390038016 1985929792 19.512 100 14 28 1 2005197840 15417616 3850432
01:12:45 56850 8192000000 10933673936 11406450688 1995485184 19.587 100 18 36 0 2014912672 14049040 5378448
01:14:43 53353 8192000000 10948178928 11418198016 2002690368 19.644 100 29 57 2 2024887248 13939936 8256944
01:16:42 62935 8192000000 10939718240 11398742016 1976553440 19.437 99 19 38 0 1994521664 12528528 5439696
01:18:50 72334 8192000000 10914490560 11394818048 1968738864 19.375 99 23 46 0 1992510800 16915072 6856864
01:21:03 22048 8192000000 10919293984 11368673280 1943802048 19.177 98 44 88 0 1969034384 12972624 12259712
01:22:28 133125 8192000000 10896081168 11350343680 1905664288 18.872 97 18 36 0 1926559120 16066320 4828512
01:25:49 87968 8192000000 10834393168 11316584448 1820676080 18.183 93 12 24 0 1832081776 8277328 3128368
01:30:00 72108 8192000000 10859144656 11348086784 1912431600 18.926 98 18 36 0 1934113376 16261120 5420656
01:33:07 25922 8192000000 10840856960 11300675584 1880055984 18.666 98 24 49 0 1896415280 9237792 7121504
01:34:33 84222 8192000000 10860085008 11328299008 1909693200 18.904 99 12 24 0 1924031328 10757456 3580672
01:37:04 60051 8192000000 9020135440 9219330048 183221776 2.187 10 2 4 0 188638736 4894320 522640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-09-2021 01:38:52 to 20-09-2021 01:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-09-2021 01:39:07 to 20-09-2021 01:39: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          244754
MON$OLDEST_ACTIVE               244755
MON$OLDEST_SNAPSHOT             244755
MON$NEXT_TRANSACTION            244755
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                       2019456
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.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
:181: 
:182: mscs-test-161.budzdorov.ru    Mon Sep 20 01:38:05 2021
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
--- end of diff output ---

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

20.09.21 01:39: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
2021-09-20 01:39:13 No severe PSQL-related problems occured
20.09.21 01:39:14. End of report.