Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.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 15907020 kB
MemAvailable 30641552 kB
Buffers 0 kB
Cached 15796192 kB
SwapCached 3716 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-10-19 22:37:50
DTS_END                         2021-10-19 22:37:50.7260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37156
TRN_ID                          235298
ATT_ID                          687
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: 9314.42 20661 1117731
customer order: creation 171.93 383 20632
customer order: refuse 14.75 355 1770
order to supplier: creation 46.08 1327 5530
order to supplier: removal 3.43 98219 411
invoice (draft): creation 45.23 3748 5427
invoice (draft): removal 54.44 733 6533
invoice accept: apply 42.10 17576 5052
invoice accept: cancel 48.61 764 5833
customer reserve: creation 3864.38 175 463725
customer reserve: removal 4730.77 55 567692
realization accept: apply 83.86 185 10063
realization accept: cancel 80.80 111 9696
payment from customer: creation 59.51 98 7141
payment from customer: removal 5.57 79 668
payment to supplier: creation 47.87 127 5744
payment to supplier: removal 3.33 60 399
service: total inventory turnovers 4.38 1149 525
service: total monetary turnovers 6.56 36 787
service: aggregate perf. data 0.83 17793 99
service: refresh index statistics 0.03 270255 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2021 22:39:18 to 19-10-2021 22:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1854
TEST_TIME 2 2577
TEST_TIME 3 3534
TEST_TIME 4 3199
TEST_TIME 5 4478
TEST_TIME 6 5055
TEST_TIME 7 6581
TEST_TIME 8 6457
TEST_TIME 9 6214
TEST_TIME 10 6362
TEST_TIME 11 6423
TEST_TIME 12 6559
TEST_TIME 13 6668
TEST_TIME 14 6902
TEST_TIME 15 6897
TEST_TIME 16 7145
TEST_TIME 17 7177
TEST_TIME 18 7460
TEST_TIME 19 7524
TEST_TIME 20 7829
TEST_TIME 21 7806
TEST_TIME 22 7980
TEST_TIME 23 8255
TEST_TIME 24 8282
TEST_TIME 25 8510
TEST_TIME 26 8580
TEST_TIME 27 8821
TEST_TIME 28 9323
TEST_TIME 29 10045
TEST_TIME 30 10246
TEST_TIME 31 10223
TEST_TIME 32 10433
TEST_TIME 33 10344
TEST_TIME 34 10199
TEST_TIME 35 10168
TEST_TIME 36 10121
TEST_TIME 37 9975
TEST_TIME 38 9802
TEST_TIME 39 10112
TEST_TIME 40 9949
TEST_TIME 41 9899
TEST_TIME 42 9801
TEST_TIME 43 9834
TEST_TIME 44 9878
TEST_TIME 45 9784
TEST_TIME 46 9772
TEST_TIME 47 9726
TEST_TIME 48 9601
TEST_TIME 49 9545
TEST_TIME 50 9548
TEST_TIME 51 9514
TEST_TIME 52 9459
TEST_TIME 53 9463
TEST_TIME 54 9663
TEST_TIME 55 9683
TEST_TIME 56 9598
TEST_TIME 57 9603
TEST_TIME 58 9734
TEST_TIME 59 9808
TEST_TIME 60 9815
TEST_TIME 61 9864
TEST_TIME 62 9862
TEST_TIME 63 9951
TEST_TIME 64 9860
TEST_TIME 65 9815
TEST_TIME 66 9740
TEST_TIME 67 9706
TEST_TIME 68 9618
TEST_TIME 69 9581
TEST_TIME 70 9483
TEST_TIME 71 9568
TEST_TIME 72 9504
TEST_TIME 73 9483
TEST_TIME 74 9499
TEST_TIME 75 9450
TEST_TIME 76 9410
TEST_TIME 77 9454
TEST_TIME 78 9484
TEST_TIME 79 9489
TEST_TIME 80 9460
TEST_TIME 81 9524
TEST_TIME 82 9547
TEST_TIME 83 9530
TEST_TIME 84 9589
TEST_TIME 85 9651
TEST_TIME 86 9616
TEST_TIME 87 9701
TEST_TIME 88 9616
TEST_TIME 89 9525
TEST_TIME 90 9525
TEST_TIME 91 9523
TEST_TIME 92 9507
TEST_TIME 93 9465
TEST_TIME 94 9434
TEST_TIME 95 9424
TEST_TIME 96 9400
TEST_TIME 97 9429
TEST_TIME 98 9401
TEST_TIME 99 9415
TEST_TIME 100 9455
TEST_TIME 101 9464
TEST_TIME 102 9481
TEST_TIME 103 9471
TEST_TIME 104 9468
TEST_TIME 105 9480
TEST_TIME 106 9482
TEST_TIME 107 9545
TEST_TIME 108 9509
TEST_TIME 109 9547
TEST_TIME 110 9470
TEST_TIME 111 9448
TEST_TIME 112 9413
TEST_TIME 113 9398
TEST_TIME 114 9381
TEST_TIME 115 9367
TEST_TIME 116 9332
TEST_TIME 117 9340
TEST_TIME 118 9374
TEST_TIME 119 9347
TEST_TIME 120 9320

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2021 22:39:22 to 19-10-2021 22:39:25.

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 1546604 1546604 0 0.00 0 26479 6 0 0
doc_list_biud 1546601 1546601 0 0.00 0 61183 26 0 0
sp_add_invoice_to_stock 5164 5052 112 2.16 41 177461 17577 0 112
sp_cancel_adding_invoice 5833 5833 0 0.00 5 91526 764 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 12455 355 0 0
sp_cancel_customer_reserve 567692 567692 0 0.00 1 61189 56 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 3691 79 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 1680 61 0 0
sp_cancel_supplier_invoice 6548 6533 15 0.22 2 55258 734 0 15
sp_cancel_supplier_order 411 411 0 0.00 6 861348 98219 0 0
sp_cancel_write_off 9696 9696 0 0.00 1 20778 112 0 0
sp_client_order 20632 20632 0 0.00 3 30863 384 0 0
sp_customer_reserve 463725 463725 0 0.00 0 87160 176 0 0
sp_fill_shopping_cart 31574 31574 0 0.00 1 24307 42 0 0
sp_fill_shopping_cart_clo_res 463720 463720 0 0.00 0 21103 14 0 0
sp_get_clo_for_invoice 5044 5044 0 0.00 0 31664 314 0 0
sp_kill_cost_storno 16596 16596 0 0.00 0 6761 54 0 0
sp_kill_qstorno_ret_qs2qd 1152024 1152024 0 0.00 0 60865 30 0 0
sp_kill_qty_storno 607041 607041 0 0.00 0 61183 66 0 0
sp_lock_selected_doc 20850 20850 0 0.00 0 10176 5 0 0
sp_make_cost_storno 27992 27992 0 0.00 0 20613 55 0 0
sp_multiply_rows_for_pdistr 27991 27991 0 0.00 0 6917 27 0 0
sp_multiply_rows_for_qdistr 20632 20632 0 0.00 0 13524 177 0 0
sp_pay_from_customer 7141 7141 0 0.00 4 9741 98 0 0
sp_pay_to_supplier 5744 5744 0 0.00 5 12571 127 0 0
sp_payment_common 12883 12883 0 0.00 1 11514 28 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 12448 331 0 0
sp_qd_handle_on_invoice_upd_sts 10877 10877 0 0.00 0 19914 325 0 0
sp_qd_handle_on_reserve_upd_sts 10062 10062 0 0.00 0 24822 59 0 0
sp_reserve_write_off 10063 10063 0 0.00 6 24848 185 0 0
sp_supplier_invoice 5427 5427 0 0.00 7 127649 3749 0 0
sp_supplier_order 5530 5530 0 0.00 5 24558 1328 0 0
srv_aggregate_perf_data 99 99 0 0.00 3167 172925 17794 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 3098 156861 48265 0 0
srv_find_qd_qs_mism 1069854 1069854 0 0.00 0 26477 5 0 0
srv_make_invnt_saldo 525 525 0 0.00 36 36027 1149 0 0
srv_make_money_saldo 787 787 0 0.00 1 12091 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 117349 403115 270256 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 5052 5052 0 0.00 0 11101 184 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 18285 121 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 2197 16 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4246 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 3798 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1049 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 2601 20 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 16460 1655 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 2246 9 0 0
t$perf-norm:sp_client_order 20632 20632 0 0.00 0 19664 22 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 3427 9 0 0
t$perf-norm:sp_pay_from_customer 7141 7141 0 0.00 0 28665 21 0 0
t$perf-norm:sp_pay_to_supplier 5744 5744 0 0.00 0 17645 14 0 0
t$perf-norm:sp_reserve_write_off 10063 10063 0 0.00 0 27611 17 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 11038 21 0 0
t$perf-norm:sp_supplier_order 5530 5530 0 0.00 0 6588 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 322 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 236 10 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 2056 24 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 12333 35 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10312 10088 224 2.17 0 2532 16 0 224
v_all_customers 37730 37730 0 0.00 0 17 1 0 0
v_all_suppliers 30222 30222 0 0.00 0 16 1 0 0
v_all_wares 41264 41264 0 0.00 0 125 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 568 6 0 0
v_cancel_client_order 3540 3540 0 0.00 0 2698 5 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3589 9 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 766 4 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 479 3 0 0
v_cancel_supplier_invoice 890 860 30 3.37 0 1997 15 0 30
v_cancel_supplier_order 822 822 0 0.00 0 925 5 0 0
v_cancel_write_off 1924 1924 0 0.00 0 3154 15 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 1808 2 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 2794 13 0 0
v_max_id_ord_sup 5418 5418 0 0.00 0 12878 4 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 3712 5 0 0
v_max_non_paid_realizn 3695 3695 0 0.00 0 2574 4 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 3153 7 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 5008 19 0 0
v_min_id_ord_sup 5418 5418 0 0.00 0 11392 18 0 0
v_min_non_paid_invoice 5743 5743 0 0.00 0 11509 21 0 0
v_min_non_paid_realizn 7140 7140 0 0.00 0 7742 21 0 0
v_our_firm 10424 10424 0 0.00 0 19 1 0 0
v_reserve_write_off 20124 20124 0 0.00 0 4764 6 0 0
x_lock_depdocs_on_canc_invoice 11936 11936 0 0.00 0 15750 119 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 6465 359 0 0
x_make_qstorno_1000_1200 5530 5530 0 0.00 1 24517 1246 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 127140 3594 0 0
x_make_qstorno_2100_3300 463725 463725 0 0.00 0 87001 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2021 22:39:26 to 19-10-2021 22:39:30.

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:54 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:55 23739 8192000000 9912278192 10227425280 993437840 10.815 100 86 172 0 1022550400 5937648 23174912
20:10:23 20062 8192000000 9962114224 10288730112 1045403584 11.317 100 88 176 1 1076163280 8319888 22439808
20:11:49 18154 8192000000 10006916512 10344316928 1085903520 11.704 100 84 168 1 1117245536 10218016 21124000
20:13:20 11280 8192000000 10043642672 10390208512 1128247728 12.105 100 87 174 0 1160964912 12077584 20639600
20:14:36 6257 8192000000 10097721664 10456211456 1179313920 12.584 100 86 170 2 1211616688 12826848 19475920
20:15:44 4500 8192000000 10138754144 10511814656 1227335216 13.029 100 79 156 3 1255480528 10555344 17589968
20:16:54 2722 8192000000 10173074640 10551111680 1261340592 13.342 100 81 162 2 1292016528 13326880 17349056
20:18:00 2538 8192000000 10200504688 10584440832 1280983072 13.522 100 89 178 1 1314830512 14164432 19683008
20:19:04 1040 8192000000 10216788320 10599370752 1297896464 13.676 100 93 188 1 1332724640 14212416 20615760
20:20:07 12859 8192000000 10215580288 10605236224 1299868272 13.694 100 64 129 1 1322727376 8348256 14510848
20:21:20 3700 8192000000 10196191280 10608140288 1302766768 13.720 100 25 50 0 1319194080 10394976 6032336
20:22:24 8708 8192000000 10203446256 10615111680 1313023712 13.813 100 26 52 0 1325463408 6552144 5887552
20:23:34 15055 8192000000 10212728656 10628349952 1321941424 13.894 100 16 30 1 1330866144 5137472 3787248
20:24:50 76240 8192000000 10210152144 10638118912 1314935600 13.831 99 31 62 2 1326503712 4455760 7112352
20:27:06 4217 8192000000 10225214880 10640510976 1323190912 13.906 100 29 58 1 1337101376 7459952 6450512
20:28:12 7625 8192000000 10184440832 10598010880 1272576576 13.445 97 17 34 0 1282075392 5434560 4064256
20:29:22 10249 8192000000 10175230096 10581946368 1246980672 13.210 96 11 20 0 1254207840 4655488 2571680
20:30:33 36117 8192000000 10145901696 10555367424 1200782784 12.784 94 23 44 2 1211795376 5484176 5528416
20:32:14 6894 8192000000 10140987440 10543611904 1236352176 13.113 99 27 54 1 1248674128 5361776 6960176
20:33:22 11400 8192000000 10170442064 10568704000 1268436992 13.407 100 30 58 0 1281526096 6015616 7073488
20:34:34 16652 8192000000 10174173760 10573295616 1266639424 13.391 99 23 44 3 1278402176 4779856 6982896
20:35:51 20518 8192000000 10184845616 10591158272 1285968576 13.567 100 34 68 3 1298964880 5848032 7148272
20:37:13 12227 8192000000 10202085664 10618843136 1314770672 13.829 100 30 60 0 1326755312 5130688 6853952
20:38:31 26473 8192000000 10241952608 10653810688 1333590688 14.000 100 34 66 2 1351318144 10335360 7392096
20:40:07 17519 8192000000 10266345360 10678521856 1354080080 14.184 100 41 82 0 1373685088 9546928 10058080
20:41:26 58452 8192000000 10263125248 10686582784 1347944928 14.129 99 34 68 1 1370932864 14283568 8704368
20:43:33 12614 8192000000 10280735632 10697912320 1365737600 14.289 100 53 103 1 1390554880 12990624 11826656
20:44:58 6615 8192000000 10301079520 10723639296 1381364544 14.429 100 55 110 1 1408859984 14724768 12770672
20:46:18 11663 8192000000 10320325600 10738778112 1395415376 14.554 100 63 127 1 1428503120 17861488 15226256
20:47:35 11228 8192000000 10310226624 10736631808 1392247424 14.526 100 52 103 2 1423538768 18752368 12538976
20:49:00 7726 8192000000 10331488672 10750156800 1403207952 14.624 100 78 156 1 1442422816 20665616 18549248
20:50:30 10165 8192000000 10346170752 10771222528 1414858016 14.727 100 63 125 1 1454289504 24251504 15179984
20:51:51 12674 8192000000 10343417280 10766065664 1396096080 14.560 99 62 121 5 1441046080 30300944 14649056
20:53:07 3094 8192000000 10345322880 10755706880 1383084400 14.444 98 73 146 0 1433734400 32693808 17956192
20:54:16 4616 8192000000 10341165712 10757447680 1390206208 14.508 99 62 124 1 1440214816 34151184 15857424
20:55:27 8262 8192000000 10346472304 10764636160 1395301200 14.553 100 65 130 0 1453775728 42298288 16176240
20:56:41 8458 8192000000 10324638000 10746679296 1381407280 14.429 100 50 98 1 1435005344 40350000 13248064
20:57:53 21031 8192000000 10322147840 10754473984 1380198928 14.418 99 32 65 1 1431037920 42042160 8796832
20:59:20 3227 8192000000 10311790720 10721890304 1359419856 14.232 100 71 142 3 1419055440 40827472 18808112
21:00:28 13650 8192000000 10318260720 10744774656 1376688352 14.387 100 33 66 3 1427424736 41650336 9086048
21:01:49 3543 8192000000 10316139920 10726031360 1359063824 14.229 99 67 135 1 1421019520 44820688 17135008
21:02:59 19480 8192000000 10313212880 10737811456 1351775392 14.163 99 31 62 0 1406180656 45767840 8637424
21:04:22 37281 8192000000 10321513216 10759639040 1377729744 14.396 100 23 46 1 1424990336 41018400 6242192
21:06:44 73313 8192000000 10288278528 10718359552 1331442688 13.980 97 32 64 1 1357408944 17875616 8090640
21:08:59 40512 8192000000 10267528912 10707222528 1329536608 13.963 97 19 38 0 1354014800 20080816 4397376
21:10:43 54922 8192000000 10260811792 10697060352 1326480976 13.935 97 19 38 0 1342502480 11302560 4718944
21:12:40 64809 8192000000 10275870352 10709770240 1325458304 13.926 97 28 56 4 1351963808 19865024 6640480
21:15:37 156861 8192000000 10271774304 10720436224 1357771216 14.217 99 25 50 0 1381585216 17816000 5998000
21:19:14 24661 8192000000 10308410288 10735390720 1380937440 14.425 100 43 87 1 1399909520 8644976 10327104
21:20:42 87556 8192000000 10289671760 10758148096 1397334432 14.571 100 17 34 0 1414231952 12907344 3990176
21:24:05 35663 8192000000 10295284944 10750214144 1394107648 14.543 100 26 51 0 1409807856 10086832 5613376
21:25:52 55586 8192000000 10303928976 10763182080 1398903888 14.585 100 20 40 1 1419042288 15639808 4498592
21:27:49 12532 8192000000 10313731136 10763964416 1404713008 14.637 100 25 50 0 1426645504 15685824 6246672
21:29:04 45060 8192000000 10314051152 10777092096 1397872592 14.576 99 13 26 1 1418526448 17325440 3328416
21:31:03 55286 8192000000 10308917088 10759901184 1384517232 14.457 99 18 36 1 1412268864 23162368 4589264
21:32:59 33305 8192000000 10297549184 10747822080 1373680816 14.360 98 39 78 2 1394539936 12058208 8800912
21:34:51 69414 8192000000 10308837504 10785427456 1400071632 14.596 100 19 38 1 1424246000 19360560 4813808
21:37:07 30080 8192000000 10305855520 10758950912 1372533936 14.350 98 27 54 1 1393339040 14861328 5943776
21:38:38 45012 8192000000 10303062272 10758750208 1371766944 14.343 99 17 34 0 1401874448 26317456 3790048
21:40:27 73167 8192000000 10293421632 10738204672 1300591312 13.701 93 25 50 1 1317501344 11634448 5275584
21:43:00 64602 8192000000 10266728224 10732978176 1345202672 14.104 97 26 52 0 1362262656 11695120 5364864
21:45:10 89948 8192000000 10272896288 10726973440 1320108976 13.878 95 17 34 0 1332923792 8379456 4435360
21:48:44 60458 8192000000 10277586784 10720006144 1334284224 14.006 97 21 41 1 1347664528 8489504 4890800
21:50:46 80640 8192000000 10243608896 10709155840 1315148848 13.833 97 5 10 0 1327004736 10595264 1260624
21:53:08 54952 8192000000 10270956336 10709454848 1331174240 13.978 98 31 62 0 1350919504 12459424 7285840
21:55:20 86738 8192000000 10250977136 10704494592 1315332352 13.834 97 17 34 0 1334470560 15265056 3873152
21:57:47 50909 8192000000 10260861600 10703917056 1339408176 14.052 99 35 70 0 1362159664 14971984 7779504
21:59:42 21169 8192000000 10260762896 10700083200 1339753248 14.055 99 29 58 0 1353965008 7609840 6601920
22:01:06 75600 8192000000 10264866528 10723348480 1320445808 13.881 97 20 40 0 1340016960 15310672 4260480
22:03:55 65133 8192000000 10275351392 10732212224 1355204224 14.194 99 17 34 0 1371531504 12640912 3686368
22:06:03 110952 8192000000 10277091856 10728247296 1359156096 14.230 99 18 35 0 1371281264 8351472 3773696
22:08:55 44376 8192000000 10289935968 10749001728 1389899968 14.505 100 15 30 0 1406183904 13069504 3214432
22:10:39 60428 8192000000 10284911888 10747506688 1378478256 14.403 99 5 10 0 1389754784 10320448 956080
22:12:40 40609 8192000000 10287514416 10746810368 1380206864 14.418 99 17 34 0 1391252608 6970560 4075184
22:14:21 99616 8192000000 10329031296 10772078592 1388474304 14.492 99 16 32 2 1404546560 12399920 3672336
22:17:02 49870 8192000000 10306905088 10757488640 1377377664 14.393 98 13 26 2 1388361952 7931984 3052304
22:19:18 88621 8192000000 10325539104 10766942208 1364543392 14.278 97 10 20 0 1376596768 10056272 1997104
22:21:48 78873 8192000000 10307852496 10762203136 1359398992 14.232 97 14 28 0 1373834448 11447888 2987568
22:24:07 112755 8192000000 10315460928 10767581184 1333642336 14.000 95 13 26 0 1355944416 19646592 2655488
22:27:04 89581 8192000000 10287892416 10741706752 1367125936 14.301 98 25 50 0 1381912672 9232208 5554528
22:29:39 31048 8192000000 10279540304 10730500096 1352562208 14.171 98 18 35 4 1368910288 12536656 3811424
22:31:10 97916 8192000000 10292819808 10764034048 1378819424 14.406 99 8 16 0 1397190448 16765984 1605040
22:33:48 84692 8192000000 10288523696 10753662976 1375444544 14.376 99 9 18 0 1395341504 17381680 2515280
22:36:24 49080 8192000000 10296174768 10751836160 1376552544 14.386 99 12 24 0 1390312832 11243168 2517120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-10-2021 22:39:32 to 19-10-2021 22:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 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 19-10-2021 22:39:47 to 19-10-2021 22:39:49.

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          235297
MON$OLDEST_ACTIVE               237628
MON$OLDEST_SNAPSHOT             237628
MON$NEXT_TRANSACTION            237628
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                       2029664
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:648: 
:649: mscs-test-161.budzdorov.ru    Tue Oct 19 22:37:56 2021
:650:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:651: 
:652: 
:653: mscs-test-161.budzdorov.ru    Tue Oct 19 22:38:00 2021
:654:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:655: 
:656: 
:657: mscs-test-161.budzdorov.ru    Tue Oct 19 22:38:00 2021
:658:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:659: 
--- end of diff output ---

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

19.10.21 22:39:50. 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-10-19 22:39:53 No severe PSQL-related problems occured
19.10.21 22:39:54. End of report.