Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.12.2021 22:40
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 16713028 kB
MemAvailable 31541116 kB
Buffers 0 kB
Cached 14993536 kB
SwapCached 0 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-12-01 22:37:53
DTS_END                         2021-12-01 22:37:53.8900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47204
TRN_ID                          238942
ATT_ID                          701
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: 9531.35 13404 1143762
customer order: creation 180.86 359 21703
customer order: refuse 15.38 374 1846
order to supplier: creation 47.84 1363 5741
order to supplier: removal 3.65 91505 438
invoice (draft): creation 46.82 3119 5618
invoice (draft): removal 53.96 734 6475
invoice accept: apply 43.49 19604 5219
invoice accept: cancel 48.89 839 5867
customer reserve: creation 4027.06 195 483247
customer reserve: removal 4759.91 56 571189
realization accept: apply 85.40 179 10248
realization accept: cancel 83.74 105 10049
payment from customer: creation 61.37 118 7364
payment from customer: removal 5.58 88 669
payment to supplier: creation 51.27 140 6152
payment to supplier: removal 3.58 79 430
service: total inventory turnovers 4.90 1135 588
service: total monetary turnovers 6.72 31 806
service: aggregate perf. data 0.91 17552 109
service: refresh index statistics 0.03 130499 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-12-2021 22:40:22 to 01-12-2021 22:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3550
TEST_TIME 2 4278
TEST_TIME 3 4752
TEST_TIME 4 4556
TEST_TIME 5 5233
TEST_TIME 6 5198
TEST_TIME 7 5662
TEST_TIME 8 6224
TEST_TIME 9 6174
TEST_TIME 10 6196
TEST_TIME 11 6177
TEST_TIME 12 6258
TEST_TIME 13 6376
TEST_TIME 14 6575
TEST_TIME 15 6716
TEST_TIME 16 7074
TEST_TIME 17 7188
TEST_TIME 18 7239
TEST_TIME 19 7634
TEST_TIME 20 8069
TEST_TIME 21 8605
TEST_TIME 22 9186
TEST_TIME 23 9067
TEST_TIME 24 9384
TEST_TIME 25 9486
TEST_TIME 26 9430
TEST_TIME 27 9425
TEST_TIME 28 9678
TEST_TIME 29 10230
TEST_TIME 30 10016
TEST_TIME 31 10432
TEST_TIME 32 10576
TEST_TIME 33 10424
TEST_TIME 34 10356
TEST_TIME 35 10391
TEST_TIME 36 10394
TEST_TIME 37 10328
TEST_TIME 38 10234
TEST_TIME 39 10019
TEST_TIME 40 10121
TEST_TIME 41 10114
TEST_TIME 42 9966
TEST_TIME 43 9925
TEST_TIME 44 9960
TEST_TIME 45 9948
TEST_TIME 46 9926
TEST_TIME 47 9917
TEST_TIME 48 9877
TEST_TIME 49 9788
TEST_TIME 50 9851
TEST_TIME 51 9695
TEST_TIME 52 9623
TEST_TIME 53 9608
TEST_TIME 54 9551
TEST_TIME 55 9479
TEST_TIME 56 9504
TEST_TIME 57 9544
TEST_TIME 58 9527
TEST_TIME 59 9674
TEST_TIME 60 9708
TEST_TIME 61 9895
TEST_TIME 62 9907
TEST_TIME 63 9900
TEST_TIME 64 9942
TEST_TIME 65 9896
TEST_TIME 66 10065
TEST_TIME 67 10033
TEST_TIME 68 9973
TEST_TIME 69 10032
TEST_TIME 70 9992
TEST_TIME 71 9932
TEST_TIME 72 9876
TEST_TIME 73 9906
TEST_TIME 74 9859
TEST_TIME 75 9817
TEST_TIME 76 9757
TEST_TIME 77 9686
TEST_TIME 78 9611
TEST_TIME 79 9633
TEST_TIME 80 9672
TEST_TIME 81 9703
TEST_TIME 82 9699
TEST_TIME 83 9668
TEST_TIME 84 9701
TEST_TIME 85 9703
TEST_TIME 86 9732
TEST_TIME 87 9679
TEST_TIME 88 9767
TEST_TIME 89 9819
TEST_TIME 90 9780
TEST_TIME 91 9823
TEST_TIME 92 9847
TEST_TIME 93 9808
TEST_TIME 94 9713
TEST_TIME 95 9698
TEST_TIME 96 9694
TEST_TIME 97 9669
TEST_TIME 98 9618
TEST_TIME 99 9620
TEST_TIME 100 9567
TEST_TIME 101 9531
TEST_TIME 102 9524
TEST_TIME 103 9524
TEST_TIME 104 9540
TEST_TIME 105 9551
TEST_TIME 106 9616
TEST_TIME 107 9606
TEST_TIME 108 9604
TEST_TIME 109 9654
TEST_TIME 110 9613
TEST_TIME 111 9648
TEST_TIME 112 9672
TEST_TIME 113 9717
TEST_TIME 114 9680
TEST_TIME 115 9686
TEST_TIME 116 9641
TEST_TIME 117 9611
TEST_TIME 118 9577
TEST_TIME 119 9581
TEST_TIME 120 9537

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-12-2021 22:40:25 to 01-12-2021 22:40:29.

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 1590530 1590530 0 0.00 0 16106 6 0 0
doc_list_biud 1590530 1590530 0 0.00 0 23214 26 0 0
sp_add_invoice_to_stock 5296 5219 77 1.45 34 188498 19605 0 77
sp_cancel_adding_invoice 5867 5867 0 0.00 5 81126 840 0 0
sp_cancel_client_order 1846 1846 0 0.00 4 14668 374 0 0
sp_cancel_customer_reserve 571189 571189 0 0.00 1 23215 56 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 5886 88 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 7152 80 0 0
sp_cancel_supplier_invoice 6480 6475 5 0.07 1 12852 734 0 5
sp_cancel_supplier_order 438 438 0 0.00 8 927821 91505 0 0
sp_cancel_write_off 10049 10049 0 0.00 1 15446 106 0 0
sp_client_order 21703 21703 0 0.00 3 21901 359 0 0
sp_customer_reserve 483247 483247 0 0.00 0 65735 195 0 0
sp_fill_shopping_cart 33062 33062 0 0.00 1 13179 35 0 0
sp_fill_shopping_cart_clo_res 483232 483232 0 0.00 0 21703 14 0 0
sp_get_clo_for_invoice 5204 5204 0 0.00 0 25273 279 0 0
sp_kill_cost_storno 17015 17015 0 0.00 0 13115 53 0 0
sp_kill_qstorno_ret_qs2qd 1159340 1159340 0 0.00 0 23089 30 0 0
sp_kill_qty_storno 611316 611316 0 0.00 0 23214 66 0 0
sp_lock_selected_doc 21408 21408 0 0.00 0 10993 7 0 0
sp_make_cost_storno 28968 28968 0 0.00 0 16090 53 0 0
sp_multiply_rows_for_pdistr 28968 28968 0 0.00 0 7021 27 0 0
sp_multiply_rows_for_qdistr 21703 21703 0 0.00 0 17446 158 0 0
sp_pay_from_customer 7364 7364 0 0.00 3 23051 119 0 0
sp_pay_to_supplier 6152 6152 0 0.00 4 17140 140 0 0
sp_payment_common 13516 13516 0 0.00 1 23043 31 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 0 14661 344 0 0
sp_qd_handle_on_invoice_upd_sts 11071 11071 0 0.00 0 17340 327 0 0
sp_qd_handle_on_reserve_upd_sts 10248 10248 0 0.00 0 17976 51 0 0
sp_reserve_write_off 10248 10248 0 0.00 6 18086 179 0 0
sp_supplier_invoice 5618 5618 0 0.00 6 77340 3119 0 0
sp_supplier_order 5741 5741 0 0.00 5 34855 1364 0 0
srv_aggregate_perf_data 109 109 0 0.00 2970 135160 17553 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1245 149196 40893 0 0
srv_find_qd_qs_mism 1093617 1093617 0 0.00 0 10588 6 0 0
srv_make_invnt_saldo 588 588 0 0.00 26 94624 1136 0 0
srv_make_money_saldo 806 806 0 0.00 1 7794 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 89547 206283 130499 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5219 5219 0 0.00 0 25690 185 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 5269 88 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 5676 15 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1078 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 774 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1002 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 6199 29 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 18742 1566 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 742 4 0 0
t$perf-norm:sp_client_order 21703 21703 0 0.00 0 24899 22 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 3330 10 0 0
t$perf-norm:sp_pay_from_customer 7364 7364 0 0.00 0 5619 13 0 0
t$perf-norm:sp_pay_to_supplier 6152 6152 0 0.00 0 10474 12 0 0
t$perf-norm:sp_reserve_write_off 10248 10248 0 0.00 0 5499 11 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 9900 21 0 0
t$perf-norm:sp_supplier_order 5741 5741 0 0.00 0 8861 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 67 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 98 2 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 1170 24 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 3641 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10556 10402 154 1.45 0 6277 19 0 154
v_all_customers 39788 39788 0 0.00 0 19 1 0 0
v_all_suppliers 31834 31834 0 0.00 0 20 1 0 0
v_all_wares 43406 43406 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 5078 13 0 0
v_cancel_client_order 3692 3692 0 0.00 0 5213 5 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2976 10 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 2102 7 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 7105 16 0 0
v_cancel_supplier_invoice 862 852 10 1.16 0 11904 31 0 10
v_cancel_supplier_order 876 876 0 0.00 0 12601 20 0 0
v_cancel_write_off 2080 2080 0 0.00 0 5468 20 0 0
v_max_id_clo_ord 5741 5741 0 0.00 0 641 1 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 1903 16 0 0
v_max_id_ord_sup 5618 5618 0 0.00 0 6112 4 0 0
v_max_non_paid_invoice 1594 1594 0 0.00 0 780 3 0 0
v_max_non_paid_realizn 3877 3877 0 0.00 0 6542 6 0 0
v_min_id_clo_ord 5741 5741 0 0.00 0 2167 6 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 7576 20 0 0
v_min_id_ord_sup 5618 5618 0 0.00 0 7052 6 0 0
v_min_non_paid_invoice 6152 6152 0 0.00 0 6554 20 0 0
v_min_non_paid_realizn 7364 7364 0 0.00 0 23036 27 0 0
v_our_firm 10592 10592 0 0.00 0 18 1 0 0
v_reserve_write_off 20496 20496 0 0.00 0 12648 11 0 0
x_lock_depdocs_on_canc_invoice 11916 11916 0 0.00 0 25846 112 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 9269 307 0 0
x_make_qstorno_1000_1200 5741 5741 0 0.00 2 34840 1282 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 3 77331 2996 0 0
x_make_qstorno_2100_3300 483247 483247 0 0.00 0 65733 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-12-2021 22:40:30 to 01-12-2021 22:40:34.

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
20:07:57 4 8192000000 8798508736 8809775104 4972256 0.060 2 1 2 0 5071856 56816 42784
20:08:58 13695 8192000000 9887978240 10194759680 973929632 10.625 100 86 172 0 1002824608 6197632 22697344
20:10:23 12759 8192000000 9932504928 10251161600 1013477856 11.009 100 87 174 0 1044171168 7905728 22787584
20:11:45 22282 8192000000 9975859280 10308390912 1060714944 11.463 100 82 164 0 1090228080 9328064 20185072
20:13:11 9024 8192000000 10030252976 10379141120 1119570000 12.023 100 76 152 0 1147699120 10282208 17846912
20:14:26 11829 8192000000 10079700576 10437885952 1162916992 12.431 100 77 154 0 1192211248 11719488 17574768
20:15:45 5783 8192000000 10121267392 10489835520 1210256224 12.871 100 79 159 2 1240080688 12373136 17451328
20:16:53 2864 8192000000 10162578672 10535763968 1249248768 13.231 100 86 173 0 1281187072 13247536 18690768
20:17:58 2796 8192000000 10193906784 10579804160 1284467040 13.554 100 71 140 2 1310902688 10930512 15505136
20:19:03 1641 8192000000 10220181312 10603003904 1301740176 13.711 100 90 181 1 1334633840 13467824 19425840
20:20:06 2546 8192000000 10212771344 10610364416 1309087776 13.778 100 52 104 1 1331270144 10969376 11212992
20:21:09 3950 8192000000 10207644560 10618552320 1314490464 13.827 100 25 50 0 1327818784 8012464 5315856
20:22:19 78813 8192000000 10210951296 10633904128 1323932704 13.912 100 31 64 0 1336981408 6260384 6788320
20:24:38 6865 8192000000 10217717296 10638245888 1333527840 13.999 100 27 54 2 1345442656 5796480 6118336
20:25:46 7089 8192000000 10225790608 10651840512 1338106144 14.040 100 25 50 0 1350028880 5310688 6612048
20:26:55 65909 8192000000 10225991168 10647601152 1324375440 13.916 99 40 80 1 1339978800 6209328 9394032
20:29:06 9052 8192000000 10210652624 10632957952 1302999680 13.723 98 23 46 2 1313714944 5498576 5216688
20:30:19 11512 8192000000 10125761232 10518552576 1147360496 12.285 90 18 36 0 1157811904 6193136 4258272
20:31:33 61774 8192000000 10148259072 10557181952 1227697456 13.033 97 17 34 0 1236521264 4736448 4087360
20:33:50 7307 8192000000 10166338176 10573385728 1279829280 13.511 100 26 52 0 1291488720 5466064 6193376
20:35:03 9525 8192000000 10182180640 10594476032 1292403408 13.626 100 27 54 1 1304383472 5656400 6323664
20:36:15 17948 8192000000 10206094368 10612113408 1300215248 13.697 100 18 36 2 1309322336 5652768 3454320
20:37:35 17830 8192000000 10212555008 10625826816 1316342816 13.844 100 26 52 0 1331746496 9610048 5793632
20:39:00 33914 8192000000 10253310464 10662891520 1341377040 14.070 100 54 106 1 1364113792 10754800 11981952
20:40:37 4034 8192000000 10272832192 10680049664 1357254160 14.213 100 61 121 1 1383356080 10435536 15666384
20:41:44 20998 8192000000 10269932192 10689544192 1361076448 14.247 100 46 92 0 1385205968 13266912 10862608
20:43:12 21174 8192000000 10292820032 10709598208 1371130144 14.337 100 50 100 1 1395728672 13495488 11103040
20:44:47 15936 8192000000 10292706576 10719895552 1375853312 14.379 100 39 78 1 1408482464 22964624 9664528
20:46:09 6122 8192000000 10309100688 10736992256 1392833680 14.531 100 49 93 3 1421251088 17796688 10620720
20:47:19 3575 8192000000 10331667296 10745663488 1398834320 14.585 100 69 136 0 1434898512 20162352 15901840
20:48:29 4473 8192000000 10330402768 10749440000 1396019584 14.560 100 62 124 1 1434419472 23868800 14531088
20:49:41 18617 8192000000 10335669776 10762579968 1410735872 14.690 100 60 121 0 1451305184 25953600 14615712
20:51:02 12446 8192000000 10335500064 10763747328 1408102576 14.667 100 53 106 2 1449661712 28557536 13001600
20:52:25 7510 8192000000 10364315504 10786119680 1420368480 14.776 100 62 122 0 1468565056 33345264 14851312
20:53:47 8988 8192000000 10360581376 10787528704 1411436640 14.697 99 53 104 1 1456252000 31353568 13461792
20:55:01 1244 8192000000 10341201424 10748358656 1368693744 14.315 97 88 174 0 1426465200 35428880 22342576
20:56:07 1455 8192000000 10368972976 10777055232 1398482784 14.581 100 90 180 1 1466299280 45529200 22287296
20:57:14 2761 8192000000 10343178128 10746064896 1371513024 14.341 99 88 176 1 1436899392 42241648 23144720
20:58:22 16705 8192000000 10320658336 10746077184 1367096352 14.301 99 44 88 2 1423319840 43856464 12367024
20:59:55 15042 8192000000 10318629632 10745233408 1384006384 14.452 100 29 58 4 1423639456 31927840 7705232
21:01:14 2825 8192000000 10334964624 10755706880 1382653024 14.440 100 46 93 0 1433185344 37916496 12615824
21:02:18 29779 8192000000 10307790160 10747961344 1375898400 14.380 100 27 53 2 1412680576 30047552 6734624
21:03:52 10708 8192000000 10288666800 10722873344 1365690288 14.288 100 23 46 1 1400798768 28988736 6119744
21:05:05 22206 8192000000 10294420208 10732716032 1351483840 14.161 100 20 40 0 1397982560 40465504 6033216
21:06:39 26985 8192000000 10275753552 10710491136 1348746688 14.136 99 17 34 0 1369420464 16125920 4547856
21:08:07 41417 8192000000 10276057440 10722283520 1340804832 14.065 98 12 24 1 1362592640 19024336 2763472
21:10:23 44303 8192000000 10258272480 10692956160 1298796464 13.684 95 19 36 2 1311639312 8381872 4460976
21:12:08 34259 8192000000 10268862560 10711375872 1346762096 14.118 99 30 56 1 1362014960 9068448 6184416
21:13:54 74522 8192000000 10264126928 10704916480 1335075088 14.013 98 35 70 0 1352708592 9513616 8119888
21:16:11 64047 8192000000 10304747344 10735689728 1378904640 14.407 100 44 88 1 1397352528 8241520 10206368
21:18:15 31321 8192000000 10285520736 10739134464 1381053472 14.426 100 21 42 0 1395155328 9585824 4516032
21:19:48 37641 8192000000 10303534000 10755166208 1396505792 14.564 100 28 56 1 1413402240 10016640 6879808
21:21:29 37136 8192000000 10302492032 10759376896 1398369472 14.580 100 20 39 0 1412269888 9241632 4658784
21:23:12 12033 8192000000 10289417296 10735108096 1372137408 14.346 99 38 76 2 1390125648 9781840 8206400
21:24:33 32270 8192000000 10307175072 10765541376 1396115824 14.560 100 19 38 0 1416836720 16325520 4395376
21:26:29 20686 8192000000 10306499792 10758795264 1399233408 14.588 100 10 20 0 1411588944 10249856 2105680
21:27:54 74752 8192000000 10294740000 10752667648 1374843024 14.370 98 21 42 0 1387615712 8244752 4527936
21:30:10 32971 8192000000 10266871760 10733305856 1353068288 14.175 97 17 34 1 1365107232 8196784 3842160
21:31:46 59095 8192000000 10301287360 10763563008 1391602096 14.520 100 12 24 0 1404311616 10104336 2605184
21:34:34 48398 8192000000 10295704912 10760085504 1380734816 14.423 99 13 26 0 1399205664 15362816 3108032
21:36:28 45845 8192000000 10294859248 10746556416 1358635824 14.225 98 21 42 2 1378071584 14757664 4678096
21:38:14 39044 8192000000 10301892224 10767314944 1381006112 14.426 99 21 42 0 1409721168 23697408 5017648
21:39:57 23401 8192000000 10293400544 10736742400 1341122000 14.068 97 30 58 2 1362855696 15070544 6663152
21:41:21 51974 8192000000 10282441856 10732810240 1346950768 14.120 98 22 44 0 1364393424 12634560 4808096
21:43:13 81348 8192000000 10289771184 10739830784 1346397360 14.115 97 9 18 0 1359163840 11256528 1509952
21:45:41 22982 8192000000 10309283488 10746470400 1374198944 14.365 100 43 86 4 1407369312 23667696 9502672
21:47:05 149196 8192000000 10260391216 10722578432 1339333008 14.051 97 27 54 0 1357015728 11714912 5967808
21:50:38 65104 8192000000 10257535344 10719006720 1356416000 14.205 99 21 41 0 1369664768 7833552 5415216
21:52:54 65954 8192000000 10259191056 10712309760 1323251392 13.906 97 12 24 0 1333895456 7864800 2779264
21:55:03 93219 8192000000 10265246240 10722406400 1362748336 14.262 100 24 48 0 1383695280 14534320 6412624
21:57:44 54818 8192000000 10251912032 10706272256 1331580400 13.981 98 13 26 0 1349522608 14283184 3659024
21:59:40 29868 8192000000 10259874160 10702843904 1332096304 13.986 98 27 54 1 1345960112 7853408 6010400
22:01:16 61939 8192000000 10269488704 10709188608 1325290224 13.925 97 15 30 0 1339826496 11404160 3132112
22:03:20 58231 8192000000 10277929824 10726375424 1339291200 14.051 98 26 52 3 1359208736 14453776 5463760
22:05:18 101209 8192000000 10299003056 10743615488 1363982752 14.273 99 15 30 0 1383250672 15872016 3395904
22:08:03 36847 8192000000 10309053184 10755469312 1392973264 14.532 100 40 80 1 1415557392 13877760 8706368
22:09:41 69904 8192000000 10312140528 10751811584 1367674112 14.306 98 23 47 0 1382349392 9446960 5228320
22:11:53 98257 8192000000 10277997232 10752819200 1377053520 14.390 99 18 36 0 1394907504 13219248 4634736
22:14:32 42504 8192000000 10292506672 10750234624 1397353552 14.571 100 14 28 1 1407132288 6502608 3276128
22:16:22 72704 8192000000 10302624400 10763849728 1376575584 14.386 98 9 18 0 1388746496 10001072 2169840
22:18:39 54758 8192000000 10305637392 10782670848 1406378160 14.652 100 10 20 0 1425652720 16818176 2456384
22:20:34 90160 8192000000 10321024384 10783092736 1386358864 14.473 98 13 26 2 1413478576 23553216 3566496
22:23:12 91544 8192000000 10311804864 10780102656 1366076976 14.292 97 13 26 0 1381435136 12448000 2910160
22:25:44 46492 8192000000 10281923888 10747367424 1339521168 14.053 96 32 64 2 1361357888 13777552 8059168
22:28:03 29416 8192000000 10288965152 10723799040 1340742144 14.064 98 50 100 1 1367479600 15766864 10970592
22:29:35 84317 8192000000 10241951152 10695086080 1278231776 13.497 94 15 30 0 1296380704 14510352 3638576
22:32:10 61468 8192000000 10263481936 10717192192 1342617072 14.081 99 27 54 0 1361047584 12669104 5761408
22:34:23 29917 8192000000 10256903440 10698174464 1316116720 13.842 97 28 56 0 1328363536 5761824 6484992
22:35:54 38488 8192000000 10270038992 10714091520 1353921920 14.183 100 53 106 1 1375032496 9598960 11511616
22:37:50 16504 8192000000 9354769472 9658208256 511124976 5.872 38 2 4 0 516780720 5570672 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-12-2021 22:40:36 to 01-12-2021 22:40:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 01-12-2021 22:40:52 to 01-12-2021 22:40:53.

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          239902
MON$OLDEST_ACTIVE               240991
MON$OLDEST_SNAPSHOT             240991
MON$NEXT_TRANSACTION            240991
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                       2032672
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

01.12.21 22:40:54. 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-12-01 22:40:57 No severe PSQL-related problems occured
01.12.21 22:40:58. End of report.