Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2022 16:40
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 15143228 kB
MemAvailable 30138652 kB
Buffers 0 kB
Cached 16595144 kB
SwapCached 3244 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.11
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-12-26 16:38:32
DTS_END                         2022-12-26 16:38:32.0370
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/34328
TRN_ID                          242452
ATT_ID                          662
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: 9310.05 21712 1117206
customer order: creation 177.94 381 21353
customer order: refuse 15.05 399 1806
order to supplier: creation 47.17 1243 5660
order to supplier: removal 3.38 107540 406
invoice (draft): creation 45.88 3069 5506
invoice (draft): removal 51.39 884 6167
invoice accept: apply 42.98 17972 5157
invoice accept: cancel 46.28 815 5554
customer reserve: creation 3979.93 179 477592
customer reserve: removal 4606.07 62 552728
realization accept: apply 85.57 196 10268
realization accept: cancel 77.28 112 9273
payment from customer: creation 60.09 127 7211
payment from customer: removal 5.74 108 689
payment to supplier: creation 50.03 152 6004
payment to supplier: removal 3.28 95 393
service: total inventory turnovers 4.50 1132 540
service: total monetary turnovers 6.53 42 784
service: aggregate perf. data 0.93 19730 111
service: refresh index statistics 0.03 279997 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2022 16:41:05 to 26-12-2022 16:41:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2452
TEST_TIME 2 3644
TEST_TIME 3 2661
TEST_TIME 4 3416
TEST_TIME 5 4361
TEST_TIME 6 5200
TEST_TIME 7 5747
TEST_TIME 8 5705
TEST_TIME 9 5816
TEST_TIME 10 5855
TEST_TIME 11 6392
TEST_TIME 12 6336
TEST_TIME 13 6383
TEST_TIME 14 6430
TEST_TIME 15 6417
TEST_TIME 16 6921
TEST_TIME 17 7302
TEST_TIME 18 7554
TEST_TIME 19 8105
TEST_TIME 20 7959
TEST_TIME 21 8001
TEST_TIME 22 8332
TEST_TIME 23 8285
TEST_TIME 24 8403
TEST_TIME 25 8530
TEST_TIME 26 9373
TEST_TIME 27 9769
TEST_TIME 28 10810
TEST_TIME 29 10972
TEST_TIME 30 11118
TEST_TIME 31 10882
TEST_TIME 32 10781
TEST_TIME 33 10602
TEST_TIME 34 10523
TEST_TIME 35 10338
TEST_TIME 36 10261
TEST_TIME 37 10133
TEST_TIME 38 9951
TEST_TIME 39 10106
TEST_TIME 40 10013
TEST_TIME 41 9967
TEST_TIME 42 9852
TEST_TIME 43 9798
TEST_TIME 44 9740
TEST_TIME 45 9677
TEST_TIME 46 9809
TEST_TIME 47 9850
TEST_TIME 48 9734
TEST_TIME 49 9758
TEST_TIME 50 9693
TEST_TIME 51 9603
TEST_TIME 52 9588
TEST_TIME 53 9615
TEST_TIME 54 9690
TEST_TIME 55 9705
TEST_TIME 56 9791
TEST_TIME 57 9710
TEST_TIME 58 9807
TEST_TIME 59 9769
TEST_TIME 60 9715
TEST_TIME 61 9836
TEST_TIME 62 9840
TEST_TIME 63 9850
TEST_TIME 64 9879
TEST_TIME 65 9842
TEST_TIME 66 9819
TEST_TIME 67 9786
TEST_TIME 68 9705
TEST_TIME 69 9648
TEST_TIME 70 9605
TEST_TIME 71 9600
TEST_TIME 72 9577
TEST_TIME 73 9546
TEST_TIME 74 9511
TEST_TIME 75 9450
TEST_TIME 76 9449
TEST_TIME 77 9450
TEST_TIME 78 9389
TEST_TIME 79 9375
TEST_TIME 80 9414
TEST_TIME 81 9508
TEST_TIME 82 9496
TEST_TIME 83 9507
TEST_TIME 84 9461
TEST_TIME 85 9567
TEST_TIME 86 9574
TEST_TIME 87 9558
TEST_TIME 88 9582
TEST_TIME 89 9636
TEST_TIME 90 9616
TEST_TIME 91 9642
TEST_TIME 92 9683
TEST_TIME 93 9586
TEST_TIME 94 9577
TEST_TIME 95 9521
TEST_TIME 96 9488
TEST_TIME 97 9449
TEST_TIME 98 9450
TEST_TIME 99 9450
TEST_TIME 100 9400
TEST_TIME 101 9431
TEST_TIME 102 9410
TEST_TIME 103 9496
TEST_TIME 104 9526
TEST_TIME 105 9541
TEST_TIME 106 9533
TEST_TIME 107 9502
TEST_TIME 108 9559
TEST_TIME 109 9531
TEST_TIME 110 9530
TEST_TIME 111 9597
TEST_TIME 112 9538
TEST_TIME 113 9495
TEST_TIME 114 9446
TEST_TIME 115 9457
TEST_TIME 116 9425
TEST_TIME 117 9424
TEST_TIME 118 9402
TEST_TIME 119 9374
TEST_TIME 120 9313

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2022 16:41:09 to 26-12-2022 16:41:12.

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 1559715 1559715 0 0.00 0 18422 6 0 0
doc_list_biud 1559714 1559714 0 0.00 0 35330 28 0 0
sp_add_invoice_to_stock 5261 5157 104 1.97 53 176994 17973 0 104
sp_cancel_adding_invoice 5554 5554 0 0.00 3 63477 816 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 17715 399 0 0
sp_cancel_customer_reserve 552728 552728 0 0.00 0 17877 62 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 9863 108 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 2 12153 96 0 0
sp_cancel_supplier_invoice 6172 6167 5 0.08 1 19959 885 0 5
sp_cancel_supplier_order 406 406 0 0.00 14 1230535 107541 0 0
sp_cancel_write_off 9273 9273 0 0.00 1 27410 113 0 0
sp_client_order 21353 21353 0 0.00 3 18437 381 0 0
sp_customer_reserve 477592 477592 0 0.00 0 73160 179 0 0
sp_fill_shopping_cart 32513 32513 0 0.00 1 19671 38 0 0
sp_fill_shopping_cart_clo_res 477582 477582 0 0.00 0 11601 13 0 0
sp_get_clo_for_invoice 5147 5147 0 0.00 0 22812 289 0 0
sp_kill_cost_storno 15909 15909 0 0.00 0 12149 60 0 0
sp_kill_qstorno_ret_qs2qd 1121302 1121302 0 0.00 0 27301 34 0 0
sp_kill_qty_storno 591349 591349 0 0.00 0 35329 73 0 0
sp_lock_selected_doc 21253 21253 0 0.00 0 9053 6 0 0
sp_make_cost_storno 28630 28630 0 0.00 0 14742 53 0 0
sp_multiply_rows_for_pdistr 28630 28630 0 0.00 0 14488 31 0 0
sp_multiply_rows_for_qdistr 21353 21353 0 0.00 0 12770 173 0 0
sp_pay_from_customer 7211 7211 0 0.00 4 20320 128 0 0
sp_pay_to_supplier 6004 6004 0 0.00 4 18050 152 0 0
sp_payment_common 13215 13215 0 0.00 1 20259 36 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 17707 367 0 0
sp_qd_handle_on_invoice_upd_sts 10701 10701 0 0.00 0 35328 340 0 0
sp_qd_handle_on_reserve_upd_sts 10268 10268 0 0.00 0 15592 62 0 0
sp_reserve_write_off 10268 10268 0 0.00 6 18452 196 0 0
sp_supplier_invoice 5506 5506 0 0.00 7 106390 3070 0 0
sp_supplier_order 5660 5660 0 0.00 4 49968 1243 0 0
srv_aggregate_perf_data 111 111 0 0.00 2556 409222 19731 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1785 156010 43911 0 0
srv_find_qd_qs_mism 1068388 1068388 0 0.00 0 18420 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 48 95593 1132 0 0
srv_make_money_saldo 784 784 0 0.00 1 14849 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 212627 474673 279998 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5157 5157 0 0.00 0 12201 153 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 4281 84 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 2371 12 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 3389 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 2452 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 596 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 7872 39 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 24290 1493 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2419 8 0 0
t$perf-norm:sp_client_order 21353 21353 0 0.00 0 14681 16 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 1150 5 0 0
t$perf-norm:sp_pay_from_customer 7211 7211 0 0.00 0 13891 12 0 0
t$perf-norm:sp_pay_to_supplier 6004 6004 0 0.00 0 4341 8 0 0
t$perf-norm:sp_reserve_write_off 10268 10268 0 0.00 0 10484 10 0 0
t$perf-norm:sp_supplier_invoice 5506 5506 0 0.00 0 5856 16 0 0
t$perf-norm:sp_supplier_order 5660 5660 0 0.00 0 2955 12 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 109 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 1076 17 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 1088 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 61 17 0 0
v_add_invoice_to_stock 10500 10292 208 1.98 0 7216 20 0 208
v_all_customers 38838 38838 0 0.00 0 47 1 0 0
v_all_suppliers 31478 31478 0 0.00 0 16 1 0 0
v_all_wares 42706 42706 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 5233 22 0 0
v_cancel_client_order 3612 3612 0 0.00 0 2676 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 4926 8 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 4403 12 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 174 3 0 0
v_cancel_supplier_invoice 856 846 10 1.16 0 3873 17 0 10
v_cancel_supplier_order 812 812 0 0.00 0 829 4 0 0
v_cancel_write_off 2028 2028 0 0.00 0 3572 18 0 0
v_max_id_clo_ord 5658 5658 0 0.00 0 1566 2 0 0
v_max_id_clo_res 970 970 0 0.00 0 3842 23 0 0
v_max_id_ord_sup 5501 5501 0 0.00 0 3751 5 0 0
v_max_non_paid_invoice 1424 1424 0 0.00 0 118 2 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 508 3 0 0
v_min_id_clo_ord 5658 5658 0 0.00 0 5128 7 0 0
v_min_id_clo_res 970 970 0 0.00 0 4407 17 0 0
v_min_id_ord_sup 5501 5501 0 0.00 0 10442 13 0 0
v_min_non_paid_invoice 6004 6004 0 0.00 0 13609 28 0 0
v_min_non_paid_realizn 7211 7211 0 0.00 0 13577 27 0 0
v_our_firm 10450 10450 0 0.00 0 127 1 0 0
v_reserve_write_off 20536 20536 0 0.00 0 9228 11 0 0
x_lock_depdocs_on_canc_invoice 11298 11298 0 0.00 0 10084 106 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 3781 321 0 0
x_make_qstorno_1000_1200 5660 5660 0 0.00 1 49961 1174 0 0
x_make_qstorno_1200_2000 5506 5506 0 0.00 3 106277 2921 0 0
x_make_qstorno_2100_3300 477592 477592 0 0.00 0 73159 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2022 16:41:13 to 26-12-2022 16:41:17.

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
14:08:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:36 6631 8192000000 9896033008 10198818816 977717728 10.662 100 92 182 1 1007012368 5538496 23756144
14:10:51 22253 8192000000 9932215200 10248007680 1011011168 10.985 100 89 178 0 1042216800 8002976 23202656
14:12:16 12072 8192000000 9955071952 10279485440 1043396048 11.297 100 87 174 0 1074758032 9056112 22305872
14:13:37 9374 8192000000 10018679344 10362892288 1108319040 11.917 100 74 146 0 1135836464 9503328 18014096
14:14:49 6058 8192000000 10070645072 10424569856 1158632304 12.390 100 79 158 0 1188354560 10994752 18727504
14:16:00 3316 8192000000 10128774192 10494496768 1209647024 12.866 100 84 166 0 1241518976 13206752 18665200
14:17:06 2444 8192000000 10154530032 10531590144 1246949776 13.210 100 77 149 0 1275782432 13078128 15754528
14:18:12 1826 8192000000 10179791808 10561888256 1273023504 13.449 100 73 146 0 1301104672 11864768 16216400
14:19:16 1482 8192000000 10209736240 10591965184 1291255680 13.616 100 92 184 1 1325042640 13696064 20090896
14:20:19 13775 8192000000 10193990032 10593210368 1295861584 13.658 100 55 110 1 1317198800 9222816 12114400
14:21:35 2783 8192000000 10197180288 10605060096 1306129424 13.751 100 43 87 0 1324513392 8886288 9497680
14:22:41 8234 8192000000 10202325152 10614812672 1316647568 13.846 100 32 64 1 1329684336 6049392 6987376
14:23:50 11351 8192000000 10198406768 10619977728 1316558256 13.846 100 22 44 0 1327616224 5917296 5140672
14:25:03 34129 8192000000 10224791392 10637504512 1322009680 13.895 100 27 52 0 1333967472 6381808 5575984
14:26:37 4149 8192000000 10228594176 10639839232 1327903008 13.948 100 43 85 0 1344373664 5965632 10505024
14:27:44 8578 8192000000 10217624240 10634186752 1325855296 13.930 100 22 44 0 1336817168 5792032 5169840
14:28:55 11032 8192000000 10207778112 10625695744 1316995616 13.849 100 16 32 0 1326552928 5986080 3571232
14:30:07 24456 8192000000 10189734848 10601639936 1251124160 13.249 95 26 50 3 1262263920 5609872 5529888
14:31:32 5806 8192000000 10143404176 10542325760 1212891392 12.896 96 30 60 0 1225873808 5843904 7138512
14:32:39 8095 8192000000 10140897696 10536804352 1236187168 13.111 99 22 44 0 1246082352 4656080 5239104
14:33:52 16851 8192000000 10154062352 10560561152 1246319024 13.204 98 26 52 0 1257636656 5310816 6006816
14:35:11 25742 8192000000 10162618336 10575540224 1275657040 13.473 100 17 34 1 1284656368 5352832 3646496
14:36:37 9172 8192000000 10178353040 10591158272 1287487504 13.581 100 25 50 0 1299528032 6588096 5452432
14:37:47 8445 8192000000 10193288304 10605219840 1296284448 13.661 100 24 48 0 1307919232 5779456 5855328
14:39:01 27478 8192000000 10245788640 10654400512 1336111904 14.022 100 44 83 4 1353869136 8172912 9584320
14:40:37 54242 8192000000 10272539376 10683342848 1357027680 14.211 100 48 96 1 1379714768 10864112 11822976
14:42:39 33290 8192000000 10271105472 10694578176 1364222656 14.275 100 39 77 0 1388013760 15421072 8370032
14:44:51 19418 8192000000 10292551680 10716160000 1375911840 14.380 100 40 79 2 1403085520 18206784 8966896
14:46:15 2213 8192000000 10313650400 10727522304 1385052640 14.462 100 72 144 1 1423122848 21370992 16699216
14:47:37 7300 8192000000 10331844032 10748465152 1404372992 14.634 100 76 145 0 1445217696 23750336 17094368
14:48:47 4405 8192000000 10347356912 10771103744 1416912624 14.745 100 56 109 0 1457646720 27670816 13063280
14:49:57 7197 8192000000 10344543888 10772860928 1419178160 14.765 100 47 94 1 1454551472 23371696 12001616
14:51:13 4388 8192000000 10362282464 10788327424 1427666640 14.841 100 56 112 1 1468940944 27273952 14000352
14:52:35 1785 8192000000 10386522176 10804023296 1433907584 14.896 100 90 178 1 1487538160 31570752 22059824
14:53:40 1814 8192000000 10391790912 10809995264 1435031392 14.906 100 89 177 2 1497001072 39876816 22092864
14:54:53 8083 8192000000 10389663632 10825494528 1431009456 14.870 99 58 116 1 1487291088 41150448 15131184
14:56:07 2745 8192000000 10374974608 10793930752 1416543936 14.742 100 69 137 2 1478030400 44852160 16634304
14:57:17 3666 8192000000 10387294576 10802413568 1418418416 14.759 100 79 155 1 1485903424 48111888 19373120
14:58:26 3256 8192000000 10363871728 10781478912 1405998656 14.648 100 61 120 1 1465944768 44158304 15787808
14:59:32 7185 8192000000 10352765280 10771406848 1391740928 14.521 100 49 98 0 1455817408 51355072 12721408
15:00:54 15556 8192000000 10351963696 10776203264 1374414016 14.367 98 43 82 1 1434682320 49497520 10770784
15:02:18 2714 8192000000 10356483472 10783977472 1391729552 14.521 100 51 101 0 1463770896 55996624 16044720
15:03:26 14156 8192000000 10370093680 10796425216 1378785824 14.406 99 46 92 0 1456596960 65133648 12677488
15:04:43 28191 8192000000 10324625904 10765729792 1354892832 14.191 98 25 50 0 1422469648 60154160 7422656
15:06:19 29353 8192000000 10289542544 10739560448 1316970992 13.849 97 18 35 0 1373100896 51201680 4928224
15:07:58 18738 8192000000 10276950464 10697420800 1331798496 13.983 98 47 91 0 1352684848 10299600 10586752
15:09:17 27128 8192000000 10275910000 10702548992 1352058160 14.166 100 36 66 0 1365219904 5393296 7768448
15:10:48 68954 8192000000 10250892816 10693337088 1281914128 13.530 94 14 29 0 1299778720 15105824 2758768
15:13:01 23714 8192000000 10279958400 10715262976 1360266800 14.240 100 31 59 1 1376161040 8587904 7306336
15:14:25 97362 8192000000 10292028896 10724483072 1363840640 14.272 99 50 100 1 1387308752 10516080 12952032
15:17:17 61103 8192000000 10252693952 10699808768 1335630672 14.018 98 21 43 0 1356813856 15590432 5592752
15:19:20 139005 8192000000 10276317984 10721280000 1338986752 14.048 98 17 34 0 1356056176 13140720 3928704
15:22:43 15529 8192000000 10306870640 10749784064 1393691296 14.539 100 36 71 0 1414746096 13044640 8010160
15:24:00 55006 8192000000 10291059824 10756296704 1398430928 14.581 100 23 46 0 1426001968 21726960 5844080
15:26:21 66658 8192000000 10292069440 10753806336 1395077408 14.551 100 2 4 0 1406364512 10946848 340256
15:28:29 46513 8192000000 10290233072 10757283840 1386557872 14.475 99 18 36 0 1402494144 12213904 3722368
15:30:17 57017 8192000000 10321332352 10787962880 1411585088 14.698 100 15 30 0 1426985696 11891536 3509072
15:32:23 18000 8192000000 10304806640 10765336576 1401391920 14.607 100 37 74 1 1421866688 11865536 8609232
15:33:50 47449 8192000000 10304597344 10762547200 1389974304 14.506 99 14 28 1 1405265968 12359184 2932480
15:35:41 13302 8192000000 10319962800 10762801152 1403625456 14.627 100 51 101 1 1423829392 8899504 11304432
15:36:55 53192 8192000000 10305517008 10747236352 1338620288 14.045 96 27 54 1 1354975008 10227312 6127408
15:38:48 156010 8192000000 10260507376 10711658496 1237269216 13.121 89 15 30 0 1255395504 14953776 3172512
15:42:43 62709 8192000000 10297809792 10746757120 1348894192 14.138 97 21 40 0 1366914160 13931968 4088000
15:45:21 84487 8192000000 10271018224 10716049408 1323460032 13.908 96 26 50 1 1337785584 8199216 6126336
15:47:46 33263 8192000000 10249759008 10697596928 1350037536 14.148 100 21 42 0 1363576928 8796752 4742640
15:49:20 65640 8192000000 10272619472 10725703680 1359940208 14.237 99 13 26 0 1372739712 9580832 3218672
15:51:43 34414 8192000000 10271057168 10714689536 1331816432 13.984 97 22 45 1 1347541376 10969552 4755392
15:53:32 59861 8192000000 10260045392 10715627520 1331650576 13.982 97 15 30 0 1349844016 14150576 4042864
15:55:53 56890 8192000000 10275052336 10718949376 1328686416 13.955 97 10 20 0 1337483376 6851792 1945168
15:57:57 45764 8192000000 10270402368 10716323840 1325275392 13.924 97 10 20 0 1343562464 15862032 2425040
15:59:45 59464 8192000000 10266233088 10713145344 1324480832 13.917 97 22 43 0 1339062752 9799136 4782784
16:02:04 50893 8192000000 10280681008 10716352512 1335302752 14.015 98 20 40 0 1361707776 21877616 4527408
16:04:31 41409 8192000000 10278319136 10717347840 1353245408 14.177 99 34 68 0 1370777360 9480352 8051600
16:06:14 55057 8192000000 10280625904 10728083456 1352488448 14.170 99 35 70 0 1375930800 15885072 7557280
16:08:12 28991 8192000000 10302645904 10747416576 1374316992 14.366 99 35 70 1 1395719920 14072992 7329936
16:09:42 136178 8192000000 10279255712 10724458496 1352713824 14.172 98 11 22 0 1367188000 12264016 2210160
16:13:00 76329 8192000000 10271646624 10738311168 1375615248 14.377 100 5 10 0 1384216912 7806048 795616
16:15:17 40313 8192000000 10296519712 10749607936 1392239648 14.526 100 19 38 0 1403444656 7024528 4180480
16:16:59 67639 8192000000 10302016016 10749693952 1379317632 14.410 99 16 32 0 1390211344 7361056 3532656
16:19:14 46008 8192000000 10300491696 10763952128 1382259760 14.437 99 20 40 0 1399434544 11994704 5180080
16:21:01 34360 8192000000 10308124640 10761191424 1400391248 14.598 100 23 46 0 1419316848 13863888 5061712
16:22:55 71667 8192000000 10295867504 10738872320 1326519728 13.936 95 19 36 0 1340135520 9248864 4366928
16:25:07 40670 8192000000 10288437824 10721267712 1323299216 13.907 96 36 72 0 1341302496 10158464 7844816
16:26:50 70898 8192000000 10293499776 10733412352 1376217024 14.383 100 31 60 2 1393245536 9838368 7190144
16:29:13 93231 8192000000 10257163040 10718334976 1311251776 13.797 96 30 60 1 1335621280 17586688 6782816
16:32:13 76514 8192000000 10281040016 10726137856 1362735040 14.262 99 31 62 0 1387257264 17419840 7102384
16:34:30 63960 8192000000 10253500192 10716540928 1327478832 13.944 97 13 26 0 1341293888 10348560 3466496
16:37:30 70483 8192000000 9951885104 10354581504 1061083216 11.467 78 13 25 0 1072670192 8877120 2709856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-12-2022 16:41:19 to 26-12-2022 16:41:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 26-12-2022 16:41:35 to 26-12-2022 16:41:38.

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          240775
MON$OLDEST_ACTIVE               242800
MON$OLDEST_SNAPSHOT             242800
MON$NEXT_TRANSACTION            242800
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                       2020736
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:688: 
:689: mscs-test-161.budzdorov.ru    Mon Dec 26 16:38:53 2022
:690:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:691: 
:692: 
:693: mscs-test-161.budzdorov.ru    Mon Dec 26 16:38:57 2022
:694:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:695: 
--- end of diff output ---

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

26.12.22 16:41:39. 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
2022-12-26 16:41:41 No severe PSQL-related problems occured
26.12.22 16:41:42. End of report.