Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.08.2021 22: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 16147248 kB
MemAvailable 30903660 kB
Buffers 0 kB
Cached 15525300 kB
SwapCached 324 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-08-29 22:37:44
DTS_END                         2021-08-29 22:37:44.1880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58674
TRN_ID                          246060
ATT_ID                          709
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: 9510.93 17565 1141312
customer order: creation 182.12 364 21854
customer order: refuse 15.02 343 1802
order to supplier: creation 48.29 1258 5795
order to supplier: removal 3.68 105286 441
invoice (draft): creation 48.21 3519 5785
invoice (draft): removal 53.81 851 6457
invoice accept: apply 43.78 18238 5254
invoice accept: cancel 48.08 805 5769
customer reserve: creation 4029.53 181 483543
customer reserve: removal 4730.28 64 567634
realization accept: apply 88.67 210 10640
realization accept: cancel 81.55 111 9786
payment from customer: creation 63.05 135 7566
payment from customer: removal 6.29 105 755
payment to supplier: creation 52.08 151 6250
payment to supplier: removal 3.44 80 413
service: total inventory turnovers 4.75 1140 570
service: total monetary turnovers 7.32 31 878
service: aggregate perf. data 0.97 16121 116
service: refresh index statistics 0.03 202307 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-08-2021 22:38:53 to 29-08-2021 22:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2078
TEST_TIME 2 1219
TEST_TIME 3 2623
TEST_TIME 4 3561
TEST_TIME 5 3497
TEST_TIME 6 4028
TEST_TIME 7 4148
TEST_TIME 8 4562
TEST_TIME 9 5008
TEST_TIME 10 5414
TEST_TIME 11 5439
TEST_TIME 12 5541
TEST_TIME 13 5699
TEST_TIME 14 5661
TEST_TIME 15 6030
TEST_TIME 16 6777
TEST_TIME 17 6701
TEST_TIME 18 6997
TEST_TIME 19 7179
TEST_TIME 20 7289
TEST_TIME 21 7482
TEST_TIME 22 7950
TEST_TIME 23 8049
TEST_TIME 24 8541
TEST_TIME 25 8442
TEST_TIME 26 8742
TEST_TIME 27 9188
TEST_TIME 28 9613
TEST_TIME 29 10243
TEST_TIME 30 10522
TEST_TIME 31 10564
TEST_TIME 32 10439
TEST_TIME 33 10490
TEST_TIME 34 10548
TEST_TIME 35 10413
TEST_TIME 36 10208
TEST_TIME 37 10174
TEST_TIME 38 10128
TEST_TIME 39 10094
TEST_TIME 40 9938
TEST_TIME 41 9927
TEST_TIME 42 9896
TEST_TIME 43 9787
TEST_TIME 44 9904
TEST_TIME 45 9871
TEST_TIME 46 9786
TEST_TIME 47 9646
TEST_TIME 48 9623
TEST_TIME 49 9659
TEST_TIME 50 9683
TEST_TIME 51 9638
TEST_TIME 52 9557
TEST_TIME 53 9490
TEST_TIME 54 9471
TEST_TIME 55 9609
TEST_TIME 56 9554
TEST_TIME 57 9575
TEST_TIME 58 9578
TEST_TIME 59 9593
TEST_TIME 60 9620
TEST_TIME 61 9741
TEST_TIME 62 9773
TEST_TIME 63 9736
TEST_TIME 64 9791
TEST_TIME 65 9933
TEST_TIME 66 9920
TEST_TIME 67 10017
TEST_TIME 68 10004
TEST_TIME 69 9929
TEST_TIME 70 9855
TEST_TIME 71 9815
TEST_TIME 72 9758
TEST_TIME 73 9745
TEST_TIME 74 9724
TEST_TIME 75 9794
TEST_TIME 76 9743
TEST_TIME 77 9651
TEST_TIME 78 9640
TEST_TIME 79 9589
TEST_TIME 80 9552
TEST_TIME 81 9570
TEST_TIME 82 9562
TEST_TIME 83 9584
TEST_TIME 84 9665
TEST_TIME 85 9659
TEST_TIME 86 9611
TEST_TIME 87 9632
TEST_TIME 88 9682
TEST_TIME 89 9662
TEST_TIME 90 9723
TEST_TIME 91 9701
TEST_TIME 92 9677
TEST_TIME 93 9579
TEST_TIME 94 9590
TEST_TIME 95 9531
TEST_TIME 96 9536
TEST_TIME 97 9551
TEST_TIME 98 9557
TEST_TIME 99 9525
TEST_TIME 100 9613
TEST_TIME 101 9564
TEST_TIME 102 9614
TEST_TIME 103 9626
TEST_TIME 104 9635
TEST_TIME 105 9659
TEST_TIME 106 9687
TEST_TIME 107 9666
TEST_TIME 108 9710
TEST_TIME 109 9725
TEST_TIME 110 9662
TEST_TIME 111 9646
TEST_TIME 112 9631
TEST_TIME 113 9616
TEST_TIME 114 9569
TEST_TIME 115 9547
TEST_TIME 116 9514
TEST_TIME 117 9513
TEST_TIME 118 9503
TEST_TIME 119 9548
TEST_TIME 120 9513

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-08-2021 22:38:57 to 29-08-2021 22:39:00.

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 1589190 1589190 0 0.00 0 32319 7 0 0
doc_list_biud 1589189 1589189 0 0.00 0 46130 29 0 0
sp_add_invoice_to_stock 5414 5254 160 2.95 23 188228 18238 0 160
sp_cancel_adding_invoice 5769 5769 0 0.00 5 75089 805 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 11151 343 0 0
sp_cancel_customer_reserve 567634 567634 0 0.00 1 38609 64 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 2 7782 106 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 3640 81 0 0
sp_cancel_supplier_invoice 6472 6457 15 0.23 1 24825 852 0 15
sp_cancel_supplier_order 441 441 0 0.00 8 1098986 105286 0 0
sp_cancel_write_off 9786 9786 0 0.00 1 14159 111 0 0
sp_client_order 21854 21854 0 0.00 3 17791 365 0 0
sp_customer_reserve 483543 483543 0 0.00 0 56746 181 0 0
sp_fill_shopping_cart 33428 33428 0 0.00 1 14569 36 0 0
sp_fill_shopping_cart_clo_res 483538 483538 0 0.00 0 15085 14 0 0
sp_get_clo_for_invoice 5249 5249 0 0.00 0 19244 278 0 0
sp_kill_cost_storno 16723 16723 0 0.00 0 6320 57 0 0
sp_kill_qstorno_ret_qs2qd 1151952 1151952 0 0.00 0 38210 35 0 0
sp_kill_qty_storno 607778 607778 0 0.00 0 46130 75 0 0
sp_lock_selected_doc 21872 21872 0 0.00 0 10484 6 0 0
sp_make_cost_storno 29705 29705 0 0.00 0 28619 76 0 0
sp_multiply_rows_for_pdistr 29705 29705 0 0.00 0 9121 27 0 0
sp_multiply_rows_for_qdistr 21854 21854 0 0.00 0 17765 170 0 0
sp_pay_from_customer 7566 7566 0 0.00 4 26245 135 0 0
sp_pay_to_supplier 6250 6250 0 0.00 5 24525 151 0 0
sp_payment_common 13816 13816 0 0.00 1 26010 34 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 11144 314 0 0
sp_qd_handle_on_invoice_upd_sts 11018 11018 0 0.00 0 46129 335 0 0
sp_qd_handle_on_reserve_upd_sts 10640 10640 0 0.00 0 17667 62 0 0
sp_reserve_write_off 10640 10640 0 0.00 6 32361 211 0 0
sp_supplier_invoice 5785 5785 0 0.00 8 101136 3520 0 0
sp_supplier_order 5795 5795 0 0.00 5 27832 1259 0 0
srv_aggregate_perf_data 116 116 0 0.00 3300 128310 16121 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 2471 120361 39605 0 0
srv_find_qd_qs_mism 1090996 1090996 0 0.00 0 21578 6 0 0
srv_make_invnt_saldo 570 570 0 0.00 43 80784 1141 0 0
srv_make_money_saldo 878 878 0 0.00 1 8693 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 128386 273056 202307 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5254 5254 0 0.00 0 12480 159 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 3619 113 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 1137 7 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 3908 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 2439 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 162 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 2279 24 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 27626 1703 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 8222 21 0 0
t$perf-norm:sp_client_order 21854 21854 0 0.00 0 11863 19 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 4515 9 0 0
t$perf-norm:sp_pay_from_customer 7566 7566 0 0.00 0 6172 10 0 0
t$perf-norm:sp_pay_to_supplier 6250 6250 0 0.00 0 4933 12 0 0
t$perf-norm:sp_reserve_write_off 10640 10640 0 0.00 0 11405 14 0 0
t$perf-norm:sp_supplier_invoice 5785 5785 0 0.00 0 6529 22 0 0
t$perf-norm:sp_supplier_order 5795 5795 0 0.00 0 3275 14 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 483 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 684 18 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 3951 28 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 913 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10818 10498 320 2.95 0 12463 23 0 320
v_all_customers 39494 39494 0 0.00 0 24 1 0 0
v_all_suppliers 32448 32448 0 0.00 0 19 1 0 0
v_all_wares 43708 43708 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2229 11 0 0
v_cancel_client_order 3604 3604 0 0.00 0 7229 7 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 3017 10 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 2655 9 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2323 10 0 0
v_cancel_supplier_invoice 888 858 30 3.37 0 2475 19 0 30
v_cancel_supplier_order 882 882 0 0.00 0 568 4 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2267 13 0 0
v_max_id_clo_ord 5791 5791 0 0.00 0 1273 2 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 4833 17 0 0
v_max_id_ord_sup 5783 5783 0 0.00 0 6126 6 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 1993 3 0 0
v_max_non_paid_realizn 4089 4089 0 0.00 0 4774 4 0 0
v_min_id_clo_ord 5791 5791 0 0.00 0 10175 11 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 4948 9 0 0
v_min_id_ord_sup 5783 5783 0 0.00 0 4341 8 0 0
v_min_non_paid_invoice 6250 6250 0 0.00 0 5454 19 0 0
v_min_non_paid_realizn 7566 7566 0 0.00 0 6668 20 0 0
v_our_firm 11168 11168 0 0.00 0 14 1 0 0
v_reserve_write_off 21280 21280 0 0.00 0 6188 7 0 0
x_lock_depdocs_on_canc_invoice 11797 11797 0 0.00 0 19638 113 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 25716 400 0 0
x_make_qstorno_1000_1200 5795 5795 0 0.00 2 27824 1175 0 0
x_make_qstorno_1200_2000 5785 5785 0 0.00 3 101109 3395 0 0
x_make_qstorno_2100_3300 483543 483543 0 0.00 0 56744 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-08-2021 22:39:01 to 29-08-2021 22:39:05.

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:47 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:48 31946 8192000000 10388225056 10708049920 1435284272 14.908 100 87 174 1 1469623536 6452016 27887248
20:10:28 18623 8192000000 10455115904 10785464320 1513224032 15.591 100 95 190 0 1552892080 9461728 30206320
20:11:55 18554 8192000000 10496878144 10843238400 1549602592 15.907 100 78 156 2 1583993424 9932496 24458336
20:13:23 12308 8192000000 10582330976 10938499072 1637722816 16.660 100 78 156 3 1672516416 11519808 23273792
20:14:38 6179 8192000000 10635206848 11002662912 1695034512 17.144 100 77 154 2 1729713920 13091120 21588288
20:15:47 5943 8192000000 10711421696 11090046976 1765388208 17.729 100 80 160 1 1804764768 17136688 22239872
20:16:56 4057 8192000000 10760583296 11144409088 1807600544 18.076 100 76 152 1 1840389952 10982320 21807088
20:18:02 2030 8192000000 10804467040 11196321792 1864242432 18.538 100 88 174 0 1903048480 14000080 24805968
20:19:05 1217 8192000000 10825501168 11212333056 1878575376 18.654 100 96 195 0 1919760944 14060480 27125088
20:20:07 3316 8192000000 10788160256 11208503296 1878712704 18.655 100 43 86 0 1899194048 8444528 12036816
20:21:11 12063 8192000000 10785926192 11214970880 1878133392 18.650 100 23 46 3 1890246336 6448944 5664000
20:22:24 11564 8192000000 10801443264 11232079872 1894603232 18.783 100 13 26 2 1903955376 5618848 3733296
20:23:38 40988 8192000000 10816659792 11248955392 1903801376 18.857 100 27 53 3 1917420000 6231120 7387504
20:25:23 9370 8192000000 10824920016 11259240448 1908556512 18.895 100 20 40 2 1920009616 5481984 5971120
20:26:33 8140 8192000000 10810283296 11242897408 1897038272 18.802 100 25 49 1 1910329440 6585808 6705360
20:27:45 19758 8192000000 10806112544 11235069952 1856154672 18.472 97 25 51 3 1868820640 5423296 7242672
20:29:11 11454 8192000000 10760859808 11189968896 1769458976 17.763 94 16 32 2 1779306800 5536688 4311136
20:30:24 8497 8192000000 10683399152 11102216192 1727402064 17.414 97 25 50 3 1744775600 10411024 6962512
20:31:33 11118 8192000000 10708864192 11127300096 1743246032 17.546 96 13 26 2 1752174288 5125136 3803120
20:32:47 10490 8192000000 10724735456 11146039296 1782888208 17.873 98 10 19 1 1790116720 4930336 2298176
20:33:58 22617 8192000000 10731662144 11158589440 1822326288 18.197 100 37 74 0 1839938640 6851552 10760800
20:35:22 7801 8192000000 10773200736 11201454080 1858570256 18.492 100 28 56 2 1872917840 5736800 8610784
20:36:31 11904 8192000000 10792638288 11218309120 1868845904 18.575 100 22 44 1 1881061264 5352224 6863136
20:37:46 44779 8192000000 10847895840 11254394880 1908066624 18.891 100 34 62 5 1922222816 4357104 9799088
20:39:32 67853 8192000000 10852111968 11283456000 1925798304 19.033 100 43 85 3 1946449456 8457904 12193248
20:41:46 10720 8192000000 10892111792 11315081216 1955820880 19.273 100 56 112 2 1983548240 12196768 15530592
20:43:00 4483 8192000000 10885142672 11314470912 1940461296 19.150 99 56 110 2 1971791296 15086144 16243856
20:44:12 14702 8192000000 10919646848 11346739200 1975079936 19.426 100 48 94 2 2004187456 14691104 14416416
20:45:31 2855 8192000000 10951150720 11372564480 1996113056 19.592 100 71 141 1 2037020064 20765088 20141920
20:46:38 6284 8192000000 10933773072 11348426752 1970359872 19.388 99 64 125 0 2013529712 24052720 19117120
20:47:50 6023 8192000000 10956818976 11383005184 2002521040 19.643 100 71 139 1 2044566224 21646192 20398992
20:49:06 11558 8192000000 10952731040 11376726016 1995268400 19.585 100 69 138 0 2041744464 25717024 20759040
20:50:27 2461 8192000000 10954173728 11366707200 1985497744 19.508 100 88 173 2 2043615168 31412992 26704432
20:51:35 5403 8192000000 10964958064 11384627200 1987546736 19.524 100 62 123 1 2044494832 37077232 19870864
20:52:45 4665 8192000000 10990568816 11406348288 2008588368 19.690 100 72 143 1 2071442640 40630912 22223360
20:53:54 8992 8192000000 10946087024 11361853440 1963729696 19.336 100 72 144 0 2023720560 36910768 23080096
20:55:17 6574 8192000000 10945813216 11366965248 1952659312 19.248 99 70 140 2 2016880064 41500896 22719856
20:56:34 4444 8192000000 10938725296 11354611712 1955865840 19.273 100 72 143 2 2018959232 41111152 21982240
20:57:45 4028 8192000000 10973936096 11385618432 1980673824 19.470 100 85 170 0 2053438320 45939920 26824576
20:58:55 3519 8192000000 10976259456 11390930944 1970802032 19.392 99 78 156 0 2045372032 50085840 24484160
21:00:07 4899 8192000000 10924816576 11353382912 1927594608 19.048 99 44 87 2 1988055056 45710640 14749808
21:01:24 9489 8192000000 10933378080 11366793216 1955701888 19.272 100 42 84 0 2020305968 50518608 14085472
21:02:42 24472 8192000000 10925642160 11372937216 1950391920 19.230 100 33 66 3 2011335632 49300656 11643056
21:04:14 24061 8192000000 10912468032 11374108672 1927918304 19.050 99 27 54 0 1991751072 53687344 10145424
21:05:57 57942 8192000000 10889876816 11365031936 1935302560 19.109 100 13 27 0 1981960896 41864080 4794256
21:07:56 44517 8192000000 10888579856 11354144768 1950811424 19.233 100 10 22 1 1964908992 11722752 2374816
21:09:41 52421 8192000000 10890274240 11344474112 1924668096 19.024 98 17 34 0 1943096048 13279600 5148352
21:11:35 88337 8192000000 10865188352 11326377984 1904832864 18.865 98 19 38 1 1927253408 16615616 5804928
21:14:44 62092 8192000000 10887163392 11339317248 1918099328 18.972 98 32 64 2 1937068672 10398688 8570656
21:16:54 38868 8192000000 10896566256 11354415104 1966719664 19.359 100 26 52 1 1980530528 6171872 7638992
21:18:37 24780 8192000000 10894529296 11365318656 1959277488 19.300 99 14 27 1 1972747392 9087312 4382592
21:20:03 6943 8192000000 10919302064 11372425216 1983258944 19.490 100 35 71 3 2001006096 7357568 10389584
21:21:18 44059 8192000000 10914057264 11395719168 1973818160 19.416 99 22 43 3 1993903072 13659664 6425248
21:23:03 33111 8192000000 10918675456 11395842048 1995297552 19.586 100 8 16 1 2008656288 11250944 2107792
21:24:39 20285 8192000000 10926576320 11397038080 1988970208 19.536 99 31 62 1 2015957312 16983168 10003936
21:27:14 80915 8192000000 10923235328 11397963776 1981020640 19.473 99 28 55 3 1998009728 8391920 8597168
21:29:36 50184 8192000000 10933193008 11411746816 1992061360 19.560 99 16 32 0 2010862672 14416304 4385008
21:31:38 63832 8192000000 10897079952 11386613760 1942401104 19.166 98 6 12 0 1959652096 15954928 1296064
21:33:42 35584 8192000000 10891454944 11363713024 1893475568 18.774 95 20 39 0 1908729040 9639152 5614320
21:35:19 13179 8192000000 10920058784 11373973504 1973045904 19.410 100 49 98 2 1997170752 10639008 13485840
21:36:36 33665 8192000000 10909039520 11376025600 1960936544 19.313 99 28 56 2 1979067648 9708832 8422272
21:38:10 63798 8192000000 10893779296 11364990976 1930672944 19.072 98 18 36 0 1954782928 19483840 4626144
21:40:14 42547 8192000000 10928573632 11389517824 1949084224 19.219 98 23 46 0 1972647584 17430688 6132672
21:41:59 81867 8192000000 10898244512 11377614848 1894528960 18.782 95 10 20 1 1912746736 15429632 2788144
21:44:25 71434 8192000000 10925172800 11384991744 1979573152 19.461 100 16 32 1 1994993168 11394448 4025568
21:46:37 108134 8192000000 10834637984 11310989312 1818025168 18.162 93 30 60 2 1834763888 8247168 8491552
21:49:26 63625 8192000000 10868613744 11325120512 1905127504 18.868 98 28 56 1 1926945744 13630976 8187264
21:51:30 66843 8192000000 10845821536 11334070272 1910704000 18.912 99 12 23 2 1934858656 19644464 4510192
21:53:40 43858 8192000000 10847750512 11315441664 1911854080 18.922 99 10 20 0 1921789136 6785472 3149584
21:55:24 65707 8192000000 10856769648 11345358848 1909914320 18.906 98 6 12 0 1919780112 8396240 1469552
21:57:30 19989 8192000000 10865933168 11326869504 1895828320 18.793 97 31 62 2 1912086336 7531984 8726032
21:59:00 35797 8192000000 10883305536 11334893568 1900854608 18.833 98 30 60 3 1919759504 10678832 8226064
22:00:38 36818 8192000000 10909578016 11360788480 1948272960 19.213 99 36 72 0 1969766720 11309136 10184624
22:02:15 69728 8192000000 10874533472 11344523264 1886921504 18.721 96 13 26 0 1901099648 10640608 3537536
22:04:30 49187 8192000000 10897889632 11366408192 1951152720 19.236 99 26 51 1 1975162160 16782880 7226560
22:06:21 43805 8192000000 10899236592 11369545728 1950708960 19.232 99 32 64 1 1969310208 9773152 8828096
22:08:06 62501 8192000000 10925014912 11388874752 1985440720 19.508 100 19 38 0 1999213424 8142512 5630192
22:10:58 60571 8192000000 10912055936 11406938112 1995969712 19.591 100 26 52 2 2019829744 16482656 7377376
22:13:06 57072 8192000000 10903967056 11394654208 1964344432 19.341 99 10 19 1 1985064528 17171408 3548688
22:15:12 44213 8192000000 10900084144 11377520640 1946572992 19.199 98 25 50 2 1966203056 12549152 7080912
22:17:08 65593 8192000000 10914224560 11406921728 1966019200 19.354 99 13 26 2 1987195072 17340128 3835744
22:19:43 34347 8192000000 10892356384 11351236608 1928391040 19.054 98 25 50 2 1943185552 7399376 7395136
22:21:18 22895 8192000000 10885272016 11338711040 1899702784 18.824 97 38 76 2 1923383104 13063392 10616928
22:22:47 59198 8192000000 10901602656 11374358528 1940618576 19.152 99 25 50 1 1962115248 14439360 7057312
22:24:47 78699 8192000000 10905905072 11374649344 1948920448 19.218 99 22 44 1 1964483392 10021328 5541616
22:27:06 120361 8192000000 10884074176 11363979264 1933951008 19.098 98 24 48 2 1949177760 8548976 6677776
22:30:10 7530 8192000000 10870938528 11347369984 1938320080 19.133 100 32 64 1 1963600400 16291840 8988480
22:31:18 29717 8192000000 10869407024 11358998528 1951390160 19.238 100 13 26 1 1967497152 12692016 3414976
22:32:51 36742 8192000000 10877552864 11354185728 1942312720 19.165 99 14 28 1 1953895296 7454624 4127952
22:34:27 55544 8192000000 10891127888 11369746432 1951212016 19.236 99 14 28 0 1964841232 9755936 3873280
22:36:27 71901 8192000000 10889924944 11368267776 1922657456 19.008 97 16 32 2 1939888032 13412720 3817856

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-08-2021 22:39:07 to 29-08-2021 22:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-08-2021 22:39:23 to 29-08-2021 22:39:25.

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          246059
MON$OLDEST_ACTIVE               248418
MON$OLDEST_SNAPSHOT             248418
MON$NEXT_TRANSACTION            248418
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                       2034464
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:720: 
:721: mscs-test-161.budzdorov.ru    Sun Aug 29 22:37:48 2021
:722:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:723: 
:724: 
:725: mscs-test-161.budzdorov.ru    Sun Aug 29 22:37:52 2021
:726:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:727: 
--- end of diff output ---

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

29.08.21 22:39:26. 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-08-29 22:39:29 No severe PSQL-related problems occured
29.08.21 22:39:30. End of report.