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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16147664 kB
MemAvailable 31026588 kB
Buffers 0 kB
Cached 15546416 kB
SwapCached 2576 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-13 22:37:45
DTS_END                         2021-08-13 22:37:45.4500
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/59760
TRN_ID                          244708
ATT_ID                          651
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: 9463.49 21887 1135619
customer order: creation 175.81 389 21097
customer order: refuse 14.59 366 1751
order to supplier: creation 46.38 1318 5566
order to supplier: removal 3.39 107941 407
invoice (draft): creation 46.10 3201 5532
invoice (draft): removal 54.04 742 6485
invoice accept: apply 42.53 16870 5103
invoice accept: cancel 49.11 852 5893
customer reserve: creation 3886.47 168 466376
customer reserve: removal 4830.38 61 579646
realization accept: apply 84.38 198 10125
realization accept: cancel 99.37 106 11924
payment from customer: creation 59.63 127 7155
payment from customer: removal 5.76 89 691
payment to supplier: creation 49.52 160 5942
payment to supplier: removal 3.58 63 429
service: total inventory turnovers 4.96 1061 595
service: total monetary turnovers 6.65 31 798
service: aggregate perf. data 0.83 17247 100
service: refresh index statistics 0.03 286750 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2021 22:39:36 to 13-08-2021 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3474
TEST_TIME 2 3972
TEST_TIME 3 4180
TEST_TIME 4 4783
TEST_TIME 5 4894
TEST_TIME 6 4566
TEST_TIME 7 4557
TEST_TIME 8 4430
TEST_TIME 9 4499
TEST_TIME 10 4571
TEST_TIME 11 4768
TEST_TIME 12 4885
TEST_TIME 13 5165
TEST_TIME 14 5480
TEST_TIME 15 5807
TEST_TIME 16 6659
TEST_TIME 17 7327
TEST_TIME 18 7303
TEST_TIME 19 7234
TEST_TIME 20 7638
TEST_TIME 21 8072
TEST_TIME 22 8379
TEST_TIME 23 8669
TEST_TIME 24 8701
TEST_TIME 25 9169
TEST_TIME 26 9496
TEST_TIME 27 9751
TEST_TIME 28 10441
TEST_TIME 29 10689
TEST_TIME 30 10612
TEST_TIME 31 10702
TEST_TIME 32 10652
TEST_TIME 33 10494
TEST_TIME 34 10281
TEST_TIME 35 10296
TEST_TIME 36 10362
TEST_TIME 37 10325
TEST_TIME 38 10222
TEST_TIME 39 10078
TEST_TIME 40 10011
TEST_TIME 41 9861
TEST_TIME 42 9824
TEST_TIME 43 10126
TEST_TIME 44 10161
TEST_TIME 45 10059
TEST_TIME 46 9924
TEST_TIME 47 9793
TEST_TIME 48 9773
TEST_TIME 49 9831
TEST_TIME 50 9713
TEST_TIME 51 9607
TEST_TIME 52 9580
TEST_TIME 53 9503
TEST_TIME 54 9484
TEST_TIME 55 9438
TEST_TIME 56 9499
TEST_TIME 57 9480
TEST_TIME 58 9497
TEST_TIME 59 9567
TEST_TIME 60 9598
TEST_TIME 61 9681
TEST_TIME 62 9636
TEST_TIME 63 9745
TEST_TIME 64 9839
TEST_TIME 65 9873
TEST_TIME 66 10073
TEST_TIME 67 10033
TEST_TIME 68 9945
TEST_TIME 69 9846
TEST_TIME 70 9873
TEST_TIME 71 9805
TEST_TIME 72 9741
TEST_TIME 73 9694
TEST_TIME 74 9630
TEST_TIME 75 9618
TEST_TIME 76 9710
TEST_TIME 77 9673
TEST_TIME 78 9652
TEST_TIME 79 9628
TEST_TIME 80 9596
TEST_TIME 81 9655
TEST_TIME 82 9643
TEST_TIME 83 9635
TEST_TIME 84 9744
TEST_TIME 85 9723
TEST_TIME 86 9740
TEST_TIME 87 9726
TEST_TIME 88 9762
TEST_TIME 89 9732
TEST_TIME 90 9669
TEST_TIME 91 9599
TEST_TIME 92 9598
TEST_TIME 93 9552
TEST_TIME 94 9550
TEST_TIME 95 9502
TEST_TIME 96 9579
TEST_TIME 97 9547
TEST_TIME 98 9523
TEST_TIME 99 9549
TEST_TIME 100 9587
TEST_TIME 101 9605
TEST_TIME 102 9581
TEST_TIME 103 9637
TEST_TIME 104 9659
TEST_TIME 105 9630
TEST_TIME 106 9645
TEST_TIME 107 9698
TEST_TIME 108 9698
TEST_TIME 109 9754
TEST_TIME 110 9744
TEST_TIME 111 9682
TEST_TIME 112 9625
TEST_TIME 113 9636
TEST_TIME 114 9587
TEST_TIME 115 9556
TEST_TIME 116 9509
TEST_TIME 117 9494
TEST_TIME 118 9475
TEST_TIME 119 9471
TEST_TIME 120 9464

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-08-2021 22:39:39 to 13-08-2021 22:39:43.

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 1568077 1568077 0 0.00 0 24633 6 0 0
doc_list_biud 1568076 1568076 0 0.00 0 26897 28 0 0
sp_add_invoice_to_stock 5203 5103 100 1.92 37 189485 16871 0 100
sp_cancel_adding_invoice 5893 5893 0 0.00 6 87670 852 0 0
sp_cancel_client_order 1751 1751 0 0.00 4 13913 366 0 0
sp_cancel_customer_reserve 579646 579646 0 0.00 1 21755 61 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 5803 89 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 1842 63 0 0
sp_cancel_supplier_invoice 6490 6485 5 0.07 2 15939 743 0 5
sp_cancel_supplier_order 407 407 0 0.00 9 994829 107942 0 0
sp_cancel_write_off 11924 11924 0 0.00 1 22008 107 0 0
sp_client_order 21097 21097 0 0.00 3 18155 390 0 0
sp_customer_reserve 466376 466376 0 0.00 0 126539 168 0 0
sp_fill_shopping_cart 32176 32176 0 0.00 1 10482 35 0 0
sp_fill_shopping_cart_clo_res 466366 466366 0 0.00 0 15359 15 0 0
sp_get_clo_for_invoice 5093 5093 0 0.00 0 32572 339 0 0
sp_kill_cost_storno 18937 18937 0 0.00 0 22000 55 0 0
sp_kill_qstorno_ret_qs2qd 1178108 1178108 0 0.00 0 21753 33 0 0
sp_kill_qty_storno 621324 621324 0 0.00 0 26896 70 0 0
sp_lock_selected_doc 21034 21034 0 0.00 0 6533 4 0 0
sp_make_cost_storno 28315 28315 0 0.00 0 24331 58 0 0
sp_multiply_rows_for_pdistr 28315 28315 0 0.00 0 24137 40 0 0
sp_multiply_rows_for_qdistr 21097 21097 0 0.00 0 12229 197 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 24165 128 0 0
sp_pay_to_supplier 5942 5942 0 0.00 5 23644 161 0 0
sp_payment_common 13097 13097 0 0.00 1 23611 46 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 13902 336 0 0
sp_qd_handle_on_invoice_upd_sts 10986 10986 0 0.00 0 26894 317 0 0
sp_qd_handle_on_reserve_upd_sts 10125 10125 0 0.00 0 13477 52 0 0
sp_reserve_write_off 10125 10125 0 0.00 6 24333 199 0 0
sp_supplier_invoice 5532 5532 0 0.00 7 97506 3201 0 0
sp_supplier_order 5566 5566 0 0.00 5 39068 1319 0 0
srv_aggregate_perf_data 100 100 0 0.00 2766 119923 17248 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1613 170037 47586 0 0
srv_find_qd_qs_mism 1087601 1087601 0 0.00 0 12714 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 19 52727 1062 0 0
srv_make_money_saldo 798 798 0 0.00 1 3429 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 102413 377219 286750 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 5103 5103 0 0.00 0 23864 182 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 9766 159 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 3809 9 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 4289 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 3558 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 394 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 3294 20 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 23093 1648 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 395 4 0 0
t$perf-norm:sp_client_order 21097 21097 0 0.00 0 14495 20 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 6057 13 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 14550 15 0 0
t$perf-norm:sp_pay_to_supplier 5943 5943 0 0.00 0 9209 11 0 0
t$perf-norm:sp_reserve_write_off 10125 10125 0 0.00 0 6948 11 0 0
t$perf-norm:sp_supplier_invoice 5532 5532 0 0.00 0 7819 16 0 0
t$perf-norm:sp_supplier_order 5566 5566 0 0.00 0 4390 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 582 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 112 5 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 622 14 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 6736 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10377 10177 200 1.92 0 20973 25 0 200
v_all_customers 38634 38634 0 0.00 0 136 1 0 0
v_all_suppliers 30674 30674 0 0.00 0 24 1 0 0
v_all_wares 42192 42192 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1163 8 0 0
v_cancel_client_order 3502 3502 0 0.00 0 3452 9 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 5748 15 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 14051 17 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 1836 9 0 0
v_cancel_supplier_invoice 878 868 10 1.13 0 4295 22 0 10
v_cancel_supplier_order 814 814 0 0.00 0 11843 26 0 0
v_cancel_write_off 2016 2016 0 0.00 0 3175 17 0 0
v_max_id_clo_ord 5561 5561 0 0.00 0 1191 2 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 3162 14 0 0
v_max_id_ord_sup 5519 5519 0 0.00 0 3556 4 0 0
v_max_non_paid_invoice 1685 1685 0 0.00 0 1163 3 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 4562 5 0 0
v_min_id_clo_ord 5561 5561 0 0.00 0 5442 8 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 4280 18 0 0
v_min_id_ord_sup 5519 5519 0 0.00 0 6051 10 0 0
v_min_non_paid_invoice 5942 5942 0 0.00 0 23605 46 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 23191 26 0 0
v_our_firm 10586 10586 0 0.00 0 16 1 0 0
v_reserve_write_off 20250 20250 0 0.00 0 17817 10 0 0
x_lock_depdocs_on_canc_invoice 11944 11944 0 0.00 0 14553 107 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 9354 327 0 0
x_make_qstorno_1000_1200 5566 5566 0 0.00 2 39032 1242 0 0
x_make_qstorno_1200_2000 5532 5532 0 0.00 2 93264 3080 0 0
x_make_qstorno_2100_3300 466376 466376 0 0.00 0 126500 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2021 22:39:44 to 13-08-2021 22:39:48.

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:49 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:49 34182 8192000000 10366656640 10683527168 1413269360 14.713 100 88 176 0 1448497184 6429744 28798080
20:10:28 27614 8192000000 10426479296 10755391488 1473838640 15.247 100 87 174 1 1510243936 8666608 27738688
20:12:00 13867 8192000000 10469398256 10812194816 1530140272 15.738 100 80 160 1 1565749200 10583552 25025376
20:13:18 13902 8192000000 10572121312 10931142656 1629026640 16.587 100 79 157 3 1663383040 10802416 23553984
20:14:39 11062 8192000000 10675385136 11043553280 1728352592 17.422 100 83 166 2 1764689840 12743888 23593360
20:15:52 4905 8192000000 10735368688 11115196416 1793005056 17.956 100 86 169 1 1829816912 13305376 23506480
20:16:58 2159 8192000000 10772849024 11158130688 1833157984 18.285 100 90 180 0 1872488608 13879088 25451536
20:18:01 1394 8192000000 10814661024 11203092480 1871175632 18.594 100 87 175 2 1909415504 12692208 25547664
20:19:04 1514 8192000000 10820963808 11214258176 1883090544 18.690 100 91 182 2 1920242800 11680544 25471712
20:20:06 5874 8192000000 10813905152 11225288704 1891436288 18.757 100 37 73 3 1907607408 5609536 10561584
20:21:13 5172 8192000000 10832608144 11257081856 1910649968 18.912 100 35 70 1 1931700352 11602816 9447568
20:22:21 10744 8192000000 10846826960 11255746560 1915595376 18.952 100 19 37 1 1926709056 5964064 5149616
20:23:32 33106 8192000000 10843328224 11273383936 1903360288 18.853 99 32 64 4 1917497328 4893168 9243872
20:25:11 6887 8192000000 10855142096 11285393408 1931036528 19.075 100 33 66 2 1947820256 7763968 9019760
20:26:20 4975 8192000000 10821838576 11254710272 1905920752 18.874 100 19 38 2 1917383536 6470784 4992000
20:27:26 45614 8192000000 10806493648 11235676160 1849405136 18.417 97 20 40 0 1860096672 4951120 5740416
20:29:16 6685 8192000000 10729478960 11132469248 1782044928 17.866 98 34 68 0 1798648592 6154928 10448736
20:30:23 4885 8192000000 10745168592 11154247680 1802406416 18.034 99 39 79 1 1821207536 7927600 10873520
20:31:30 57603 8192000000 10723544176 11138289664 1771945472 17.783 96 33 67 1 1786273904 4578896 9749536
20:33:33 5478 8192000000 10780050544 11197534208 1856708720 18.477 100 25 50 2 1869851376 5633296 7509360
20:34:41 8285 8192000000 10779073568 11211112448 1866435808 18.555 100 22 44 0 1879126992 5657472 7033712
20:35:50 39096 8192000000 10798960528 11228618752 1880909808 18.672 100 22 44 2 1892765072 5473040 6382224
20:37:31 4472 8192000000 10836440128 11258626048 1909149584 18.900 100 40 82 1 1926650096 5903488 11597024
20:38:40 28083 8192000000 10859669440 11291619328 1934877440 19.106 100 42 84 2 1955147616 7538128 12732048
20:40:22 11125 8192000000 10905600848 11329822720 1961607488 19.319 100 68 135 1 1993058224 12318160 19132576
20:41:37 52477 8192000000 10916367440 11358388224 1962468512 19.326 99 38 76 1 1992767824 18964448 11334864
20:43:40 35041 8192000000 10933363712 11380236288 1973643648 19.414 99 26 52 3 2005320976 23576528 8100800
20:45:17 10479 8192000000 10970793696 11406639104 2013016224 19.725 100 51 102 0 2059493824 31236304 15241296
20:46:34 4119 8192000000 10961498560 11392348160 2001753584 19.637 100 53 104 0 2048769760 31348064 15668112
20:47:43 14138 8192000000 10960450624 11395440640 1988628368 19.533 99 51 101 1 2039284304 34736464 15919472
20:49:00 3753 8192000000 10977153456 11409055744 2007420112 19.681 100 59 118 1 2063878928 38174208 18284608
20:50:09 6420 8192000000 10967949520 11392806912 1986629840 19.517 100 64 128 1 2050283072 43580240 20072992
20:51:23 4360 8192000000 10952996624 11372396544 1955364768 19.269 98 77 152 0 2027724464 48495904 23863792
20:52:33 4229 8192000000 10969693632 11390689280 1973155920 19.410 100 81 162 0 2055849712 57671072 25022720
20:53:44 5847 8192000000 10953127296 11380793344 1957219792 19.284 100 62 121 1 2037091664 59966976 19904896
20:54:52 3976 8192000000 10926417216 11349753856 1945316688 19.189 100 60 120 1 2008222016 44734016 18171312
20:56:10 1612 8192000000 10973070288 11389145088 1973692896 19.415 100 77 152 0 2048087328 51457536 22936896
20:57:16 6896 8192000000 10952569840 11394953216 1965003520 19.346 100 39 78 0 2039103648 60404720 13695408
20:58:28 20733 8192000000 10909155456 11358535680 1916718528 18.961 98 38 75 1 1993245344 63507648 13019168
20:59:52 12006 8192000000 10939037936 11379077120 1955951120 19.274 100 41 82 1 2017368896 47708944 13708832
21:01:07 19865 8192000000 10902550592 11346276352 1916451744 18.958 99 30 60 0 1973420128 46639248 10329136
21:02:31 11900 8192000000 10912491216 11354275840 1938159872 19.132 100 31 62 1 1993353632 44459280 10734480
21:03:59 30365 8192000000 10928201568 11372351488 1935824832 19.113 99 22 44 1 1987376656 44115200 7436624
21:05:32 48925 8192000000 10885969392 11367759872 1926421728 19.038 99 5 10 0 1957863712 28587184 2854800
21:07:22 16568 8192000000 10894850304 11346022400 1941767136 19.161 99 21 40 3 1960774704 13301648 5705920
21:08:40 110713 8192000000 10899056560 11355594752 1969851904 19.384 100 19 38 2 1987751632 12679728 5220000
21:11:36 104334 8192000000 10913785472 11376410624 1963286864 19.332 99 22 44 0 1981350016 11983808 6079344
21:14:32 170037 8192000000 10946002560 11403509760 1976934192 19.440 99 37 74 1 2002815328 14990176 10890960
21:18:30 32510 8192000000 10975929376 11416121344 2001267664 19.633 99 31 62 1 2027951584 17342304 9341616
21:20:05 47490 8192000000 10922273520 11405590528 1994923072 19.583 100 15 30 1 2025607328 25416032 5268224
21:21:54 22160 8192000000 10916568736 11394400256 2000106224 19.624 100 8 17 1 2013749648 11345760 2297664
21:23:18 67402 8192000000 10937431104 11400245248 1989633344 19.541 99 13 26 0 2003484192 9741232 4109616
21:26:02 43397 8192000000 10913337248 11395674112 1986619744 19.517 99 12 24 2 1999727888 9146672 3961472
21:27:46 73882 8192000000 10903075152 11379646464 1972950992 19.409 99 38 75 0 1990673552 6743856 10978704
21:31:14 56709 8192000000 10871133776 11347980288 1872643312 18.606 95 6 12 1 1889020160 14904496 1472352
21:33:12 56171 8192000000 10893370672 11362430976 1955048944 19.267 99 21 42 1 1973586912 12297776 6240192
21:35:10 32793 8192000000 10901965136 11367432192 1976399264 19.436 100 24 49 1 1992981968 9169936 7412768
21:36:53 59867 8192000000 10912326400 11367993344 1900936752 18.834 96 25 50 1 1922252000 13946288 7368960
21:39:09 52358 8192000000 10865973984 11325378560 1838351936 18.327 94 12 24 0 1855167232 13591552 3223744
21:41:08 64371 8192000000 10909236000 11368361984 1940386064 19.150 99 14 27 0 1970811200 27070688 3354448
21:43:24 74092 8192000000 10892731504 11349729280 1884355824 18.700 96 16 32 0 1900550528 11712880 4481824
21:45:44 59872 8192000000 10891113168 11336585216 1947301792 19.205 100 52 103 0 1973215664 11202224 14711648
21:47:44 59419 8192000000 10852656144 11320913920 1889399152 18.741 97 22 44 1 1904751952 10143168 5209632
21:49:44 64734 8192000000 10860819200 11332112384 1909181520 18.900 98 12 24 1 1926942544 14309424 3451600
21:51:49 51119 8192000000 10861997776 11336937472 1920730992 18.993 99 19 38 0 1945182496 18279552 6171952
21:53:59 71417 8192000000 10886196192 11351883776 1936046752 19.115 99 23 44 1 1947887584 6409696 5431136
21:56:19 51797 8192000000 10905879888 11366051840 1949067488 19.219 99 20 40 0 1963107504 8513792 5526224
21:58:15 51804 8192000000 10903046624 11374428160 1960296576 19.308 99 13 26 1 1972930304 8846352 3787376
22:00:10 51616 8192000000 10911558960 11382681600 1946085872 19.195 98 16 32 0 1964404336 13277184 5041280
22:02:02 102995 8192000000 10913368256 11388203008 1933976624 19.099 97 31 62 3 1954773824 12314912 8482288
22:04:47 50368 8192000000 10914035280 11380908032 1961511600 19.318 99 22 43 2 1977942736 10521056 5910080
22:06:38 124708 8192000000 10885285056 11350503424 1953121776 19.251 98 34 68 2 1971006528 7937920 9946832
22:09:44 65840 8192000000 10911372080 11398045696 1982375824 19.484 99 11 22 1 1998060960 12417056 3268080
22:11:50 73385 8192000000 10913205616 11386138624 1918257088 18.973 96 13 27 1 1934548864 12611424 3680352
22:14:08 36248 8192000000 10939753040 11417608192 2006247392 19.672 100 16 32 0 2022874880 11939760 4687728
22:15:48 54859 8192000000 10944965664 11433177088 2017065376 19.757 100 16 30 1 2035954304 13709264 5179664
22:17:58 58466 8192000000 10962474912 11435692032 2002122192 19.639 99 13 25 0 2016052000 10373232 3556576
22:19:58 70728 8192000000 10884877936 11360817152 1833567344 18.288 92 12 24 0 1850015088 13154144 3293600
22:22:09 75936 8192000000 10903034224 11368873984 1912546544 18.927 96 21 42 0 1926670256 8542624 5581088
22:24:44 56774 8192000000 10914883408 11397713920 1925063616 19.027 97 15 30 1 1943695136 14467728 4163792
22:26:41 87804 8192000000 10923245952 11420565504 1984484448 19.500 100 8 16 2 2016083872 29278960 2320464
22:30:15 38713 8192000000 10842615728 11320766464 1869612880 18.581 95 35 69 2 1887622544 8746992 9262672
22:33:06 45706 8192000000 10875115760 11340537856 1948133920 19.212 100 28 56 0 1967230880 11413488 7683472
22:34:52 92644 8192000000 10875026816 11362361344 1953178864 19.252 100 17 32 0 1973837600 15516816 5141920
22:37:25 32053 8192000000 9535805392 9867137024 682416816 7.689 35 4 7 0 687514224 4293296 804112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2021 22:39:49 to 13-08-2021 22:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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:02, from 13-08-2021 22:40:05 to 13-08-2021 22:40:07.

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          243971
MON$OLDEST_ACTIVE               245147
MON$OLDEST_SNAPSHOT             245147
MON$NEXT_TRANSACTION            245147
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                       2047584
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:240: 
:241: mscs-test-161.budzdorov.ru    Fri Aug 13 22:38:05 2021
:242:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:243: 
:244: 
:245: mscs-test-161.budzdorov.ru    Fri Aug 13 22:38:06 2021
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:247: 
:248: 
:249: mscs-test-161.budzdorov.ru    Fri Aug 13 22:38:06 2021
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
--- end of diff output ---

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

13.08.21 22:40:08. 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-13 22:40:10 No severe PSQL-related problems occured
13.08.21 22:40:12. End of report.