Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.04.2022 22: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 16557160 kB
MemAvailable 31572624 kB
Buffers 0 kB
Cached 15187620 kB
SwapCached 3184 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-04-30 22:38:12
DTS_END                         2022-04-30 22:38:12.6750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46166
TRN_ID                          240213
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: 9341.08 21419 1120929
customer order: creation 174.78 314 20974
customer order: refuse 15.29 362 1835
order to supplier: creation 46.38 1293 5565
order to supplier: removal 3.54 98550 425
invoice (draft): creation 46.77 3560 5612
invoice (draft): removal 52.98 863 6358
invoice accept: apply 42.54 18292 5105
invoice accept: cancel 47.75 845 5730
customer reserve: creation 3890.99 182 466919
customer reserve: removal 4719.02 57 566283
realization accept: apply 84.48 181 10137
realization accept: cancel 82.32 108 9878
payment from customer: creation 62.33 119 7480
payment from customer: removal 5.93 83 711
payment to supplier: creation 49.81 124 5977
payment to supplier: removal 3.78 76 454
service: total inventory turnovers 4.61 1159 553
service: total monetary turnovers 6.84 36 821
service: aggregate perf. data 0.90 19542 108
service: refresh index statistics 0.03 282625 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-04-2022 22:39:50 to 30-04-2022 22:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2601
TEST_TIME 2 3557
TEST_TIME 3 3943
TEST_TIME 4 3966
TEST_TIME 5 4382
TEST_TIME 6 4187
TEST_TIME 7 4429
TEST_TIME 8 4742
TEST_TIME 9 5269
TEST_TIME 10 6081
TEST_TIME 11 6487
TEST_TIME 12 6767
TEST_TIME 13 6984
TEST_TIME 14 7571
TEST_TIME 15 7517
TEST_TIME 16 7625
TEST_TIME 17 7760
TEST_TIME 18 8143
TEST_TIME 19 8180
TEST_TIME 20 8962
TEST_TIME 21 8913
TEST_TIME 22 8904
TEST_TIME 23 9165
TEST_TIME 24 9070
TEST_TIME 25 9600
TEST_TIME 26 10109
TEST_TIME 27 10012
TEST_TIME 28 10570
TEST_TIME 29 10775
TEST_TIME 30 10832
TEST_TIME 31 10766
TEST_TIME 32 11058
TEST_TIME 33 10869
TEST_TIME 34 10730
TEST_TIME 35 10503
TEST_TIME 36 10570
TEST_TIME 37 10564
TEST_TIME 38 10442
TEST_TIME 39 10348
TEST_TIME 40 10217
TEST_TIME 41 10470
TEST_TIME 42 10348
TEST_TIME 43 10228
TEST_TIME 44 10221
TEST_TIME 45 10114
TEST_TIME 46 9994
TEST_TIME 47 9884
TEST_TIME 48 9845
TEST_TIME 49 9765
TEST_TIME 50 9674
TEST_TIME 51 9715
TEST_TIME 52 9709
TEST_TIME 53 9686
TEST_TIME 54 9640
TEST_TIME 55 9667
TEST_TIME 56 9702
TEST_TIME 57 9651
TEST_TIME 58 9738
TEST_TIME 59 9768
TEST_TIME 60 9753
TEST_TIME 61 9800
TEST_TIME 62 9787
TEST_TIME 63 9798
TEST_TIME 64 9830
TEST_TIME 65 9880
TEST_TIME 66 9792
TEST_TIME 67 9825
TEST_TIME 68 9756
TEST_TIME 69 9720
TEST_TIME 70 9633
TEST_TIME 71 9680
TEST_TIME 72 9619
TEST_TIME 73 9640
TEST_TIME 74 9603
TEST_TIME 75 9544
TEST_TIME 76 9456
TEST_TIME 77 9490
TEST_TIME 78 9451
TEST_TIME 79 9404
TEST_TIME 80 9387
TEST_TIME 81 9355
TEST_TIME 82 9359
TEST_TIME 83 9313
TEST_TIME 84 9407
TEST_TIME 85 9377
TEST_TIME 86 9383
TEST_TIME 87 9454
TEST_TIME 88 9453
TEST_TIME 89 9451
TEST_TIME 90 9504
TEST_TIME 91 9516
TEST_TIME 92 9556
TEST_TIME 93 9535
TEST_TIME 94 9607
TEST_TIME 95 9594
TEST_TIME 96 9507
TEST_TIME 97 9494
TEST_TIME 98 9431
TEST_TIME 99 9413
TEST_TIME 100 9377
TEST_TIME 101 9340
TEST_TIME 102 9348
TEST_TIME 103 9384
TEST_TIME 104 9361
TEST_TIME 105 9363
TEST_TIME 106 9361
TEST_TIME 107 9367
TEST_TIME 108 9384
TEST_TIME 109 9390
TEST_TIME 110 9405
TEST_TIME 111 9405
TEST_TIME 112 9438
TEST_TIME 113 9545
TEST_TIME 114 9536
TEST_TIME 115 9545
TEST_TIME 116 9525
TEST_TIME 117 9456
TEST_TIME 118 9435
TEST_TIME 119 9407
TEST_TIME 120 9341

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-04-2022 22:39:54 to 30-04-2022 22:39:57.

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 1554481 1554481 0 0.00 0 38197 6 0 0
doc_list_biud 1554480 1554480 0 0.00 0 93488 27 0 0
sp_add_invoice_to_stock 5239 5105 134 2.55 30 149526 18293 0 134
sp_cancel_adding_invoice 5730 5730 0 0.00 5 78299 845 0 0
sp_cancel_client_order 1835 1835 0 0.00 3 17945 363 0 0
sp_cancel_customer_reserve 566283 566283 0 0.00 1 31306 57 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 4620 83 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 1764 76 0 0
sp_cancel_supplier_invoice 6370 6358 12 0.18 1 93501 864 0 12
sp_cancel_supplier_order 425 425 0 0.00 9 1077432 98550 0 0
sp_cancel_write_off 9878 9878 0 0.00 1 9510 108 0 0
sp_client_order 20974 20974 0 0.00 3 26280 314 0 0
sp_customer_reserve 466919 466919 0 0.00 0 57819 183 0 0
sp_fill_shopping_cart 32138 32138 0 0.00 1 24238 41 0 0
sp_fill_shopping_cart_clo_res 466914 466914 0 0.00 0 26283 13 0 0
sp_get_clo_for_invoice 5100 5100 0 0.00 0 38679 315 0 0
sp_kill_cost_storno 16773 16773 0 0.00 0 7957 55 0 0
sp_kill_qstorno_ret_qs2qd 1149227 1149227 0 0.00 0 93482 31 0 0
sp_kill_qty_storno 605746 605746 0 0.00 0 93485 69 0 0
sp_lock_selected_doc 21228 21228 0 0.00 0 9923 6 0 0
sp_make_cost_storno 28694 28694 0 0.00 0 38190 54 0 0
sp_multiply_rows_for_pdistr 28693 28693 0 0.00 0 7237 30 0 0
sp_multiply_rows_for_qdistr 20974 20974 0 0.00 0 10442 138 0 0
sp_pay_from_customer 7480 7480 0 0.00 4 21719 120 0 0
sp_pay_to_supplier 5977 5977 0 0.00 5 38678 125 0 0
sp_payment_common 13456 13456 0 0.00 1 21709 34 0 0
sp_qd_handle_on_cancel_clo 1835 1835 0 0.00 0 17936 331 0 0
sp_qd_handle_on_invoice_upd_sts 10830 10830 0 0.00 0 35535 355 0 0
sp_qd_handle_on_reserve_upd_sts 10137 10137 0 0.00 0 24275 61 0 0
sp_reserve_write_off 10137 10137 0 0.00 6 24292 182 0 0
sp_supplier_invoice 5612 5612 0 0.00 6 113811 3560 0 0
sp_supplier_order 5565 5565 0 0.00 5 94315 1293 0 0
srv_aggregate_perf_data 108 108 0 0.00 2380 163130 19542 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1875 144162 42663 0 0
srv_find_qd_qs_mism 1072810 1072810 0 0.00 0 14641 6 0 0
srv_make_invnt_saldo 553 553 0 0.00 50 34611 1160 0 0
srv_make_money_saldo 821 821 0 0.00 1 4737 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 126663 612061 282625 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5105 5105 0 0.00 0 8407 159 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 11083 100 0 0
t$perf-norm:sp_cancel_client_order 1835 1835 0 0.00 0 2278 11 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2027 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 2009 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 2784 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 7856 51 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23893 1759 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 2220 8 0 0
t$perf-norm:sp_client_order 20974 20974 0 0.00 0 5263 15 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 5929 10 0 0
t$perf-norm:sp_pay_from_customer 7480 7480 0 0.00 0 8287 11 0 0
t$perf-norm:sp_pay_to_supplier 5977 5977 0 0.00 0 2690 7 0 0
t$perf-norm:sp_reserve_write_off 10137 10137 0 0.00 0 9405 9 0 0
t$perf-norm:sp_supplier_invoice 5612 5612 0 0.00 0 5380 17 0 0
t$perf-norm:sp_supplier_order 5565 5565 0 0.00 0 6018 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 263 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 9 0 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 2649 32 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 744 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 9 5 0 0
v_add_invoice_to_stock 10468 10200 268 2.56 0 6643 19 0 268
v_all_customers 38700 38700 0 0.00 0 24 1 0 0
v_all_suppliers 30724 30724 0 0.00 0 16 1 0 0
v_all_wares 41948 41948 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 4105 17 0 0
v_cancel_client_order 3670 3670 0 0.00 0 3544 5 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 4611 10 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 4453 6 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 696 5 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 790 15 0 24
v_cancel_supplier_order 850 850 0 0.00 0 361 4 0 0
v_cancel_write_off 2120 2120 0 0.00 0 7804 15 0 0
v_max_id_clo_ord 5563 5563 0 0.00 0 3559 3 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 1428 14 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 6296 7 0 0
v_max_non_paid_invoice 1778 1778 0 0.00 0 896 3 0 0
v_max_non_paid_realizn 3832 3832 0 0.00 0 3215 5 0 0
v_min_id_clo_ord 5563 5563 0 0.00 0 3165 7 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 14138 37 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 6405 9 0 0
v_min_non_paid_invoice 5976 5976 0 0.00 0 9692 19 0 0
v_min_non_paid_realizn 7480 7480 0 0.00 0 21703 29 0 0
v_our_firm 10544 10544 0 0.00 0 15 1 0 0
v_reserve_write_off 20274 20274 0 0.00 0 3847 6 0 0
x_lock_depdocs_on_canc_invoice 11696 11696 0 0.00 0 14985 110 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 13066 418 0 0
x_make_qstorno_1000_1200 5565 5565 0 0.00 2 94302 1206 0 0
x_make_qstorno_1200_2000 5612 5612 0 0.00 2 110167 3410 0 0
x_make_qstorno_2100_3300 466919 466919 0 0.00 0 57817 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-04-2022 22:39:58 to 30-04-2022 22:40:02.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:17 18120 8192000000 9906698368 10218774528 993826224 10.819 100 85 170 0 1021826320 6117328 21882768
20:10:43 24247 8192000000 9952866864 10277249024 1034383360 11.211 100 83 166 0 1064002208 8264608 21354240
20:12:12 20269 8192000000 9990495840 10323546112 1073736400 11.588 100 88 176 0 1105074736 10068080 21270256
20:13:36 13535 8192000000 10032773280 10380652544 1123234896 12.058 100 83 166 2 1154134240 11166384 19732960
20:14:58 12117 8192000000 10074996416 10434572288 1156388112 12.369 100 76 150 1 1186050880 11894080 17768688
20:16:19 4674 8192000000 10123229040 10488451072 1210098192 12.870 100 74 148 1 1239472656 12958816 16415648
20:17:25 4178 8192000000 10144629488 10524160000 1239209824 13.139 100 72 142 1 1267390400 12829056 15351520
20:18:31 2974 8192000000 10190913888 10571079680 1280190640 13.515 100 84 168 0 1311915888 13219664 18505584
20:19:36 1546 8192000000 10209113856 10592505856 1294849008 13.648 100 88 177 1 1328633360 13649248 20135104
20:20:39 15387 8192000000 10200656368 10604408832 1304534480 13.736 100 62 126 1 1329130048 10988528 13607040
20:21:59 4704 8192000000 10203449280 10610298880 1311927440 13.804 100 37 71 1 1326138112 5029264 9181408
20:23:04 58087 8192000000 10221002528 10634358784 1317392304 13.853 100 47 94 2 1337917776 9162432 11363040
20:25:05 6352 8192000000 10222643792 10639921152 1328016320 13.949 100 28 56 0 1340336224 5798224 6521680
20:26:12 7820 8192000000 10225402464 10647228416 1335544240 14.017 100 23 45 3 1345640192 5414208 4681744
20:27:26 14197 8192000000 10231874144 10650845184 1311943088 13.804 98 24 48 4 1327214992 10352864 4919040
20:28:42 16123 8192000000 10211839280 10633097216 1305476032 13.745 98 26 52 2 1318134400 6392288 6266080
20:29:59 6362 8192000000 10172117264 10576781312 1252472192 13.261 97 22 44 1 1263036256 5968080 4595984
20:31:06 6056 8192000000 10140869360 10540646400 1241078096 13.156 99 20 40 0 1251657664 6055008 4524560
20:32:16 13925 8192000000 10154993648 10559938560 1252771008 13.264 99 15 30 0 1261734752 5653456 3310288
20:33:33 36770 8192000000 10161490592 10569285632 1251256640 13.250 98 24 48 0 1262699680 5523824 5919216
20:35:10 7112 8192000000 10177443648 10588663808 1287779696 13.584 100 35 70 2 1302314960 6236384 8298880
20:36:20 11377 8192000000 10190922560 10598215680 1293118624 13.633 100 29 58 0 1305619312 5138896 7361792
20:37:34 14792 8192000000 10202414160 10618474496 1307665040 13.765 100 28 55 2 1320134976 5048128 7421808
20:38:54 56612 8192000000 10231672352 10645979136 1331021616 13.976 100 36 70 0 1346884464 8392112 7470736
20:41:06 12568 8192000000 10263222208 10681745408 1353711888 14.181 100 45 90 0 1378706720 14676496 10318336
20:42:28 9449 8192000000 10295000128 10710630400 1377498240 14.394 100 51 102 2 1406793296 18051776 11243280
20:43:39 8407 8192000000 10295581232 10720485376 1386152336 14.472 100 46 85 1 1414714864 18043488 10519040
20:44:55 17552 8192000000 10316798592 10737000448 1394878960 14.549 100 54 107 3 1429623632 21668400 13076272
20:46:18 13465 8192000000 10333495104 10751553536 1406222048 14.650 100 58 116 1 1444863392 24804208 13837136
20:47:36 3771 8192000000 10346396256 10762330112 1410494144 14.688 100 83 164 2 1455697040 26634384 18568512
20:48:45 2145 8192000000 10355802112 10774564864 1418019968 14.755 100 77 152 5 1463948784 28027424 17901392
20:49:53 5026 8192000000 10354706464 10768084992 1414083088 14.720 100 84 167 0 1463279680 28784768 20411824
20:51:04 3542 8192000000 10358099392 10776174592 1415369568 14.732 100 74 149 1 1464656512 30590176 18696768
20:52:12 3709 8192000000 10348608560 10764005376 1392932176 14.532 99 60 119 1 1444152960 36060400 15160384
20:53:19 3199 8192000000 10343228640 10758438912 1395435072 14.554 100 77 154 2 1448865168 33831008 19599088
20:54:27 4994 8192000000 10366310096 10782646272 1406224368 14.650 100 77 152 2 1463380960 37113840 20042752
20:55:41 5009 8192000000 10335727648 10750332928 1378810256 14.406 100 74 148 0 1441413040 43165600 19437184
20:56:54 1875 8192000000 10376335328 10785947648 1403911136 14.630 100 83 164 2 1472318816 47490544 20917136
20:58:03 7297 8192000000 10377406656 10805366784 1415367600 14.732 100 57 114 1 1481587552 51631232 14588720
20:59:23 5572 8192000000 10332939264 10760695808 1387861888 14.487 99 39 77 3 1427131648 28719968 10549792
21:00:33 13364 8192000000 10334422720 10767441920 1390133040 14.507 99 41 80 1 1436685504 35218352 11334112
21:02:00 9823 8192000000 10318727088 10749046784 1374022432 14.363 100 28 56 1 1421941936 40117008 7802496
21:03:13 6865 8192000000 10319049488 10737516544 1351156288 14.158 99 53 106 0 1418128800 52272000 14700512
21:04:22 28913 8192000000 10290530768 10736189440 1356241680 14.204 99 17 34 1 1395350976 35021744 4087552
21:05:59 36574 8192000000 10282234656 10730569728 1336101744 14.022 99 12 24 1 1383867168 44124400 3641024
21:07:39 86470 8192000000 10296358848 10735255552 1346772448 14.118 98 18 36 0 1371458080 20827056 3858576
21:10:07 90727 8192000000 10273391296 10715897856 1332038960 13.986 97 20 40 0 1351557392 14556992 4961440
21:12:40 71004 8192000000 10297169520 10741399552 1369276736 14.321 99 35 70 1 1386985312 9171040 8537536
21:15:07 49819 8192000000 10306213600 10776956928 1389350128 14.500 100 19 38 0 1416878112 23466896 4061088
21:16:58 76900 8192000000 10317741136 10766807040 1384019152 14.452 99 25 49 1 1412310528 23062624 5228752
21:19:17 61335 8192000000 10306676080 10770612224 1400350704 14.598 100 30 60 1 1418551344 10971552 7229088
21:21:20 34467 8192000000 10309453056 10771922944 1411132048 14.694 100 19 36 0 1422933520 7792688 4008784
21:24:04 31392 8192000000 10296476928 10753355776 1396880784 14.567 100 18 35 0 1407761280 6414896 4465600
21:25:35 64947 8192000000 10314725088 10780581888 1407225968 14.659 100 26 52 2 1421025648 7769024 6030656
21:27:45 22642 8192000000 10311615648 10781814784 1410644416 14.690 100 23 46 1 1430445760 14166800 5634544
21:29:08 51212 8192000000 10322725296 10794643456 1412927408 14.710 100 22 45 0 1433800528 15483248 5389872
21:31:29 73782 8192000000 10299194512 10763980800 1358449936 14.223 97 15 30 1 1370396544 8815056 3131552
21:34:00 55129 8192000000 10302613712 10764169216 1396056320 14.560 100 27 54 0 1413517936 11364144 6097472
21:36:11 27169 8192000000 10267630464 10724827136 1337952160 14.039 98 25 50 0 1353598464 10150784 5495520
21:37:44 36957 8192000000 10286119488 10734268416 1355276848 14.195 98 27 54 0 1369652720 8828912 5546960
21:40:16 38555 8192000000 10266641296 10713026560 1299065360 13.687 94 12 24 1 1308303952 6996576 2242016
21:41:55 60822 8192000000 10292545360 10729058304 1354463856 14.188 99 18 36 0 1369206160 10734048 4008256
21:43:58 41532 8192000000 10298303808 10733277184 1361043456 14.247 99 38 76 0 1384048464 13827360 9177648
21:45:43 101878 8192000000 10275903712 10739998720 1349685104 14.145 98 23 45 1 1363341184 8777168 4878912
21:49:44 55766 8192000000 10238232416 10698891264 1299628032 13.692 96 9 18 0 1314344656 12562608 2154016
21:51:45 54063 8192000000 10236279632 10678538240 1282055312 13.532 95 19 39 0 1295258880 9094880 4108688
21:53:40 81533 8192000000 10250659568 10706636800 1330866944 13.975 98 16 32 1 1343733936 8665040 4201952
21:56:23 25409 8192000000 10269337312 10714484736 1351742208 14.163 99 48 95 1 1377777888 14953024 11082656
21:57:55 72564 8192000000 10249985712 10717958144 1344506944 14.098 99 9 18 0 1354892304 8332880 2052480
22:00:11 49339 8192000000 10268967104 10733338624 1356121088 14.203 99 21 42 1 1371683168 11046640 4515440
22:02:02 64926 8192000000 10280655104 10738020352 1361405280 14.250 99 22 44 0 1379790592 13057984 5327328
22:04:08 31657 8192000000 10304891456 10756820992 1392999120 14.533 100 49 98 0 1417169792 12728256 11442416
22:05:45 35623 8192000000 10285677120 10736361472 1344114432 14.094 97 26 52 0 1359331072 9476192 5740448
22:07:21 22127 8192000000 10300727360 10742902784 1378720272 14.405 100 43 86 1 1402517856 14119728 9677856
22:08:49 31641 8192000000 10297536064 10748248064 1390356528 14.509 100 21 42 1 1405775488 10952704 4466256
22:10:21 144161 8192000000 10316170976 10781835264 1330826528 13.975 95 9 18 0 1347232272 14250912 2154832
22:14:15 87502 8192000000 10303880016 10760892416 1387933600 14.487 99 28 56 0 1403298256 9242992 6121664
22:18:06 74792 8192000000 10265240976 10726785024 1347249056 14.123 97 9 18 0 1360758736 11567840 1941840
22:20:36 55056 8192000000 10288958592 10762326016 1385565248 14.466 100 11 22 0 1402021504 13853536 2602720
22:22:38 51233 8192000000 10292883760 10747359232 1368049840 14.310 98 30 61 0 1384154784 9016624 7088320
22:24:30 43615 8192000000 10293916384 10747359232 1376361696 14.384 99 26 52 0 1393904784 11484112 6058976
22:26:24 47508 8192000000 10272566752 10723991552 1360167200 14.239 98 13 26 0 1375569376 12291248 3110928
22:28:19 85765 8192000000 10292214576 10749526016 1337884896 14.038 96 20 40 0 1362650912 19297552 5468464
22:30:56 48936 8192000000 10318194976 10792603648 1387615280 14.485 100 10 20 0 1422768672 32480720 2672672
22:33:04 114138 8192000000 10265987536 10716442624 1231390624 13.067 89 24 48 1 1249979920 13332560 5256736
22:36:03 73879 8192000000 10264413392 10715807744 1338630016 14.045 98 20 40 0 1352400752 9444640 4326096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-04-2022 22:40:04 to 30-04-2022 22:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-04-2022 22:40:20 to 30-04-2022 22:40:22.

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          238291
MON$OLDEST_ACTIVE               240335
MON$OLDEST_SNAPSHOT             240335
MON$NEXT_TRANSACTION            240335
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                       2038496
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.33580 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33580 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33580 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33580 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 ---
--- end of diff output ---

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

30.04.22 22:40:23. 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-04-30 22:40:25 No severe PSQL-related problems occured
30.04.22 22:40:26. End of report.