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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16096072 kB
MemAvailable 30997608 kB
Buffers 0 kB
Cached 15527568 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-07 01:37:45
DTS_END                         2021-08-07 01:37:45.8890
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/36534
TRN_ID                          243210
ATT_ID                          643
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: 9643.64 16834 1157237
customer order: creation 179.41 334 21529
customer order: refuse 15.66 363 1879
order to supplier: creation 47.23 1264 5667
order to supplier: removal 3.54 95003 425
invoice (draft): creation 47.45 3480 5694
invoice (draft): removal 54.48 749 6537
invoice accept: apply 44.22 16605 5306
invoice accept: cancel 49.63 766 5955
customer reserve: creation 4016.14 167 481937
customer reserve: removal 4886.08 54 586329
realization accept: apply 85.73 198 10288
realization accept: cancel 80.24 113 9629
payment from customer: creation 62.10 133 7452
payment from customer: removal 5.61 74 673
payment to supplier: creation 50.16 154 6019
payment to supplier: removal 3.65 68 438
service: total inventory turnovers 4.72 1094 567
service: total monetary turnovers 6.78 49 814
service: aggregate perf. data 0.79 24905 95
service: refresh index statistics 0.03 191097 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-08-2021 01:38:40 to 07-08-2021 01:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5104
TEST_TIME 2 3618
TEST_TIME 3 4906
TEST_TIME 4 5457
TEST_TIME 5 5244
TEST_TIME 6 5075
TEST_TIME 7 5502
TEST_TIME 8 5523
TEST_TIME 9 5423
TEST_TIME 10 5455
TEST_TIME 11 5886
TEST_TIME 12 6257
TEST_TIME 13 6611
TEST_TIME 14 6456
TEST_TIME 15 6401
TEST_TIME 16 6375
TEST_TIME 17 6496
TEST_TIME 18 6822
TEST_TIME 19 6959
TEST_TIME 20 7044
TEST_TIME 21 7389
TEST_TIME 22 7997
TEST_TIME 23 8011
TEST_TIME 24 8223
TEST_TIME 25 8882
TEST_TIME 26 8891
TEST_TIME 27 9130
TEST_TIME 28 9328
TEST_TIME 29 9906
TEST_TIME 30 10338
TEST_TIME 31 10411
TEST_TIME 32 10292
TEST_TIME 33 10299
TEST_TIME 34 10314
TEST_TIME 35 10191
TEST_TIME 36 10199
TEST_TIME 37 10229
TEST_TIME 38 10257
TEST_TIME 39 10303
TEST_TIME 40 10118
TEST_TIME 41 9936
TEST_TIME 42 9963
TEST_TIME 43 9879
TEST_TIME 44 9829
TEST_TIME 45 9832
TEST_TIME 46 9775
TEST_TIME 47 9777
TEST_TIME 48 9825
TEST_TIME 49 9897
TEST_TIME 50 9787
TEST_TIME 51 9738
TEST_TIME 52 9722
TEST_TIME 53 9611
TEST_TIME 54 9596
TEST_TIME 55 9568
TEST_TIME 56 9523
TEST_TIME 57 9499
TEST_TIME 58 9481
TEST_TIME 59 9458
TEST_TIME 60 9471
TEST_TIME 61 9529
TEST_TIME 62 9558
TEST_TIME 63 9567
TEST_TIME 64 9675
TEST_TIME 65 9665
TEST_TIME 66 9721
TEST_TIME 67 9659
TEST_TIME 68 9710
TEST_TIME 69 9823
TEST_TIME 70 9798
TEST_TIME 71 9907
TEST_TIME 72 9862
TEST_TIME 73 9847
TEST_TIME 74 9722
TEST_TIME 75 9760
TEST_TIME 76 9764
TEST_TIME 77 9694
TEST_TIME 78 9672
TEST_TIME 79 9606
TEST_TIME 80 9620
TEST_TIME 81 9556
TEST_TIME 82 9556
TEST_TIME 83 9504
TEST_TIME 84 9440
TEST_TIME 85 9472
TEST_TIME 86 9518
TEST_TIME 87 9512
TEST_TIME 88 9517
TEST_TIME 89 9572
TEST_TIME 90 9589
TEST_TIME 91 9576
TEST_TIME 92 9581
TEST_TIME 93 9651
TEST_TIME 94 9692
TEST_TIME 95 9646
TEST_TIME 96 9671
TEST_TIME 97 9659
TEST_TIME 98 9721
TEST_TIME 99 9701
TEST_TIME 100 9714
TEST_TIME 101 9692
TEST_TIME 102 9612
TEST_TIME 103 9619
TEST_TIME 104 9568
TEST_TIME 105 9597
TEST_TIME 106 9541
TEST_TIME 107 9576
TEST_TIME 108 9555
TEST_TIME 109 9514
TEST_TIME 110 9512
TEST_TIME 111 9550
TEST_TIME 112 9523
TEST_TIME 113 9550
TEST_TIME 114 9538
TEST_TIME 115 9543
TEST_TIME 116 9617
TEST_TIME 117 9641
TEST_TIME 118 9633
TEST_TIME 119 9625
TEST_TIME 120 9648

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-08-2021 01:38:43 to 07-08-2021 01:38:47.

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 1602009 1602009 0 0.00 0 21294 6 0 0
doc_list_biud 1602009 1602009 0 0.00 0 25938 25 0 0
sp_add_invoice_to_stock 5408 5306 102 1.88 32 222107 16606 0 102
sp_cancel_adding_invoice 5955 5955 0 0.00 5 57137 767 0 0
sp_cancel_client_order 1879 1879 0 0.00 4 14364 363 0 0
sp_cancel_customer_reserve 586329 586329 0 0.00 1 23762 54 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 2 2894 74 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 5352 69 0 0
sp_cancel_supplier_invoice 6548 6537 11 0.16 2 20847 750 0 11
sp_cancel_supplier_order 425 425 0 0.00 5 1003211 95004 0 0
sp_cancel_write_off 9629 9629 0 0.00 1 18371 114 0 0
sp_client_order 21529 21529 0 0.00 3 29493 334 0 0
sp_customer_reserve 481937 481937 0 0.00 0 111028 168 0 0
sp_fill_shopping_cart 32885 32885 0 0.00 1 24477 40 0 0
sp_fill_shopping_cart_clo_res 481928 481928 0 0.00 0 14736 13 0 0
sp_get_clo_for_invoice 5297 5297 0 0.00 0 17383 256 0 0
sp_kill_cost_storno 16695 16695 0 0.00 0 4797 52 0 0
sp_kill_qstorno_ret_qs2qd 1189249 1189249 0 0.00 0 23754 29 0 0
sp_kill_qty_storno 626339 626339 0 0.00 0 25938 64 0 0
sp_lock_selected_doc 21565 21565 0 0.00 0 7110 5 0 0
sp_make_cost_storno 29056 29056 0 0.00 0 21293 56 0 0
sp_multiply_rows_for_pdistr 29056 29056 0 0.00 0 11375 29 0 0
sp_multiply_rows_for_qdistr 21529 21529 0 0.00 0 15985 128 0 0
sp_pay_from_customer 7452 7452 0 0.00 4 16842 134 0 0
sp_pay_to_supplier 6019 6019 0 0.00 5 15576 155 0 0
sp_payment_common 13471 13471 0 0.00 1 14878 34 0 0
sp_qd_handle_on_cancel_clo 1879 1879 0 0.00 0 9827 318 0 0
sp_qd_handle_on_invoice_upd_sts 11252 11252 0 0.00 0 25937 332 0 0
sp_qd_handle_on_reserve_upd_sts 10288 10288 0 0.00 0 14937 60 0 0
sp_reserve_write_off 10288 10288 0 0.00 7 23374 198 0 0
sp_supplier_invoice 5694 5694 0 0.00 7 79869 3480 0 0
sp_supplier_order 5667 5667 0 0.00 5 81159 1265 0 0
srv_aggregate_perf_data 95 95 0 0.00 2826 409240 24906 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2153 187780 48546 0 0
srv_find_qd_qs_mism 1106890 1106890 0 0.00 0 10857 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 23 65537 1094 0 0
srv_make_money_saldo 814 814 0 0.00 1 15853 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 98914 322976 191097 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5307 5307 0 0.00 0 15833 152 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 5688 110 0 0
t$perf-norm:sp_cancel_client_order 1879 1879 0 0.00 0 3599 14 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 1322 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 1265 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 1429 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1342 15 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 27535 1583 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 14241 21 0 0
t$perf-norm:sp_client_order 21529 21529 0 0.00 0 15761 21 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 2208 10 0 0
t$perf-norm:sp_pay_from_customer 7452 7452 0 0.00 0 7034 13 0 0
t$perf-norm:sp_pay_to_supplier 6019 6019 0 0.00 0 14105 12 0 0
t$perf-norm:sp_reserve_write_off 10288 10288 0 0.00 0 13188 14 0 0
t$perf-norm:sp_supplier_invoice 5694 5694 0 0.00 0 4899 17 0 0
t$perf-norm:sp_supplier_order 5667 5667 0 0.00 0 7664 19 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 194 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 239 6 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 1092 17 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 823 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 4 0 0
v_add_invoice_to_stock 10798 10594 204 1.88 0 13406 23 0 204
v_all_customers 39248 39248 0 0.00 0 19 1 0 0
v_all_suppliers 30866 30866 0 0.00 0 22 1 0 0
v_all_wares 43056 43056 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 4107 11 0 0
v_cancel_client_order 3758 3758 0 0.00 0 14265 10 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 2260 7 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 4565 15 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 1042 5 0 0
v_cancel_supplier_invoice 822 800 22 2.67 0 15273 39 0 22
v_cancel_supplier_order 850 850 0 0.00 0 407 3 0 0
v_cancel_write_off 2044 2044 0 0.00 0 4296 13 0 0
v_max_id_clo_ord 5667 5667 0 0.00 0 16857 5 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 3826 22 0 0
v_max_id_ord_sup 5690 5690 0 0.00 0 4771 6 0 0
v_max_non_paid_invoice 1943 1943 0 0.00 0 727 3 0 0
v_max_non_paid_realizn 3924 3924 0 0.00 0 1909 3 0 0
v_min_id_clo_ord 5667 5667 0 0.00 0 3583 8 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 3263 10 0 0
v_min_id_ord_sup 5690 5690 0 0.00 0 5392 11 0 0
v_min_non_paid_invoice 6019 6019 0 0.00 0 5378 21 0 0
v_min_non_paid_realizn 7452 7452 0 0.00 0 8352 20 0 0
v_our_firm 10864 10864 0 0.00 0 17 1 0 0
v_reserve_write_off 20576 20576 0 0.00 0 14267 9 0 0
x_lock_depdocs_on_canc_invoice 12092 12092 0 0.00 0 19976 106 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 17398 423 0 0
x_make_qstorno_1000_1200 5667 5667 0 0.00 2 80957 1176 0 0
x_make_qstorno_1200_2000 5694 5694 0 0.00 3 79855 3337 0 0
x_make_qstorno_2100_3300 481937 481937 0 0.00 0 111026 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-08-2021 01:38:48 to 07-08-2021 01:38:52.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:49 12479 8192000000 10364896656 10682220544 1421354768 14.785 100 86 172 0 1454600864 5733872 27512224
23:10:06 26379 8192000000 10434880256 10763214848 1487582704 15.368 100 96 192 0 1526571280 8113440 30875136
23:11:42 22051 8192000000 10478111504 10816901120 1530716688 15.743 100 88 176 1 1568070816 9513488 27840640
23:13:07 9967 8192000000 10562616080 10915672064 1607671376 16.405 100 80 160 0 1642983168 10927328 24384464
23:14:19 8083 8192000000 10626284912 10998890496 1691550112 17.114 100 76 151 2 1725064320 11433584 22080624
23:15:30 5860 8192000000 10712872544 11084742656 1765947664 17.734 100 87 174 0 1804439568 13774512 24717392
23:16:40 2637 8192000000 10753688288 11136786432 1814723312 18.135 100 87 171 1 1851537872 12941664 23872896
23:17:44 1852 8192000000 10798283216 11185770496 1853607248 18.451 100 89 176 1 1890265536 12501360 24156928
23:18:48 1306 8192000000 10834096992 11224678400 1886939712 18.721 100 88 174 2 1924297168 13639232 23718224
23:19:50 3546 8192000000 10811035920 11228528640 1891059808 18.754 100 83 168 1 1928761440 13945824 23755808
23:21:01 8177 8192000000 10813917856 11237601280 1893238128 18.772 100 33 66 1 1909056768 6394016 9424624
23:22:11 6920 8192000000 10813671264 11248603136 1906229648 18.876 100 34 68 1 1926718464 10888160 9600656
23:23:25 12827 8192000000 10817906992 11254140928 1910782640 18.913 100 12 24 0 1919878048 5047168 4048240
23:24:39 49413 8192000000 10853211536 11275624448 1920472624 18.991 100 34 68 0 1935614592 5415968 9726000
23:26:51 6019 8192000000 10809096080 11241091072 1865070368 18.544 97 24 49 2 1877506608 5518336 6917904
23:27:57 8716 8192000000 10806173392 11236114432 1859719984 18.501 98 26 52 1 1877028336 10219712 7088640
23:29:06 8088 8192000000 10737133632 11157680128 1762688848 17.707 96 19 38 2 1773988800 6192208 5107744
23:30:16 23738 8192000000 10721270112 11138293760 1703010688 17.210 93 18 36 1 1713358032 5507136 4840208
23:31:41 6418 8192000000 10715768528 11133517824 1789109200 17.924 99 32 63 0 1804628288 6516656 9002432
23:32:51 9620 8192000000 10737366752 11157762048 1806454096 18.067 99 16 32 0 1816546528 5488400 4604032
23:34:04 69753 8192000000 10765926944 11192373248 1854658528 18.460 100 39 78 1 1872044288 5733360 11652400
23:36:14 8233 8192000000 10789360512 11215028224 1873007456 18.609 100 32 64 0 1888575136 6366528 9201152
23:37:22 16777 8192000000 10813523248 11244683264 1899157552 18.820 100 18 33 1 1908648288 4972832 4517904
23:38:48 25350 8192000000 10870484592 11284979712 1934391808 19.102 100 73 144 0 1965885200 10808208 20685184
23:40:19 5815 8192000000 10867557456 11300347904 1943588560 19.175 100 49 98 0 1967824992 10515936 13720496
23:41:36 5854 8192000000 10891512800 11324338176 1963004096 19.330 100 54 108 0 1989429792 10915424 15510272
23:42:46 4170 8192000000 10917803568 11347480576 1979693952 19.462 100 52 103 1 2006529520 12556096 14279472
23:43:53 15867 8192000000 10937958992 11368083456 1997307424 19.601 100 65 128 1 2031705408 16021744 18376240
23:45:38 11645 8192000000 10953086800 11385122816 2010049840 19.702 100 56 111 1 2044911872 18466720 16395312
23:46:55 3234 8192000000 10966276384 11394867200 2011382080 19.712 100 65 128 2 2053483152 21807264 20293808
23:48:02 4135 8192000000 10928648000 11367510016 1984941856 19.504 100 39 76 0 2021738192 24975728 11820608
23:49:11 2605 8192000000 10955222368 11379326976 1992942896 19.567 100 71 141 1 2038056960 24224352 20889712
23:50:19 5857 8192000000 10956784752 11389906944 1997438256 19.603 100 58 113 1 2040585216 25476416 17670544
23:51:28 10626 8192000000 10927188736 11372535808 1989348112 19.539 100 37 74 0 2022653280 21533440 11771728
23:52:45 8064 8192000000 10947512368 11375443968 1975352592 19.428 99 63 126 1 2023900160 29513376 19034192
23:54:02 10821 8192000000 10961573424 11391500288 1996245056 19.593 100 59 118 0 2046171040 31640672 18285312
23:55:22 3962 8192000000 10963310256 11384483840 1993162656 19.569 100 69 137 1 2046985504 33250160 20572688
23:56:31 3910 8192000000 10971636112 11387609088 1991479920 19.555 100 89 178 0 2060247008 40712064 28055024
23:57:44 2153 8192000000 10967983552 11382362112 1975231920 19.427 99 74 148 1 2043739760 45679152 22828688
23:58:54 8708 8192000000 10936289344 11374125056 1940881728 19.154 98 55 110 2 2008446720 49102080 18462912
00:00:10 12220 8192000000 10947698784 11381383168 1984684768 19.502 100 44 88 1 2035347280 35991040 14671472
00:01:31 7382 8192000000 10964058512 11397074944 1983541552 19.493 100 43 86 0 2042528848 42674720 16312576
00:02:41 22095 8192000000 10925920000 11378319360 1969207056 19.379 100 29 58 0 2011973952 33323536 9443360
00:04:06 17017 8192000000 10912340832 11348217856 1932127184 19.084 99 39 78 1 1985540992 39424768 13989040
00:05:25 24015 8192000000 10881116400 11334541312 1917662528 18.968 99 34 68 1 1964161456 35271712 11227216
00:06:54 35269 8192000000 10877408496 11322544128 1884195424 18.699 97 13 26 0 1915338400 26329280 4813696
00:08:30 78719 8192000000 10876588336 11327311872 1917212000 18.964 99 4 8 1 1934086736 15851504 1023232
00:10:50 53764 8192000000 10899203872 11366322176 1977650896 19.446 100 25 46 3 1993130864 8844336 6635632
00:12:47 116803 8192000000 10907074384 11360014336 1938451584 19.134 98 14 28 0 1955684688 13151008 4082096
00:15:45 187780 8192000000 10926227024 11376812032 1948815376 19.217 98 17 34 0 1964146672 10406544 4924752
00:19:55 79048 8192000000 10914778160 11369222144 1929235424 19.061 97 33 66 0 1950885952 11394736 10255792
00:22:17 96086 8192000000 10910682704 11376394240 1956054272 19.275 99 24 47 2 1971108864 8270048 6784544
00:24:59 39361 8192000000 10923669792 11377799168 1982012624 19.481 99 41 80 1 2005452000 11313600 12125776
00:26:48 95806 8192000000 10892095248 11360481280 1909198544 18.900 96 15 31 1 1930565152 17067200 4299408
00:29:26 29513 8192000000 10896918016 11372326912 1974633888 19.422 100 22 44 0 1993123904 11588704 6901312
00:31:49 55274 8192000000 10901553280 11370102784 1941801520 19.161 98 20 37 3 1956712240 9465072 5445648
00:33:45 30772 8192000000 10908333984 11384774656 1984527936 19.501 100 19 37 0 2000540928 10727648 5285344
00:35:23 82612 8192000000 10886725360 11367399424 1939111696 19.140 98 13 26 0 1957019664 14772320 3135648
00:37:46 55372 8192000000 10901761264 11375239168 1943312928 19.173 98 11 22 0 1959864368 13562240 2989200
00:39:42 79728 8192000000 10884050160 11352633344 1887782016 18.728 96 13 26 0 1904773328 13711440 3279872
00:42:06 35394 8192000000 10885401536 11347275776 1913396288 18.934 97 17 34 2 1928522992 10109472 5017232
00:43:48 49529 8192000000 10926590576 11375288320 1957055248 19.283 99 48 96 1 1983820080 13842528 12922304
00:45:39 99236 8192000000 10868120624 11353034752 1903304720 18.853 97 15 30 0 1927998400 19744224 4949456
00:48:18 120143 8192000000 10880115856 11363753984 1846192384 18.391 94 18 36 1 1873453264 22320416 4940464
00:51:18 127417 8192000000 10885434960 11372130304 1946272592 19.197 99 20 40 0 1964998496 12777776 5948128
00:54:27 21448 8192000000 10888699344 11347132416 1950577648 19.231 99 24 48 0 1964716928 7634528 6504752
00:56:33 86595 8192000000 10866883552 11345436672 1928322544 19.053 99 8 16 0 1942183040 11548528 2311968
00:59:01 42541 8192000000 10864898016 11343482880 1933647296 19.096 99 7 14 0 1948580672 12758752 2174624
01:00:44 65514 8192000000 10901491056 11353927680 1945500480 19.191 99 23 47 0 1965276640 12751968 7024192
01:03:01 69187 8192000000 10858887968 11352784896 1904019136 18.859 97 7 15 2 1917942144 12078032 1844976
01:05:10 90349 8192000000 10917525888 11372699648 1969106240 19.378 100 25 50 2 1993143328 16931664 7105424
01:07:42 41014 8192000000 10888585168 11357913088 1927308304 19.045 97 26 50 1 1942358048 8505472 6544272
01:09:37 115691 8192000000 10909605952 11394310144 1943500240 19.175 98 12 24 1 1962715120 15746464 3468416
01:12:58 65148 8192000000 10912119040 11397480448 1949590176 19.223 98 27 54 1 1968282160 11253248 7438736
01:15:03 70705 8192000000 10855790464 11325091840 1822874128 18.201 93 16 32 0 1840527232 13440624 4212480
01:17:15 76379 8192000000 10868899648 11337908224 1879752928 18.663 96 19 38 1 1894891040 9671040 5467072
01:19:31 74466 8192000000 10850818800 11321278464 1913633280 18.936 98 26 52 1 1928958560 8029376 7295904
01:22:08 50257 8192000000 10885505984 11366916096 1943783840 19.177 99 15 30 0 1960229584 12113952 4331792
01:24:09 64219 8192000000 10865761216 11354136576 1917261408 18.965 98 10 20 1 1930383440 10290464 2831568
01:26:13 51136 8192000000 10888342544 11372064768 1967250800 19.364 100 10 19 1 1982918272 13052144 2615328
01:28:16 68544 8192000000 10889378272 11364630528 1938935456 19.138 99 11 22 0 1953919440 11670096 3313888
01:30:26 49506 8192000000 10871076576 11338407936 1902186752 18.844 97 18 35 3 1916411840 8879136 5345952
01:32:54 79525 8192000000 10829813328 11310792704 1776661232 17.822 92 13 25 1 1797246064 16459520 4125312
01:35:14 28305 8192000000 10828294096 11288940544 1855921072 18.470 97 30 60 0 1876088656 11486448 8681136
01:36:48 36058 8192000000 10881924144 11336015872 1929860336 19.066 100 43 86 1 1955244656 13083216 12301104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-08-2021 01:38:53 to 07-08-2021 01:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-08-2021 01:39:09 to 07-08-2021 01:39:11.

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          241202
MON$OLDEST_ACTIVE               244462
MON$OLDEST_SNAPSHOT             244462
MON$NEXT_TRANSACTION            244462
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                       2050464
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:305: 
:306: mscs-test-161.budzdorov.ru    Sat Aug  7 01:37:55 2021
:307:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:308: 
:309: 
:310: mscs-test-161.budzdorov.ru    Sat Aug  7 01:38:02 2021
:311:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:312: 
:313: 
:314: mscs-test-161.budzdorov.ru    Sat Aug  7 01:38:02 2021
:315:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:316: 
:317: 
:318: mscs-test-161.budzdorov.ru    Sat Aug  7 01:38:03 2021
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:320: 
:321: 
:322: mscs-test-161.budzdorov.ru    Sat Aug  7 01:38:03 2021
:323:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:324: 
:325: 
:326: mscs-test-161.budzdorov.ru    Sat Aug  7 01:38:03 2021
:327:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:328: 
--- end of diff output ---

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

07.08.21 01:39:12. 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-07 01:39:15 No severe PSQL-related problems occured
07.08.21 01:39:16. End of report.