Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.01.2022 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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16623832 kB
MemAvailable 31235008 kB
Buffers 0 kB
Cached 15028624 kB
SwapCached 2300 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.9
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 2022-01-14 01:38:03
DTS_END                         2022-01-14 01:38:03.2490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40812
TRN_ID                          241637
ATT_ID                          705
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: 9108.33 18952 1093000
customer order: creation 172.96 384 20755
customer order: refuse 14.29 322 1715
order to supplier: creation 46.59 1280 5591
order to supplier: removal 3.48 98117 418
invoice (draft): creation 46.17 3458 5540
invoice (draft): removal 52.53 772 6303
invoice accept: apply 41.43 18112 4971
invoice accept: cancel 46.81 847 5617
customer reserve: creation 3804.51 180 456541
customer reserve: removal 4581.57 58 549788
realization accept: apply 85.18 197 10222
realization accept: cancel 82.03 118 9844
payment from customer: creation 60.55 107 7266
payment from customer: removal 5.83 78 699
payment to supplier: creation 49.12 139 5894
payment to supplier: removal 3.55 85 426
service: total inventory turnovers 4.33 1134 519
service: total monetary turnovers 6.56 24 787
service: aggregate perf. data 0.83 19107 100
service: refresh index statistics 0.03 234515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2022 01:39:15 to 14-01-2022 01:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7224
TEST_TIME 2 5137
TEST_TIME 3 4969
TEST_TIME 4 4633
TEST_TIME 5 4966
TEST_TIME 6 4899
TEST_TIME 7 4979
TEST_TIME 8 5049
TEST_TIME 9 5219
TEST_TIME 10 5280
TEST_TIME 11 5511
TEST_TIME 12 5602
TEST_TIME 13 5723
TEST_TIME 14 6187
TEST_TIME 15 6312
TEST_TIME 16 6676
TEST_TIME 17 6634
TEST_TIME 18 7379
TEST_TIME 19 7923
TEST_TIME 20 7945
TEST_TIME 21 8093
TEST_TIME 22 8418
TEST_TIME 23 8540
TEST_TIME 24 9028
TEST_TIME 25 10080
TEST_TIME 26 10084
TEST_TIME 27 10187
TEST_TIME 28 10093
TEST_TIME 29 9802
TEST_TIME 30 9750
TEST_TIME 31 9609
TEST_TIME 32 9522
TEST_TIME 33 9601
TEST_TIME 34 9681
TEST_TIME 35 9540
TEST_TIME 36 9376
TEST_TIME 37 9234
TEST_TIME 38 9239
TEST_TIME 39 9265
TEST_TIME 40 9408
TEST_TIME 41 9421
TEST_TIME 42 9352
TEST_TIME 43 9423
TEST_TIME 44 9466
TEST_TIME 45 9475
TEST_TIME 46 9585
TEST_TIME 47 9625
TEST_TIME 48 9648
TEST_TIME 49 9581
TEST_TIME 50 9644
TEST_TIME 51 9617
TEST_TIME 52 9528
TEST_TIME 53 9439
TEST_TIME 54 9415
TEST_TIME 55 9324
TEST_TIME 56 9350
TEST_TIME 57 9306
TEST_TIME 58 9263
TEST_TIME 59 9261
TEST_TIME 60 9183
TEST_TIME 61 9215
TEST_TIME 62 9224
TEST_TIME 63 9199
TEST_TIME 64 9138
TEST_TIME 65 9201
TEST_TIME 66 9429
TEST_TIME 67 9425
TEST_TIME 68 9387
TEST_TIME 69 9396
TEST_TIME 70 9405
TEST_TIME 71 9388
TEST_TIME 72 9448
TEST_TIME 73 9439
TEST_TIME 74 9529
TEST_TIME 75 9499
TEST_TIME 76 9387
TEST_TIME 77 9363
TEST_TIME 78 9304
TEST_TIME 79 9342
TEST_TIME 80 9310
TEST_TIME 81 9245
TEST_TIME 82 9265
TEST_TIME 83 9214
TEST_TIME 84 9144
TEST_TIME 85 9144
TEST_TIME 86 9193
TEST_TIME 87 9187
TEST_TIME 88 9196
TEST_TIME 89 9220
TEST_TIME 90 9238
TEST_TIME 91 9219
TEST_TIME 92 9214
TEST_TIME 93 9256
TEST_TIME 94 9254
TEST_TIME 95 9400
TEST_TIME 96 9388
TEST_TIME 97 9406
TEST_TIME 98 9414
TEST_TIME 99 9394
TEST_TIME 100 9459
TEST_TIME 101 9392
TEST_TIME 102 9361
TEST_TIME 103 9315
TEST_TIME 104 9301
TEST_TIME 105 9297
TEST_TIME 106 9237
TEST_TIME 107 9264
TEST_TIME 108 9222
TEST_TIME 109 9207
TEST_TIME 110 9195
TEST_TIME 111 9157
TEST_TIME 112 9173
TEST_TIME 113 9141
TEST_TIME 114 9127
TEST_TIME 115 9087
TEST_TIME 116 9139
TEST_TIME 117 9153
TEST_TIME 118 9199
TEST_TIME 119 9178
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-01-2022 01:39:18 to 14-01-2022 01:39:22.

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 1515270 1515270 0 0.00 0 36182 7 0 0
doc_list_biud 1515269 1515269 0 0.00 0 33669 27 0 0
sp_add_invoice_to_stock 5045 4971 74 1.46 24 186699 18113 0 74
sp_cancel_adding_invoice 5617 5617 0 0.00 5 97016 847 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 10676 322 0 0
sp_cancel_customer_reserve 549788 549788 0 0.00 1 33670 59 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 8288 79 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 4964 85 0 0
sp_cancel_supplier_invoice 6312 6303 9 0.14 2 11885 772 0 9
sp_cancel_supplier_order 418 418 0 0.00 7 1001194 98117 0 0
sp_cancel_write_off 9844 9844 0 0.00 1 10786 118 0 0
sp_client_order 20755 20755 0 0.00 3 27603 384 0 0
sp_customer_reserve 456541 456541 0 0.00 0 144694 180 0 0
sp_fill_shopping_cart 31874 31874 0 0.00 1 13392 38 0 0
sp_fill_shopping_cart_clo_res 456540 456540 0 0.00 0 12712 14 0 0
sp_get_clo_for_invoice 4970 4970 0 0.00 0 28081 303 0 0
sp_kill_cost_storno 16586 16586 0 0.00 0 8405 60 0 0
sp_kill_qstorno_ret_qs2qd 1116141 1116141 0 0.00 0 33658 32 0 0
sp_kill_qty_storno 588877 588877 0 0.00 0 33669 69 0 0
sp_lock_selected_doc 21011 21011 0 0.00 0 7809 6 0 0
sp_make_cost_storno 28352 28352 0 0.00 0 36113 61 0 0
sp_multiply_rows_for_pdistr 28351 28351 0 0.00 0 7673 34 0 0
sp_multiply_rows_for_qdistr 20755 20755 0 0.00 0 12732 173 0 0
sp_pay_from_customer 7266 7266 0 0.00 4 20701 108 0 0
sp_pay_to_supplier 5894 5894 0 0.00 4 14831 139 0 0
sp_payment_common 13159 13159 0 0.00 1 20662 32 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 10670 301 0 0
sp_qd_handle_on_invoice_upd_sts 10587 10587 0 0.00 0 16923 322 0 0
sp_qd_handle_on_reserve_upd_sts 10222 10222 0 0.00 0 13626 66 0 0
sp_reserve_write_off 10222 10222 0 0.00 6 14084 198 0 0
sp_supplier_invoice 5540 5540 0 0.00 7 74064 3458 0 0
sp_supplier_order 5591 5591 0 0.00 4 31480 1281 0 0
srv_aggregate_perf_data 100 100 0 0.00 2973 353028 19107 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1432 260481 45460 0 0
srv_find_qd_qs_mism 1044937 1044937 0 0.00 0 21626 5 0 0
srv_make_invnt_saldo 519 519 0 0.00 31 34879 1135 0 0
srv_make_money_saldo 787 787 0 0.00 1 3221 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 187115 309883 234516 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4971 4971 0 0.00 0 18722 171 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 1405 71 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 4516 15 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 4547 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2781 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 5789 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1441 15 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 23165 1533 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 4056 15 0 0
t$perf-norm:sp_client_order 20755 20755 0 0.00 0 9283 16 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 1177 6 0 0
t$perf-norm:sp_pay_from_customer 7266 7266 0 0.00 0 5415 14 0 0
t$perf-norm:sp_pay_to_supplier 5895 5895 0 0.00 0 4614 11 0 0
t$perf-norm:sp_reserve_write_off 10222 10222 0 0.00 0 8968 13 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 7132 19 0 0
t$perf-norm:sp_supplier_order 5591 5591 0 0.00 0 5246 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 338 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 201 5 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 4790 27 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 1769 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10086 9938 148 1.46 0 4273 17 0 148
v_all_customers 37810 37810 0 0.00 0 63 0 0 0
v_all_suppliers 30644 30644 0 0.00 0 15 1 0 0
v_all_wares 41510 41510 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 539 6 0 0
v_cancel_client_order 3430 3430 0 0.00 0 1372 3 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 4289 9 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 266 3 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 2192 13 0 0
v_cancel_supplier_invoice 868 850 18 2.07 0 2129 17 0 18
v_cancel_supplier_order 836 836 0 0.00 0 2091 5 0 0
v_cancel_write_off 2014 2014 0 0.00 0 4600 15 0 0
v_max_id_clo_ord 5589 5589 0 0.00 0 513 1 0 0
v_max_id_clo_res 945 945 0 0.00 0 2734 10 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 3201 4 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 697 2 0 0
v_max_non_paid_realizn 3948 3948 0 0.00 0 4520 4 0 0
v_min_id_clo_ord 5589 5589 0 0.00 0 4078 11 0 0
v_min_id_clo_res 945 945 0 0.00 0 6193 16 0 0
v_min_id_ord_sup 5528 5528 0 0.00 0 8112 8 0 0
v_min_non_paid_invoice 5893 5893 0 0.00 0 5317 20 0 0
v_min_non_paid_realizn 7266 7266 0 0.00 0 20653 25 0 0
v_our_firm 10336 10336 0 0.00 0 12 1 0 0
v_reserve_write_off 20444 20444 0 0.00 0 4589 6 0 0
x_lock_depdocs_on_canc_invoice 11495 11495 0 0.00 0 35786 122 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 10848 310 0 0
x_make_qstorno_1000_1200 5591 5591 0 0.00 1 31474 1196 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 3 73983 3328 0 0
x_make_qstorno_2100_3300 456541 456541 0 0.00 0 144693 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2022 01:39:23 to 14-01-2022 01:39:27.

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:08:07 8 8192000000 8808303344 8822624256 12327456 0.150 4 3 4 0 12512736 78160 107120
23:09:08 21084 8192000000 9890069360 10199511040 970106816 10.588 100 86 172 0 999439088 5579424 23752848
23:10:36 14051 8192000000 9945018512 10264219648 1024784288 11.118 100 92 184 0 1060567712 11669600 24113824
23:11:53 19691 8192000000 9972070448 10302541824 1058265760 11.440 100 84 168 0 1088836544 8809312 21761472
23:13:18 16438 8192000000 10034578912 10383663104 1117303104 12.002 100 75 151 0 1144979712 10104544 17572064
23:14:37 10570 8192000000 10102592544 10466533376 1187782736 12.663 100 80 160 3 1217896240 11963616 18149888
23:15:52 6886 8192000000 10147873280 10519162880 1234305584 13.094 100 81 162 2 1265323488 12862112 18155792
23:17:02 3145 8192000000 10173227936 10552156160 1260552256 13.335 100 88 177 0 1294128240 13790336 19785648
23:18:07 1782 8192000000 10199554192 10578792448 1283319392 13.543 100 89 178 1 1321470288 18604976 19545920
23:19:11 1710 8192000000 10210829632 10593140736 1295712928 13.656 100 92 184 1 1329424672 12842992 20868752
23:20:14 8931 8192000000 10182353376 10593116160 1300161952 13.697 100 58 116 0 1321968192 8065312 13740928
23:21:27 6424 8192000000 10193076864 10602762240 1306296080 13.752 100 28 56 0 1319626752 7050144 6280528
23:22:38 10110 8192000000 10196754848 10614124544 1314190992 13.824 100 13 26 0 1322644624 5701296 2752336
23:23:49 64971 8192000000 10211149248 10634194944 1324941968 13.921 100 34 68 0 1338152912 5344976 7865968
23:26:00 3010 8192000000 10235351680 10648166400 1332078208 13.986 100 38 76 0 1350035104 9768928 8187968
23:27:05 9105 8192000000 10217815424 10633146368 1306515952 13.754 98 18 36 0 1315695248 5271360 3907936
23:28:14 16558 8192000000 10197737568 10602557440 1271670992 13.437 97 21 42 1 1282961776 5382736 5908048
23:29:31 34777 8192000000 10161322560 10571825152 1211042176 12.879 93 20 40 0 1220348080 5063504 4242400
23:31:11 3245 8192000000 10135927648 10527690752 1224113648 13.000 99 39 79 1 1239754240 6659616 8980976
23:32:18 7844 8192000000 10146013968 10549481472 1245233056 13.194 99 18 36 1 1255147184 5883056 4031072
23:33:26 52245 8192000000 10164931536 10574639104 1264567136 13.372 99 34 69 1 1277679408 5022464 8089808
23:35:19 8141 8192000000 10181033424 10592747520 1290550512 13.609 100 42 84 0 1307163392 6230832 10382048
23:36:28 10972 8192000000 10186977376 10604916736 1299442816 13.690 100 21 42 1 1309900992 5520768 4937408
23:37:39 11181 8192000000 10194960528 10613235712 1305806400 13.748 100 15 30 0 1314912560 5688720 3417440
23:38:57 17240 8192000000 10233311376 10642083840 1330579856 13.972 100 48 97 1 1348646624 6996384 11070384
23:40:20 56691 8192000000 10255948544 10675208192 1348961616 14.138 100 51 102 0 1372320816 10996832 12362368
23:42:22 28772 8192000000 10270280336 10689847296 1352037728 14.166 99 51 101 1 1375955424 12045648 11872048
23:43:55 12062 8192000000 10272997872 10697515008 1367569312 14.305 100 40 79 2 1390077808 12993888 9514608
23:45:10 8760 8192000000 10312093920 10726834176 1380838992 14.424 100 62 124 1 1416536016 20789024 14908000
23:46:24 6970 8192000000 10312958976 10730283008 1385018896 14.461 100 48 96 1 1417769984 21316288 11434800
23:47:34 7940 8192000000 10325863936 10746605568 1402917248 14.621 100 54 108 0 1439007120 22720192 13369680
23:48:56 13927 8192000000 10347808480 10764795904 1409202928 14.677 100 65 126 3 1453822672 29257616 15362128
23:50:19 4370 8192000000 10347289296 10770378752 1412094384 14.703 100 64 126 0 1461893584 34406320 15392880
23:51:27 1825 8192000000 10360629264 10773741568 1409451216 14.679 100 74 145 2 1466371248 38640032 18280000
23:52:31 1429 8192000000 10375195584 10783973376 1414899088 14.727 100 91 183 3 1476655440 39841776 21914576
23:53:38 5875 8192000000 10363910368 10774183936 1404483312 14.635 100 72 144 1 1465466192 42924400 18058480
23:54:50 3890 8192000000 10340461360 10747031552 1377591952 14.395 100 76 152 1 1442218816 45531824 19095040
23:56:01 4371 8192000000 10373144176 10777825280 1395484432 14.555 100 70 140 1 1471547968 57713648 18349888
23:57:10 4219 8192000000 10353971488 10763083776 1386814720 14.477 100 72 144 2 1455299424 49801248 18683456
23:58:18 5848 8192000000 10331440656 10753638400 1370215824 14.329 99 49 98 2 1425531504 42894976 12420704
23:59:34 12832 8192000000 10339580816 10767040512 1384747376 14.459 100 47 92 1 1446745456 49273712 12724368
00:00:49 27380 8192000000 10318475776 10756882432 1358151344 14.221 98 24 48 1 1408845200 43622288 7071568
00:02:18 52772 8192000000 10265129520 10698129408 1234571488 13.096 91 19 38 0 1292687840 52105056 6011296
00:04:58 54024 8192000000 10248442240 10681982976 1300067296 13.696 97 19 38 0 1319329232 15199584 4062352
00:07:00 83497 8192000000 10259899360 10695888896 1332240608 13.987 99 33 66 0 1353017888 13278288 7498992
00:09:59 24196 8192000000 10303964816 10734624768 1377701472 14.396 100 57 114 1 1404763824 14322144 12740208
00:11:24 30576 8192000000 10282277728 10738511872 1366899392 14.299 99 32 64 1 1389683472 15266576 7517504
00:13:03 260481 8192000000 10291608496 10740043776 1330302544 13.970 96 20 39 0 1351431984 17172784 3956656
00:18:24 56058 8192000000 10292087520 10745745408 1382195792 14.436 100 26 52 0 1399140480 10907264 6037424
00:20:21 32818 8192000000 10282295344 10732863488 1353928624 14.183 98 20 37 0 1369076736 9465328 5682784
00:21:54 39594 8192000000 10288026624 10732212224 1365960736 14.291 99 29 57 0 1381338240 9199072 6178432
00:23:37 57997 8192000000 10309960848 10763997184 1378693376 14.405 99 11 21 1 1396815184 15039056 3082752
00:25:36 60952 8192000000 10306353584 10766876672 1400431488 14.599 100 14 28 2 1413111872 9780048 2900336
00:27:41 51402 8192000000 10313048560 10780213248 1391734320 14.521 99 24 48 0 1411001008 13960928 5305760
00:29:33 35050 8192000000 10326967504 10762096640 1357586848 14.216 96 28 56 0 1372912880 8562048 6763984
00:32:04 93544 8192000000 10315649952 10769485824 1390633456 14.512 99 32 61 2 1408624912 10380192 7611264
00:34:41 30039 8192000000 10300922896 10775425024 1408312832 14.669 100 15 30 0 1426585216 14131232 4141152
00:36:21 69261 8192000000 10310499488 10775445504 1411933280 14.701 100 9 19 0 1427791056 13765520 2092256
00:38:38 71070 8192000000 10296130528 10767560704 1358073632 14.220 96 11 22 0 1368508144 8148896 2285616
00:40:56 56560 8192000000 10310834864 10763284480 1343005440 14.084 96 14 28 1 1357725968 11615376 3105152
00:42:53 72907 8192000000 10299321344 10760155136 1359585824 14.234 98 17 34 1 1386033408 22125856 4321728
00:46:44 40941 8192000000 10259026752 10700640256 1300188528 13.697 96 37 74 0 1320387280 11978400 8220352
00:48:25 60276 8192000000 10255342992 10693353472 1321639568 13.892 98 35 70 0 1342978032 13829888 7508576
00:50:32 92436 8192000000 10284748400 10720686080 1307866464 13.767 96 14 28 0 1326866768 16255216 2745088
00:53:08 120468 8192000000 10265841616 10710536192 1316719120 13.847 96 26 52 1 1333391072 11021424 5650528
00:56:10 6208 8192000000 10267432848 10707746816 1346605216 14.117 99 22 42 1 1357821200 6532272 4683712
00:57:16 27953 8192000000 10255500704 10706632704 1354150816 14.185 100 17 34 0 1366863856 8366272 4346768
01:00:14 41843 8192000000 10266398208 10728710144 1369406144 14.322 100 17 34 0 1384856528 11479744 3970640
01:01:58 62399 8192000000 10275868672 10737336320 1371509968 14.341 100 18 36 0 1388630384 12681952 4438464
01:04:05 42966 8192000000 10255563504 10707197952 1317532160 13.854 96 19 38 1 1330328464 8811968 3984336
01:05:57 14119 8192000000 10290909104 10727260160 1360705968 14.244 99 59 118 1 1382024576 7905104 13413504
01:07:21 66684 8192000000 10284031632 10735046656 1333215760 13.996 96 14 28 0 1345539632 9433568 2890304
01:09:29 82160 8192000000 10307687568 10763091968 1384125504 14.453 99 21 42 0 1403030000 14358480 4546016
01:11:52 56383 8192000000 10303645136 10754351104 1364054608 14.274 98 30 60 1 1386625088 15271552 7298928
01:14:01 52538 8192000000 10287226816 10732478464 1348085536 14.130 97 19 36 2 1363348464 11175792 4087136
01:15:54 62043 8192000000 10316425408 10773004288 1395779824 14.557 100 15 30 0 1409641232 10710800 3150608
01:18:04 58700 8192000000 10316512448 10771533824 1385539440 14.466 99 24 48 1 1409190592 18390048 5261104
01:20:04 89535 8192000000 10283139072 10740854784 1351088960 14.157 97 18 36 1 1365055312 10052832 3913520
01:22:45 80767 8192000000 10297630976 10763976704 1397438016 14.572 100 15 30 0 1409750688 8753072 3559600
01:25:06 54521 8192000000 10302424848 10767159296 1403426752 14.625 100 12 24 1 1413120720 7023184 2670784
01:27:08 39714 8192000000 10307547808 10768162816 1389180048 14.499 98 20 40 1 1405669520 11835600 4653872
01:29:13 28787 8192000000 10294313392 10754707456 1367620672 14.306 97 10 20 0 1381108768 11024240 2463856
01:30:50 42634 8192000000 10308297936 10769530880 1403841856 14.629 100 15 30 0 1416753584 9863424 3048304
01:32:34 49197 8192000000 10293815488 10747125760 1324438064 13.917 94 16 32 0 1336228640 8350240 3440336
01:34:25 42768 8192000000 10298766656 10760495104 1378406544 14.402 99 26 52 2 1398318864 13503664 6408656
01:36:17 45222 8192000000 10274004832 10731302912 1336632032 14.027 97 15 30 0 1352506176 12285792 3588352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-01-2022 01:39:28 to 14-01-2022 01:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2022 01:39:44 to 14-01-2022 01:39:46.

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          239928
MON$OLDEST_ACTIVE               241759
MON$OLDEST_SNAPSHOT             241759
MON$NEXT_TRANSACTION            241759
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                       2007360
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:917: 
:918: mscs-test-161.budzdorov.ru    Fri Jan 14 01:38:07 2022
:919:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:920: 
:921: 
:922: mscs-test-161.budzdorov.ru    Fri Jan 14 01:38:11 2022
:923:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:924: 
:925: 
:926: mscs-test-161.budzdorov.ru    Fri Jan 14 01:38:11 2022
:927:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:928: 
:929: 
:930: mscs-test-161.budzdorov.ru    Fri Jan 14 01:38:11 2022
:931:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:932: 
:933: 
:934: mscs-test-161.budzdorov.ru    Fri Jan 14 01:38:13 2022
:935:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:936: 
--- end of diff output ---

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

14.01.22 01:39:47. There are 6 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
2022-01-14 01:39:49 No severe PSQL-related problems occured
14.01.22 01:39:50. End of report.