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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16433364 kB
MemAvailable 31388808 kB
Buffers 0 kB
Cached 15310100 kB
SwapCached 2440 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.10
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-05-31 19:38:19
DTS_END                         2022-05-31 19:38:19.9010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36510
TRN_ID                          243863
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: 9372.42 17619 1124690
customer order: creation 177.16 326 21259
customer order: refuse 14.96 337 1795
order to supplier: creation 45.52 1324 5462
order to supplier: removal 3.34 96702 401
invoice (draft): creation 46.98 3536 5638
invoice (draft): removal 50.98 798 6118
invoice accept: apply 43.96 18615 5275
invoice accept: cancel 46.43 927 5572
customer reserve: creation 4061.96 183 487435
customer reserve: removal 4589.89 54 550787
realization accept: apply 84.35 190 10122
realization accept: cancel 75.08 123 9009
payment from customer: creation 59.43 119 7131
payment from customer: removal 5.77 99 692
payment to supplier: creation 50.14 138 6017
payment to supplier: removal 3.72 71 446
service: total inventory turnovers 4.77 1236 572
service: total monetary turnovers 7.27 34 872
service: aggregate perf. data 0.69 25875 83
service: refresh index statistics 0.03 201697 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-05-2022 19:40:10 to 31-05-2022 19:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5248
TEST_TIME 2 4446
TEST_TIME 3 4168
TEST_TIME 4 4103
TEST_TIME 5 6124
TEST_TIME 6 6407
TEST_TIME 7 6390
TEST_TIME 8 6701
TEST_TIME 9 6655
TEST_TIME 10 6694
TEST_TIME 11 6885
TEST_TIME 12 6966
TEST_TIME 13 6973
TEST_TIME 14 7199
TEST_TIME 15 7353
TEST_TIME 16 7426
TEST_TIME 17 7553
TEST_TIME 18 7843
TEST_TIME 19 8343
TEST_TIME 20 8605
TEST_TIME 21 8649
TEST_TIME 22 9483
TEST_TIME 23 9419
TEST_TIME 24 9351
TEST_TIME 25 9663
TEST_TIME 26 9657
TEST_TIME 27 10116
TEST_TIME 28 10900
TEST_TIME 29 10826
TEST_TIME 30 10643
TEST_TIME 31 10477
TEST_TIME 32 10364
TEST_TIME 33 10718
TEST_TIME 34 10725
TEST_TIME 35 10561
TEST_TIME 36 10504
TEST_TIME 37 10281
TEST_TIME 38 10196
TEST_TIME 39 10058
TEST_TIME 40 10091
TEST_TIME 41 10075
TEST_TIME 42 9956
TEST_TIME 43 9868
TEST_TIME 44 9946
TEST_TIME 45 9960
TEST_TIME 46 9976
TEST_TIME 47 9920
TEST_TIME 48 9838
TEST_TIME 49 9705
TEST_TIME 50 9611
TEST_TIME 51 9458
TEST_TIME 52 9494
TEST_TIME 53 9555
TEST_TIME 54 9526
TEST_TIME 55 9508
TEST_TIME 56 9585
TEST_TIME 57 9616
TEST_TIME 58 9696
TEST_TIME 59 9632
TEST_TIME 60 9828
TEST_TIME 61 9793
TEST_TIME 62 9738
TEST_TIME 63 9878
TEST_TIME 64 9845
TEST_TIME 65 9905
TEST_TIME 66 9857
TEST_TIME 67 9819
TEST_TIME 68 9711
TEST_TIME 69 9775
TEST_TIME 70 9694
TEST_TIME 71 9690
TEST_TIME 72 9708
TEST_TIME 73 9658
TEST_TIME 74 9633
TEST_TIME 75 9598
TEST_TIME 76 9540
TEST_TIME 77 9483
TEST_TIME 78 9452
TEST_TIME 79 9375
TEST_TIME 80 9389
TEST_TIME 81 9406
TEST_TIME 82 9414
TEST_TIME 83 9437
TEST_TIME 84 9466
TEST_TIME 85 9439
TEST_TIME 86 9468
TEST_TIME 87 9473
TEST_TIME 88 9503
TEST_TIME 89 9556
TEST_TIME 90 9522
TEST_TIME 91 9647
TEST_TIME 92 9624
TEST_TIME 93 9691
TEST_TIME 94 9610
TEST_TIME 95 9547
TEST_TIME 96 9547
TEST_TIME 97 9541
TEST_TIME 98 9503
TEST_TIME 99 9460
TEST_TIME 100 9443
TEST_TIME 101 9440
TEST_TIME 102 9438
TEST_TIME 103 9407
TEST_TIME 104 9441
TEST_TIME 105 9440
TEST_TIME 106 9454
TEST_TIME 107 9503
TEST_TIME 108 9499
TEST_TIME 109 9542
TEST_TIME 110 9528
TEST_TIME 111 9522
TEST_TIME 112 9597
TEST_TIME 113 9610
TEST_TIME 114 9598
TEST_TIME 115 9545
TEST_TIME 116 9496
TEST_TIME 117 9454
TEST_TIME 118 9475
TEST_TIME 119 9444
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-05-2022 19:40:14 to 31-05-2022 19:40:17.

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 1575205 1575205 0 0.00 0 13730 7 0 0
doc_list_biud 1575204 1575204 0 0.00 0 56264 25 0 0
sp_add_invoice_to_stock 5401 5275 126 2.33 39 199370 18615 0 126
sp_cancel_adding_invoice 5572 5572 0 0.00 5 90045 927 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 18858 337 0 0
sp_cancel_customer_reserve 550787 550787 0 0.00 1 25820 55 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 4133 100 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 2698 71 0 0
sp_cancel_supplier_invoice 6127 6118 9 0.14 1 56268 798 0 9
sp_cancel_supplier_order 401 401 0 0.00 6 1027728 96703 0 0
sp_cancel_write_off 9009 9009 0 0.00 1 18982 123 0 0
sp_client_order 21259 21259 0 0.00 3 41046 327 0 0
sp_customer_reserve 487435 487435 0 0.00 0 69693 183 0 0
sp_fill_shopping_cart 32348 32348 0 0.00 1 10518 39 0 0
sp_fill_shopping_cart_clo_res 487429 487429 0 0.00 0 25197 12 0 0
sp_get_clo_for_invoice 5269 5269 0 0.00 0 35731 301 0 0
sp_kill_cost_storno 15719 15719 0 0.00 0 12794 59 0 0
sp_kill_qstorno_ret_qs2qd 1117102 1117102 0 0.00 0 56263 30 0 0
sp_kill_qty_storno 589073 589073 0 0.00 0 56264 66 0 0
sp_lock_selected_doc 21240 21240 0 0.00 0 10648 6 0 0
sp_make_cost_storno 28539 28539 0 0.00 0 13582 57 0 0
sp_multiply_rows_for_pdistr 28538 28538 0 0.00 0 10922 32 0 0
sp_multiply_rows_for_qdistr 21259 21259 0 0.00 0 41001 135 0 0
sp_pay_from_customer 7131 7131 0 0.00 4 10936 119 0 0
sp_pay_to_supplier 6017 6017 0 0.00 4 30408 138 0 0
sp_payment_common 13147 13147 0 0.00 1 10607 29 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 18848 307 0 0
sp_qd_handle_on_invoice_upd_sts 10841 10841 0 0.00 0 33725 364 0 0
sp_qd_handle_on_reserve_upd_sts 10122 10122 0 0.00 0 18492 61 0 0
sp_reserve_write_off 10122 10122 0 0.00 6 21794 191 0 0
sp_supplier_invoice 5638 5638 0 0.00 4 94033 3537 0 0
sp_supplier_order 5462 5462 0 0.00 6 82412 1324 0 0
srv_aggregate_perf_data 83 83 0 0.00 2847 373197 25876 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1773 138754 45827 0 0
srv_find_qd_qs_mism 1075003 1075003 0 0.00 0 13531 6 0 0
srv_make_invnt_saldo 572 572 0 0.00 29 87742 1236 0 0
srv_make_money_saldo 872 872 0 0.00 1 5623 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 130749 320775 201697 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 5275 5275 0 0.00 0 11004 217 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 8703 179 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 3875 15 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2379 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 3500 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 3014 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 910 14 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 22166 1659 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 2272 11 0 0
t$perf-norm:sp_client_order 21259 21259 0 0.00 0 9794 21 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 826 3 0 0
t$perf-norm:sp_pay_from_customer 7131 7131 0 0.00 0 10357 15 0 0
t$perf-norm:sp_pay_to_supplier 6017 6017 0 0.00 0 7985 12 0 0
t$perf-norm:sp_reserve_write_off 10122 10122 0 0.00 0 9100 15 0 0
t$perf-norm:sp_supplier_invoice 5638 5638 0 0.00 0 3298 19 0 0
t$perf-norm:sp_supplier_order 5462 5462 0 0.00 0 5921 18 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 154 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 256 6 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 2644 35 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 6633 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10790 10538 252 2.33 0 6696 20 0 252
v_all_customers 38442 38442 0 0.00 0 23 1 0 0
v_all_suppliers 30764 30764 0 0.00 0 56 1 0 0
v_all_wares 42516 42516 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 576 7 0 0
v_cancel_client_order 3590 3590 0 0.00 0 4165 5 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 4113 14 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 884 6 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 2676 8 0 0
v_cancel_supplier_invoice 820 802 18 2.19 0 5546 29 0 18
v_cancel_supplier_order 802 802 0 0.00 0 1591 6 0 0
v_cancel_write_off 2026 2026 0 0.00 0 3354 14 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 373 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 1482 12 0 0
v_max_id_ord_sup 5632 5632 0 0.00 0 4931 5 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 156 2 0 0
v_max_non_paid_realizn 3838 3838 0 0.00 0 3305 5 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 4433 7 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 6119 17 0 0
v_min_id_ord_sup 5632 5632 0 0.00 0 4427 7 0 0
v_min_non_paid_invoice 6016 6016 0 0.00 0 7781 22 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 8016 20 0 0
v_our_firm 10660 10660 0 0.00 0 179 1 0 0
v_reserve_write_off 20244 20244 0 0.00 0 6537 7 0 0
x_lock_depdocs_on_canc_invoice 11289 11289 0 0.00 0 26639 120 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 24812 423 0 0
x_make_qstorno_1000_1200 5462 5462 0 0.00 1 82384 1241 0 0
x_make_qstorno_1200_2000 5638 5638 0 0.00 2 93886 3399 0 0
x_make_qstorno_2100_3300 487435 487435 0 0.00 0 69691 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-05-2022 19:40:18 to 31-05-2022 19:40:22.

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
17:08:23 6 8192000000 8799266640 8810692608 5315664 0.064 2 1 2 0 5415264 56816 42784
17:09:24 19259 8192000000 9896862080 10205339648 979584096 10.680 100 89 178 0 1008962080 5833776 23544208
17:10:52 19515 8192000000 9943783712 10267377664 1024602288 11.116 100 90 180 1 1056491264 8139920 23749056
17:12:16 20609 8192000000 9981223840 10315554816 1066463296 11.518 100 78 156 1 1096206416 10128416 19614704
17:13:41 11500 8192000000 10040554192 10387460096 1121042656 12.037 100 79 158 0 1151401440 11481568 18877216
17:14:55 7666 8192000000 10080742080 10441248768 1169689120 12.494 100 78 156 2 1200897328 13051440 18156768
17:16:05 6719 8192000000 10129350032 10495361024 1211506848 12.883 100 87 173 0 1245223424 13933024 19783552
17:17:15 2975 8192000000 10167146208 10544783360 1257175408 13.304 100 84 167 0 1288982576 13244448 18562720
17:18:20 1598 8192000000 10198724096 10579558400 1285415584 13.562 100 87 175 2 1317383040 12483728 19483728
17:19:23 12874 8192000000 10210120896 10598313984 1294241632 13.643 100 92 181 2 1327405200 12360496 20803072
17:20:37 24728 8192000000 10192341216 10594766848 1296561968 13.664 100 42 85 1 1312477296 5568608 10346720
17:22:02 6025 8192000000 10194116560 10609094656 1312065088 13.805 100 19 38 0 1322180336 5863392 4251856
17:23:11 5649 8192000000 10207756240 10624716800 1316884096 13.848 100 35 69 0 1334989760 10609040 7496624
17:24:18 8776 8192000000 10211104016 10632773632 1328108992 13.950 100 25 50 2 1339886848 6360896 5416960
17:25:29 52598 8192000000 10220678352 10647191552 1337223600 14.032 100 17 34 0 1346005040 4986304 3795136
17:27:23 4995 8192000000 10223347824 10637725696 1324745232 13.920 100 18 36 1 1334221344 5717184 3758928
17:28:29 4630 8192000000 10208826112 10617856000 1287915760 13.585 98 21 39 1 1297607168 5487328 4204080
17:29:37 65244 8192000000 10158702608 10560364544 1194188704 12.722 93 33 66 1 1207418608 5288800 7941104
17:31:46 4728 8192000000 10149477696 10547851264 1222233248 12.982 96 32 63 2 1236531328 7427552 6870528
17:32:56 9045 8192000000 10158877824 10562949120 1249034032 13.229 98 23 46 0 1259897888 5869248 4994608
17:34:10 13382 8192000000 10187407264 10587037696 1284526912 13.554 100 23 46 2 1294962720 5452288 4983520
17:35:24 26788 8192000000 10183673888 10598072320 1293287776 13.634 100 17 34 1 1306593328 9574240 3731312
17:36:51 10489 8192000000 10209799328 10619604992 1311521280 13.800 100 28 56 1 1323450592 5532000 6397312
17:38:03 10908 8192000000 10218177488 10634899456 1324895488 13.921 100 27 54 0 1336593536 5475008 6223040
17:39:24 21985 8192000000 10261305696 10669477888 1351449040 14.161 100 46 92 1 1370623968 9050848 10124080
17:40:51 96539 8192000000 10267532704 10693234688 1365032576 14.283 100 37 74 0 1387713008 13786224 8894208
17:43:57 2484 8192000000 10285433232 10706526208 1370142720 14.328 100 55 109 0 1395243488 12727760 12373008
17:45:04 9791 8192000000 10304010848 10723217408 1383840144 14.451 100 58 116 1 1412023472 14983744 13199584
17:46:20 7831 8192000000 10314561728 10734059520 1387826080 14.486 100 54 108 0 1421814960 21556176 12432704
17:47:32 17103 8192000000 10308358240 10723078144 1380888816 14.424 99 64 127 1 1415364080 20402672 14072592
17:48:56 3220 8192000000 10324519136 10738515968 1379949040 14.416 99 64 128 2 1421391600 26582848 14859712
17:50:04 6837 8192000000 10335460176 10751987712 1394839376 14.549 99 68 134 0 1439509040 28585696 16083968
17:51:15 5057 8192000000 10329416304 10742165504 1387457808 14.483 100 79 158 2 1436685248 30310944 18916496
17:52:28 3697 8192000000 10328252496 10736037888 1372548592 14.350 99 80 160 1 1425689504 33084208 20056704
17:53:36 2188 8192000000 10349047632 10760863744 1400073920 14.596 100 69 137 0 1450465632 33875072 16516640
17:54:42 2035 8192000000 10342610944 10745446400 1387700000 14.485 100 88 177 3 1449390768 39337472 22353296
17:55:49 2758 8192000000 10350683680 10759548928 1386581488 14.475 99 70 141 3 1447221040 42191744 18447808
17:57:00 1771 8192000000 10364007584 10766307328 1389199520 14.499 100 96 191 0 1462093664 48949408 23944736
17:58:08 13650 8192000000 10333797856 10751102976 1368394992 14.313 99 47 94 0 1433420336 52161584 12863760
17:59:24 4922 8192000000 10350111120 10763550720 1368091232 14.310 99 78 154 3 1441439936 53284448 20064256
18:00:33 10325 8192000000 10333614496 10762657792 1386697168 14.476 100 37 74 2 1446793744 49316048 10780528
18:02:02 18549 8192000000 10320553456 10748690432 1375263696 14.374 100 40 80 1 1427446688 40891536 11291456
18:03:42 12870 8192000000 10292809744 10720043008 1332356304 13.988 98 25 50 0 1384307200 45428192 6522704
18:05:15 24202 8192000000 10285761184 10737147904 1344966880 14.102 100 18 36 2 1402477136 51792016 5718240
18:06:46 73669 8192000000 10263185280 10710634496 1345654448 14.108 99 14 30 2 1363975248 15425440 2895360
18:09:01 43992 8192000000 10307827488 10739261440 1380377936 14.420 100 30 60 1 1401249632 14149776 6721920
18:10:50 134019 8192000000 10311658128 10776375296 1358616560 14.225 98 5 10 0 1388597216 28913792 1066864
18:14:05 25608 8192000000 10302138224 10744786944 1374853248 14.371 98 40 80 2 1395221824 10840272 9528304
18:16:20 119585 8192000000 10291107888 10740776960 1369645504 14.324 99 27 54 2 1389438384 13462352 6330528
18:19:23 51405 8192000000 10307934384 10755739648 1387111600 14.480 100 32 64 0 1409238624 14202832 7924192
18:21:26 37803 8192000000 10310658320 10766024704 1397903424 14.576 100 14 28 1 1422509632 21190464 3415744
18:23:09 35513 8192000000 10290301440 10744238080 1369393632 14.322 98 9 18 1 1382152256 10318768 2439856
18:24:49 25511 8192000000 10292707520 10757246976 1393283280 14.535 100 9 17 0 1403434016 8299248 1851488
18:26:17 92086 8192000000 10287159312 10750414848 1376880240 14.389 99 23 46 1 1389438768 6817200 5741328
18:28:58 52047 8192000000 10296325744 10762403840 1397333680 14.571 100 14 28 1 1416497520 16050752 3113088
18:31:01 62069 8192000000 10298526368 10772443136 1392477424 14.528 100 18 36 1 1415568064 18353824 4736816
18:33:05 37086 8192000000 10316504144 10763923456 1387252496 14.481 99 39 77 0 1412251152 15660000 9338656
18:34:43 46156 8192000000 10281081232 10737270784 1354340704 14.187 98 19 38 1 1372357424 13598864 4417856
18:36:47 66301 8192000000 10300391472 10755764224 1349735440 14.145 97 13 26 0 1365712384 13038384 2938560
18:38:54 85575 8192000000 10287743312 10722476032 1281668048 13.528 92 36 72 1 1303579520 14065984 7845488
18:41:22 74419 8192000000 10289105072 10747248640 1349719824 14.145 98 13 26 0 1371949056 19168944 3060288
18:43:43 75922 8192000000 10291729440 10734800896 1315181040 13.833 95 15 30 0 1332488416 13877328 3430048
18:46:04 119319 8192000000 10266960688 10720985088 1320481104 13.881 96 23 46 1 1336268816 10490176 5297536
18:49:13 25746 8192000000 10278186752 10731184128 1364427168 14.277 100 44 88 0 1386186048 11687360 10071520
18:50:44 46465 8192000000 10264573680 10724114432 1365474624 14.286 100 7 14 1 1378670768 11707408 1488736
18:52:31 58956 8192000000 10239477056 10695450624 1317332576 13.853 96 9 18 0 1325920064 6503040 2084448
18:54:32 73522 8192000000 10226281248 10683002880 1321937600 13.894 98 16 32 0 1332309920 6361568 4010752
18:56:50 59107 8192000000 10254863472 10708238336 1344312816 14.096 99 14 27 1 1358388992 11078112 2998064
18:58:49 60581 8192000000 10255428160 10728435712 1346125296 14.113 99 8 16 0 1359010224 11208288 1676640
19:00:52 62995 8192000000 10249090384 10708688896 1291811712 13.621 95 12 24 0 1301975408 7641024 2522672
19:03:02 36345 8192000000 10257651296 10704683008 1286863280 13.576 95 26 52 1 1310174656 17447344 5864032
19:04:41 16476 8192000000 10263781872 10697752576 1334981584 14.012 99 49 97 2 1354982512 9387536 10613392
19:05:58 69067 8192000000 10272701440 10730225664 1366023840 14.291 100 16 32 0 1383632256 13947456 3660960
19:08:12 18144 8192000000 10315751648 10752778240 1385540928 14.466 100 31 62 0 1403442816 11439472 6462416
19:09:35 138754 8192000000 10281644016 10747305984 1314167696 13.824 95 14 28 0 1333613968 16511360 2934912
19:13:00 91656 8192000000 10282859232 10741067776 1377194048 14.391 100 23 46 0 1396251920 13686880 5370992
19:15:32 46488 8192000000 10267354736 10740826112 1377769072 14.397 100 6 12 0 1393295456 14103440 1422944
19:17:23 58650 8192000000 10261823408 10721394688 1335169264 14.014 97 14 29 0 1348436160 10022624 3244272
19:20:37 40317 8192000000 10288782096 10746216448 1385679568 14.467 100 19 37 0 1396643312 6954304 4009440
19:22:27 35604 8192000000 10285886752 10747121664 1383006096 14.443 100 19 38 1 1398723136 11539920 4177120
19:24:03 44407 8192000000 10283836048 10747310080 1349465440 14.143 97 14 28 1 1364011456 11214192 3331824
19:25:51 79077 8192000000 10275841536 10727137280 1322361744 13.898 96 22 44 0 1339806352 12323536 5121072
19:28:12 28474 8192000000 10288571216 10732453888 1339953952 14.057 97 29 58 2 1356995056 10085792 6955312
19:29:51 69247 8192000000 10298401920 10762674176 1341284128 14.069 97 23 46 1 1372144880 25897312 4963440
19:32:08 82331 8192000000 10266001280 10726322176 1321762016 13.893 96 11 22 0 1339645648 15037664 2845968
19:34:53 57274 8192000000 10286387008 10727149568 1364975472 14.282 99 47 94 1 1385387520 9551824 10860224
19:36:50 61461 8192000000 10244813888 10710175744 1334791120 14.010 98 7 14 1 1344830432 8003376 2035936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-05-2022 19:40:24 to 31-05-2022 19:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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 31-05-2022 19:40:40 to 31-05-2022 19:40:42.

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          243613
MON$OLDEST_ACTIVE               243973
MON$OLDEST_SNAPSHOT             243973
MON$NEXT_TRANSACTION            243973
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                       2031520
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:267: 
:268: mscs-test-161.budzdorov.ru    Tue May 31 19:38:34 2022
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:270: 
:271: 
:272: mscs-test-161.budzdorov.ru    Tue May 31 19:38:34 2022
:273:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:274: 
:275: 
:276: mscs-test-161.budzdorov.ru    Tue May 31 19:38:34 2022
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
--- end of diff output ---

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

31.05.22 19:40:43. 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-05-31 19:40:45 No severe PSQL-related problems occured
31.05.22 19:40:46. End of report.