Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.05.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 16545672 kB
MemAvailable 31403676 kB
Buffers 0 kB
Cached 15204044 kB
SwapCached 2436 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-29 16:38:19
DTS_END                         2022-05-29 16:38:19.9730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_TO_SUPPLIER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60352
TRN_ID                          237330
ATT_ID                          669
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: 9247.03 22453 1109644
customer order: creation 169.79 373 20375
customer order: refuse 14.03 335 1683
order to supplier: creation 45.08 1318 5410
order to supplier: removal 3.57 113725 428
invoice (draft): creation 44.96 3711 5395
invoice (draft): removal 54.27 870 6512
invoice accept: apply 40.13 19207 4816
invoice accept: cancel 48.73 768 5847
customer reserve: creation 3752.63 188 450315
customer reserve: removal 4783.19 66 573983
realization accept: apply 81.01 161 9721
realization accept: cancel 82.08 119 9849
payment from customer: creation 58.48 122 7017
payment from customer: removal 5.68 80 681
payment to supplier: creation 48.09 138 5771
payment to supplier: removal 3.57 57 428
service: total inventory turnovers 4.70 1161 564
service: total monetary turnovers 6.21 26 745
service: aggregate perf. data 0.83 19473 100
service: refresh index statistics 0.03 287153 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-05-2022 16:40:36 to 29-05-2022 16:40:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2380
TEST_TIME 2 3199
TEST_TIME 3 3544
TEST_TIME 4 3819
TEST_TIME 5 4268
TEST_TIME 6 4519
TEST_TIME 7 4697
TEST_TIME 8 5156
TEST_TIME 9 5373
TEST_TIME 10 6195
TEST_TIME 11 6502
TEST_TIME 12 6717
TEST_TIME 13 6827
TEST_TIME 14 7040
TEST_TIME 15 6929
TEST_TIME 16 7362
TEST_TIME 17 7204
TEST_TIME 18 7815
TEST_TIME 19 8185
TEST_TIME 20 7991
TEST_TIME 21 8207
TEST_TIME 22 8490
TEST_TIME 23 8294
TEST_TIME 24 8692
TEST_TIME 25 8881
TEST_TIME 26 10298
TEST_TIME 27 10971
TEST_TIME 28 11709
TEST_TIME 29 11398
TEST_TIME 30 11342
TEST_TIME 31 11064
TEST_TIME 32 10865
TEST_TIME 33 10837
TEST_TIME 34 10827
TEST_TIME 35 10723
TEST_TIME 36 10570
TEST_TIME 37 10458
TEST_TIME 38 10222
TEST_TIME 39 10247
TEST_TIME 40 10039
TEST_TIME 41 9867
TEST_TIME 42 9711
TEST_TIME 43 9702
TEST_TIME 44 9713
TEST_TIME 45 9598
TEST_TIME 46 9432
TEST_TIME 47 9348
TEST_TIME 48 9426
TEST_TIME 49 9592
TEST_TIME 50 9588
TEST_TIME 51 9492
TEST_TIME 52 9435
TEST_TIME 53 9282
TEST_TIME 54 9309
TEST_TIME 55 9244
TEST_TIME 56 9138
TEST_TIME 57 9116
TEST_TIME 58 9211
TEST_TIME 59 9203
TEST_TIME 60 9252
TEST_TIME 61 9211
TEST_TIME 62 9223
TEST_TIME 63 9347
TEST_TIME 64 9433
TEST_TIME 65 9369
TEST_TIME 66 9439
TEST_TIME 67 9516
TEST_TIME 68 9516
TEST_TIME 69 9494
TEST_TIME 70 9552
TEST_TIME 71 9556
TEST_TIME 72 9442
TEST_TIME 73 9387
TEST_TIME 74 9324
TEST_TIME 75 9332
TEST_TIME 76 9302
TEST_TIME 77 9247
TEST_TIME 78 9222
TEST_TIME 79 9198
TEST_TIME 80 9134
TEST_TIME 81 9200
TEST_TIME 82 9184
TEST_TIME 83 9247
TEST_TIME 84 9226
TEST_TIME 85 9269
TEST_TIME 86 9270
TEST_TIME 87 9327
TEST_TIME 88 9284
TEST_TIME 89 9307
TEST_TIME 90 9322
TEST_TIME 91 9336
TEST_TIME 92 9390
TEST_TIME 93 9407
TEST_TIME 94 9387
TEST_TIME 95 9376
TEST_TIME 96 9313
TEST_TIME 97 9281
TEST_TIME 98 9338
TEST_TIME 99 9315
TEST_TIME 100 9328
TEST_TIME 101 9267
TEST_TIME 102 9287
TEST_TIME 103 9213
TEST_TIME 104 9163
TEST_TIME 105 9172
TEST_TIME 106 9293
TEST_TIME 107 9269
TEST_TIME 108 9276
TEST_TIME 109 9350
TEST_TIME 110 9348
TEST_TIME 111 9308
TEST_TIME 112 9330
TEST_TIME 113 9342
TEST_TIME 114 9409
TEST_TIME 115 9379
TEST_TIME 116 9422
TEST_TIME 117 9381
TEST_TIME 118 9347
TEST_TIME 119 9298
TEST_TIME 120 9247

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-05-2022 16:40:40 to 29-05-2022 16:40:43.

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 1528023 1528023 0 0.00 0 25485 6 0 0
doc_list_biud 1528023 1528023 0 0.00 0 60010 31 0 0
sp_add_invoice_to_stock 4928 4816 112 2.27 28 209038 19208 0 112
sp_cancel_adding_invoice 5847 5847 0 0.00 5 67778 769 0 0
sp_cancel_client_order 1683 1683 0 0.00 4 8979 335 0 0
sp_cancel_customer_reserve 573983 573983 0 0.00 1 60011 67 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 5023 81 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 2013 58 0 0
sp_cancel_supplier_invoice 6518 6512 6 0.09 2 25560 871 0 6
sp_cancel_supplier_order 428 428 0 0.00 7 1182629 113726 0 0
sp_cancel_write_off 9849 9849 0 0.00 1 15819 120 0 0
sp_client_order 20375 20375 0 0.00 3 59471 374 0 0
sp_customer_reserve 450315 450315 0 0.00 0 94863 188 0 0
sp_fill_shopping_cart 31159 31159 0 0.00 1 27846 38 0 0
sp_fill_shopping_cart_clo_res 450313 450313 0 0.00 0 16278 15 0 0
sp_get_clo_for_invoice 4814 4814 0 0.00 0 35893 345 0 0
sp_kill_cost_storno 16805 16805 0 0.00 0 8530 59 0 0
sp_kill_qstorno_ret_qs2qd 1164755 1164755 0 0.00 0 60008 36 0 0
sp_kill_qty_storno 612837 612837 0 0.00 0 60009 77 0 0
sp_lock_selected_doc 20194 20194 0 0.00 0 7684 4 0 0
sp_make_cost_storno 27323 27323 0 0.00 0 12574 54 0 0
sp_multiply_rows_for_pdistr 27323 27323 0 0.00 0 7764 29 0 0
sp_multiply_rows_for_qdistr 20375 20375 0 0.00 0 59462 160 0 0
sp_pay_from_customer 7017 7017 0 0.00 4 20427 122 0 0
sp_pay_to_supplier 5771 5771 0 0.00 5 12496 138 0 0
sp_payment_common 12788 12788 0 0.00 1 20414 38 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 8939 307 0 0
sp_qd_handle_on_invoice_upd_sts 10661 10661 0 0.00 0 30373 358 0 0
sp_qd_handle_on_reserve_upd_sts 9721 9721 0 0.00 0 13420 51 0 0
sp_reserve_write_off 9721 9721 0 0.00 6 13522 162 0 0
sp_supplier_invoice 5395 5395 0 0.00 7 99054 3712 0 0
sp_supplier_order 5410 5410 0 0.00 5 35161 1318 0 0
srv_aggregate_perf_data 100 100 0 0.00 2341 144068 19473 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1171 123386 48980 0 0
srv_find_qd_qs_mism 1063490 1063490 0 0.00 0 25484 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 36 59159 1162 0 0
srv_make_money_saldo 745 745 0 0.00 1 2340 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 172443 516583 287153 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4817 4817 0 0.00 0 16695 166 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 16680 129 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 2667 12 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 5128 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 4564 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1641 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 511 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 21096 1688 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 3116 14 0 0
t$perf-norm:sp_client_order 20376 20376 0 0.00 0 14852 20 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 5104 17 0 0
t$perf-norm:sp_pay_from_customer 7017 7017 0 0.00 0 9746 11 0 0
t$perf-norm:sp_pay_to_supplier 5772 5772 0 0.00 0 15440 16 0 0
t$perf-norm:sp_reserve_write_off 9721 9721 0 0.00 0 17699 13 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 6597 22 0 0
t$perf-norm:sp_supplier_order 5410 5410 0 0.00 0 8060 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 451 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 244 4 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 12920 52 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 4881 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9852 9628 224 2.27 0 7639 16 0 224
v_all_customers 37328 37328 0 0.00 0 28 1 0 0
v_all_suppliers 30392 30392 0 0.00 0 27 1 0 0
v_all_wares 40750 40750 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 830 6 0 0
v_cancel_client_order 3366 3366 0 0.00 0 6588 6 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 5012 14 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 6575 12 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 605 5 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 503 13 0 12
v_cancel_supplier_order 856 856 0 0.00 0 2406 7 0 0
v_cancel_write_off 1982 1982 0 0.00 0 4803 17 0 0
v_max_id_clo_ord 5409 5409 0 0.00 0 3202 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 3161 16 0 0
v_max_id_ord_sup 5375 5375 0 0.00 0 3419 2 0 0
v_max_non_paid_invoice 1359 1359 0 0.00 0 697 4 0 0
v_max_non_paid_realizn 3672 3672 0 0.00 0 5531 4 0 0
v_min_id_clo_ord 5409 5409 0 0.00 0 4709 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 7980 17 0 0
v_min_id_ord_sup 5375 5375 0 0.00 0 5521 6 0 0
v_min_non_paid_invoice 5771 5771 0 0.00 0 11694 28 0 0
v_min_non_paid_realizn 7017 7017 0 0.00 0 20408 31 0 0
v_our_firm 10112 10112 0 0.00 0 18 1 0 0
v_reserve_write_off 19442 19442 0 0.00 0 6447 6 0 0
x_lock_depdocs_on_canc_invoice 11969 11969 0 0.00 0 15592 132 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 22372 413 0 0
x_make_qstorno_1000_1200 5410 5410 0 0.00 1 35152 1239 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 3 98685 3573 0 0
x_make_qstorno_2100_3300 450315 450315 0 0.00 0 94861 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-05-2022 16:40:44 to 29-05-2022 16:40:48.

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:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:24 29949 8192000000 9902284816 10210287616 979349984 10.678 100 93 186 0 1009507136 6010384 24146768
14:10:57 11046 8192000000 9928465040 10249920512 1017773104 11.051 100 87 174 0 1048356464 8119632 22463728
14:12:17 15468 8192000000 9965492176 10294607872 1050172784 11.362 100 84 168 0 1080682656 9115488 21394384
14:13:40 15412 8192000000 10032261072 10376163328 1111960288 11.951 100 80 160 0 1141075536 9942480 19172768
14:15:00 7848 8192000000 10072600912 10431111168 1160141120 12.405 100 74 148 0 1188739216 11369104 17228992
14:16:10 6034 8192000000 10131630016 10498564096 1214552608 12.911 100 82 162 0 1245423248 13224352 17646288
14:17:17 4025 8192000000 10165008880 10540773376 1250463936 13.242 100 83 166 1 1281640800 13259888 17916976
14:18:23 2667 8192000000 10197633152 10577227776 1279573104 13.509 100 88 176 0 1313496768 14613008 19310656
14:19:28 2241 8192000000 10218230448 10604859392 1298347648 13.680 100 88 175 1 1331459760 13630848 19481264
14:20:31 6120 8192000000 10214633248 10605035520 1302306464 13.716 100 79 157 2 1328933184 9131184 17495536
14:21:39 5269 8192000000 10200308512 10610208768 1311235008 13.797 100 26 52 1 1323977984 7324256 5418720
14:22:45 8937 8192000000 10206270672 10618875904 1319394192 13.871 100 30 60 0 1332356848 5816016 7146640
14:23:57 57936 8192000000 10213533632 10636591104 1327356256 13.943 100 33 65 3 1340042512 5279728 7406528
14:25:57 5692 8192000000 10227865600 10636251136 1322105216 13.896 99 33 67 0 1340384096 10447712 7831168
14:27:06 9873 8192000000 10227267456 10649395200 1327485376 13.944 99 18 36 1 1337160384 5363840 4311168
14:28:17 35626 8192000000 10214895648 10624675840 1287705200 13.583 97 25 51 0 1299138736 5554848 5878688
14:29:53 7630 8192000000 10146047360 10552270848 1199771904 12.774 94 18 36 0 1209433632 5580336 4081392
14:31:03 6095 8192000000 10170736032 10575118336 1239882832 13.145 97 36 72 1 1262246304 13794016 8569456
14:32:11 6287 8192000000 10147195152 10544500736 1250135888 13.239 100 23 46 0 1260858192 5253040 5469264
14:33:20 56985 8192000000 10156529024 10560749568 1252287888 13.259 99 25 50 0 1263650464 5660432 5702144
14:35:18 5109 8192000000 10190190880 10591842304 1285827488 13.566 100 33 67 0 1300461696 7228960 7405248
14:36:24 10532 8192000000 10188091072 10601168896 1284239360 13.552 99 28 56 0 1296572080 5326864 7005856
14:37:39 114386 8192000000 10210499408 10628218880 1306481536 13.754 100 33 67 0 1320174880 5831648 7861696
14:40:37 9854 8192000000 10257343360 10667696128 1347207648 14.122 100 55 110 1 1369490912 9134976 13148288
14:41:52 16307 8192000000 10279737408 10694471680 1367100736 14.301 100 52 105 1 1391332656 11628208 12603712
14:43:18 16294 8192000000 10278305072 10700066816 1370242752 14.329 100 39 75 4 1391562960 11927712 9392496
14:44:41 9757 8192000000 10294592848 10713915392 1382088336 14.435 100 42 84 0 1406734432 14233120 10412976
14:45:52 27296 8192000000 10304203728 10736926720 1393137808 14.534 100 38 76 3 1421028640 18221536 9669296
14:47:33 5014 8192000000 10332951104 10745896960 1399533952 14.591 100 72 144 4 1441478656 25104448 16840256
14:48:43 7059 8192000000 10338991760 10765377536 1412153136 14.703 100 49 97 2 1452685664 27710064 12822464
14:49:54 2620 8192000000 10343985904 10764189696 1410761248 14.691 100 60 120 3 1452225808 27773728 13690832
14:51:01 3595 8192000000 10348755008 10753564672 1368883456 14.317 97 88 173 4 1428650112 38370112 21396544
14:52:10 2278 8192000000 10366549568 10776023040 1410966464 14.693 100 79 158 0 1467796912 37620272 19210176
14:53:18 2278 8192000000 10374901360 10788061184 1409096000 14.676 99 75 148 2 1473825568 46225632 18503936
14:54:24 3825 8192000000 10388207168 10796945408 1421447968 14.786 100 83 164 3 1489742592 47046400 21248224
14:55:34 4122 8192000000 10399999856 10811060224 1420042544 14.773 100 75 150 0 1497215872 56885936 20287392
14:56:46 1170 8192000000 10401866800 10816315392 1428126064 14.845 100 86 173 0 1507650160 56956688 22567408
14:57:51 3760 8192000000 10369996912 10792456192 1408489904 14.671 99 43 86 0 1475220304 55129488 11600912
14:59:02 3329 8192000000 10397430032 10809819136 1417790944 14.753 100 75 150 0 1499880560 62762704 19326912
15:00:15 8384 8192000000 10394500592 10817961984 1419090704 14.765 100 48 94 0 1498327264 66303216 12933344
15:01:33 11624 8192000000 10391660496 10820112384 1403461008 14.626 99 47 95 1 1486760480 69653104 13646368
15:02:50 16678 8192000000 10367800448 10799996928 1390162800 14.507 100 37 74 0 1474836784 73746560 10927424
15:04:34 24744 8192000000 10326565344 10776653824 1378522960 14.403 100 18 36 0 1451732912 67485136 5724816
15:06:01 48225 8192000000 10280800240 10722603008 1328325072 13.952 95 17 34 0 1349562176 16887104 4350000
15:07:54 43187 8192000000 10288650784 10739904512 1364825840 14.281 99 28 54 3 1384394224 13285728 6282656
15:10:05 108497 8192000000 10304525184 10762104832 1381726368 14.432 99 24 48 0 1401211488 13765904 5719216
15:12:55 49586 8192000000 10280173872 10728345600 1347529536 14.125 97 19 38 0 1360615104 9024496 4061072
15:14:50 115600 8192000000 10288802928 10728472576 1344335728 14.096 97 28 56 1 1358402160 7278032 6788400
15:17:49 62470 8192000000 10264478704 10727092224 1344108256 14.094 97 5 10 0 1354845120 9720144 1016720
15:19:54 21580 8192000000 10293478320 10731630592 1377302832 14.392 100 64 126 1 1401820224 9711072 14806320
15:21:23 36626 8192000000 10298579712 10754768896 1385655744 14.467 100 31 60 0 1409982176 16869744 7456688
15:23:11 74409 8192000000 10317722784 10775498752 1404034304 14.631 100 10 20 0 1425159440 18608656 2516480
15:25:26 68969 8192000000 10313066368 10775404544 1378093344 14.399 98 23 46 0 1410278736 26532288 5653104
15:27:36 83855 8192000000 10291902208 10753871872 1375168816 14.373 99 18 35 1 1396567024 17233264 4164944
15:30:00 85707 8192000000 10291705504 10760650752 1384854288 14.460 99 10 20 0 1403005744 15664688 2486768
15:32:53 89992 8192000000 10288265616 10753429504 1394811632 14.549 100 15 30 0 1407829360 9787216 3230512
15:35:32 75641 8192000000 10293887248 10767933440 1377883664 14.398 99 15 30 1 1405095200 23920512 3291024
15:37:49 50202 8192000000 10284750368 10742276096 1350763520 14.154 97 13 26 1 1366841792 12990880 3087392
15:39:41 26752 8192000000 10301910608 10757632000 1395510032 14.555 100 39 78 0 1415459488 11176064 8773392
15:41:08 62293 8192000000 10279694240 10738216960 1351016240 14.157 97 20 40 0 1366663952 11082816 4564896
15:43:12 28082 8192000000 10282262624 10717884416 1350421888 14.151 98 42 84 0 1367196288 7069776 9704624
15:44:43 15953 8192000000 10271855632 10712698880 1325299984 13.925 96 27 54 1 1339975904 8919024 5756896
15:45:59 77749 8192000000 10270329552 10712870912 1307881184 13.767 96 15 30 0 1320903680 9922992 3099504
15:48:17 65592 8192000000 10272986560 10717974528 1323957008 13.913 97 31 62 0 1342952048 12333152 6661888
15:50:28 123386 8192000000 10228334768 10671071232 1273157616 13.450 94 23 46 0 1286467760 7974000 5336144
15:53:32 52087 8192000000 10228808064 10690789376 1335828336 14.020 100 13 26 0 1348381920 9824832 2728752
15:55:24 93396 8192000000 10244654544 10700193792 1314535872 13.827 97 11 21 1 1326782240 9518960 2727408
15:57:58 60012 8192000000 10264832704 10726412288 1360038160 14.238 100 9 18 0 1373123440 11137744 1947536
15:59:58 108338 8192000000 10245057056 10704273408 1293384032 13.635 95 17 34 1 1309888624 12336336 4168256
16:02:52 60128 8192000000 10261089616 10718908416 1338922592 14.048 98 18 36 0 1352416816 9250464 4243760
16:04:52 72099 8192000000 10260940048 10721480704 1360746224 14.244 100 21 42 0 1375093344 10038752 4308368
16:07:04 18761 8192000000 10292358064 10725187584 1366398496 14.295 100 37 74 1 1384995776 10018528 8578752
16:08:24 72163 8192000000 10290679744 10745430016 1370081440 14.328 99 17 34 1 1384796240 11209984 3504816
16:10:42 71478 8192000000 10298937824 10749730816 1371757456 14.343 99 15 29 1 1392393312 17490192 3145664
16:13:02 121179 8192000000 10293292576 10745696256 1360838320 14.245 98 25 49 2 1378879056 12326608 5714128
16:16:08 35544 8192000000 10304158320 10764197888 1391794608 14.522 100 26 52 0 1412205552 14608384 5802560
16:17:47 54654 8192000000 10279574176 10749378560 1369125360 14.319 98 13 26 0 1383132272 11054736 2952176
16:19:42 57971 8192000000 10305188448 10759303168 1381171808 14.427 99 16 32 0 1393101776 7818976 4110992
16:21:47 93412 8192000000 10290835904 10747621376 1365495616 14.287 97 13 26 0 1383959376 15027808 3435952
16:24:43 49995 8192000000 10301112064 10757783552 1378177600 14.400 99 15 30 1 1391898304 10372048 3348656
16:26:34 80688 8192000000 10279470960 10741141504 1345822336 14.110 97 6 12 0 1362460304 15398688 1239280
16:28:54 41898 8192000000 10286965680 10723667968 1345889952 14.110 98 23 46 1 1360288992 8749408 5649632
16:30:39 57606 8192000000 10282283888 10728091648 1350202272 14.149 98 29 57 1 1368584000 12311552 6070176
16:32:39 93491 8192000000 10277618176 10736484352 1330466928 13.971 97 17 34 1 1350168480 16165744 3535808
16:35:13 106597 8192000000 10265691680 10709581824 1342609232 14.081 99 35 70 2 1360453072 10554016 7289824
16:38:05 24416 8192000000 9599627744 9949466624 742057968 8.305 55 3 6 0 748739552 6534496 147088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-05-2022 16:40:50 to 29-05-2022 16:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-05-2022 16:41:06 to 29-05-2022 16:41:08.

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          237135
MON$OLDEST_ACTIVE               237585
MON$OLDEST_SNAPSHOT             237585
MON$NEXT_TRANSACTION            237585
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                       2019136
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 ---
:106: 
:107: mscs-test-161.budzdorov.ru    Sun May 29 16:38:34 2022
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:109: 
--- end of diff output ---

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

29.05.22 16:41:09. 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-29 16:41:11 No severe PSQL-related problems occured
29.05.22 16:41:12. End of report.