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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16323680 kB
MemAvailable 31419012 kB
Buffers 0 kB
Cached 15361348 kB
SwapCached 2648 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-17 01:38:19
DTS_END                         2022-05-17 01:38:19.6030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36540
TRN_ID                          253095
ATT_ID                          737
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: 9522.44 17591 1142693
customer order: creation 186.99 314 22439
customer order: refuse 15.93 354 1912
order to supplier: creation 48.23 1317 5788
order to supplier: removal 3.68 112083 441
invoice (draft): creation 48.38 3581 5806
invoice (draft): removal 54.31 812 6517
invoice accept: apply 43.52 18706 5222
invoice accept: cancel 48.88 827 5866
customer reserve: creation 3950.28 188 474034
customer reserve: removal 4810.66 67 577279
realization accept: apply 89.15 205 10698
realization accept: cancel 82.47 108 9896
payment from customer: creation 64.44 111 7733
payment from customer: removal 6.24 81 749
payment to supplier: creation 52.27 133 6272
payment to supplier: removal 3.33 74 400
service: total inventory turnovers 5.33 1071 639
service: total monetary turnovers 7.29 31 875
service: aggregate perf. data 1.03 14496 123
service: refresh index statistics 0.03 197258 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7241
TEST_TIME 2 5797
TEST_TIME 3 4623
TEST_TIME 4 5696
TEST_TIME 5 5856
TEST_TIME 6 5808
TEST_TIME 7 5696
TEST_TIME 8 5962
TEST_TIME 9 6017
TEST_TIME 10 6004
TEST_TIME 11 6321
TEST_TIME 12 6735
TEST_TIME 13 6726
TEST_TIME 14 6697
TEST_TIME 15 6730
TEST_TIME 16 6763
TEST_TIME 17 6907
TEST_TIME 18 7021
TEST_TIME 19 6973
TEST_TIME 20 6938
TEST_TIME 21 7067
TEST_TIME 22 7101
TEST_TIME 23 7165
TEST_TIME 24 7523
TEST_TIME 25 7868
TEST_TIME 26 8056
TEST_TIME 27 8670
TEST_TIME 28 8707
TEST_TIME 29 8659
TEST_TIME 30 9544
TEST_TIME 31 10441
TEST_TIME 32 10824
TEST_TIME 33 10727
TEST_TIME 34 10594
TEST_TIME 35 10533
TEST_TIME 36 10465
TEST_TIME 37 10508
TEST_TIME 38 10491
TEST_TIME 39 10542
TEST_TIME 40 10510
TEST_TIME 41 10343
TEST_TIME 42 10143
TEST_TIME 43 10081
TEST_TIME 44 10006
TEST_TIME 45 9894
TEST_TIME 46 9838
TEST_TIME 47 9941
TEST_TIME 48 9808
TEST_TIME 49 9865
TEST_TIME 50 9856
TEST_TIME 51 9895
TEST_TIME 52 9868
TEST_TIME 53 9777
TEST_TIME 54 9692
TEST_TIME 55 9642
TEST_TIME 56 9622
TEST_TIME 57 9531
TEST_TIME 58 9520
TEST_TIME 59 9510
TEST_TIME 60 9503
TEST_TIME 61 9486
TEST_TIME 62 9471
TEST_TIME 63 9504
TEST_TIME 64 9641
TEST_TIME 65 9643
TEST_TIME 66 9674
TEST_TIME 67 9707
TEST_TIME 68 9689
TEST_TIME 69 9686
TEST_TIME 70 9768
TEST_TIME 71 9767
TEST_TIME 72 9765
TEST_TIME 73 9855
TEST_TIME 74 9834
TEST_TIME 75 9722
TEST_TIME 76 9691
TEST_TIME 77 9658
TEST_TIME 78 9625
TEST_TIME 79 9602
TEST_TIME 80 9676
TEST_TIME 81 9616
TEST_TIME 82 9584
TEST_TIME 83 9484
TEST_TIME 84 9551
TEST_TIME 85 9567
TEST_TIME 86 9537
TEST_TIME 87 9554
TEST_TIME 88 9523
TEST_TIME 89 9544
TEST_TIME 90 9559
TEST_TIME 91 9559
TEST_TIME 92 9629
TEST_TIME 93 9628
TEST_TIME 94 9577
TEST_TIME 95 9645
TEST_TIME 96 9693
TEST_TIME 97 9758
TEST_TIME 98 9804
TEST_TIME 99 9772
TEST_TIME 100 9714
TEST_TIME 101 9690
TEST_TIME 102 9647
TEST_TIME 103 9609
TEST_TIME 104 9612
TEST_TIME 105 9583
TEST_TIME 106 9565
TEST_TIME 107 9540
TEST_TIME 108 9493
TEST_TIME 109 9444
TEST_TIME 110 9434
TEST_TIME 111 9475
TEST_TIME 112 9468
TEST_TIME 113 9476
TEST_TIME 114 9452
TEST_TIME 115 9504
TEST_TIME 116 9517
TEST_TIME 117 9477
TEST_TIME 118 9547
TEST_TIME 119 9575
TEST_TIME 120 9525

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-05-2022 01:39:07 to 17-05-2022 01:39:10.

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 1583272 1583272 0 0.00 0 16157 6 0 0
doc_list_biud 1583272 1583272 0 0.00 0 63947 31 0 0
sp_add_invoice_to_stock 5390 5222 168 3.11 52 192863 18706 0 168
sp_cancel_adding_invoice 5866 5866 0 0.00 4 71924 827 0 0
sp_cancel_client_order 1912 1912 0 0.00 4 15478 355 0 0
sp_cancel_customer_reserve 577279 577279 0 0.00 1 63948 68 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 3 5892 82 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 4654 74 0 0
sp_cancel_supplier_invoice 6532 6517 15 0.22 1 15145 813 0 15
sp_cancel_supplier_order 441 441 0 0.00 7 1191971 112083 0 0
sp_cancel_write_off 9896 9896 0 0.00 1 5722 108 0 0
sp_client_order 22439 22439 0 0.00 3 16113 315 0 0
sp_customer_reserve 474034 474034 0 0.00 0 49077 188 0 0
sp_fill_shopping_cart 34018 34018 0 0.00 1 14541 38 0 0
sp_fill_shopping_cart_clo_res 474032 474032 0 0.00 0 20313 14 0 0
sp_get_clo_for_invoice 5220 5220 0 0.00 0 36195 288 0 0
sp_kill_cost_storno 16911 16911 0 0.00 0 5887 63 0 0
sp_kill_qstorno_ret_qs2qd 1171412 1171412 0 0.00 0 63945 36 0 0
sp_kill_qty_storno 617829 617829 0 0.00 0 63946 78 0 0
sp_lock_selected_doc 22018 22018 0 0.00 0 8393 5 0 0
sp_make_cost_storno 29923 29923 0 0.00 0 16148 56 0 0
sp_multiply_rows_for_pdistr 29923 29923 0 0.00 0 8116 29 0 0
sp_multiply_rows_for_qdistr 22439 22439 0 0.00 0 16095 140 0 0
sp_pay_from_customer 7733 7733 0 0.00 4 10756 112 0 0
sp_pay_to_supplier 6272 6272 0 0.00 5 16370 134 0 0
sp_payment_common 14005 14005 0 0.00 1 14704 31 0 0
sp_qd_handle_on_cancel_clo 1912 1912 0 0.00 0 15472 337 0 0
sp_qd_handle_on_invoice_upd_sts 11086 11086 0 0.00 0 16496 374 0 0
sp_qd_handle_on_reserve_upd_sts 10698 10698 0 0.00 0 23734 77 0 0
sp_reserve_write_off 10698 10698 0 0.00 6 24295 206 0 0
sp_supplier_invoice 5806 5806 0 0.00 6 74443 3581 0 0
sp_supplier_order 5788 5788 0 0.00 5 34835 1318 0 0
srv_aggregate_perf_data 123 123 0 0.00 2489 115348 14496 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1667 126971 38707 0 0
srv_find_qd_qs_mism 1092980 1092980 0 0.00 0 13293 5 0 0
srv_make_invnt_saldo 639 639 0 0.00 29 83405 1071 0 0
srv_make_money_saldo 875 875 0 0.00 0 3397 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 106781 266632 197259 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5223 5223 0 0.00 0 14344 192 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 5349 96 0 0
t$perf-norm:sp_cancel_client_order 1912 1912 0 0.00 0 875 9 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 875 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 1191 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1275 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 1437 14 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 20964 1705 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 1183 6 0 0
t$perf-norm:sp_client_order 22439 22439 0 0.00 0 11890 19 0 0
t$perf-norm:sp_customer_reserve 1158 1158 0 0.00 0 2830 11 0 0
t$perf-norm:sp_pay_from_customer 7733 7733 0 0.00 0 5671 9 0 0
t$perf-norm:sp_pay_to_supplier 6272 6272 0 0.00 0 3973 9 0 0
t$perf-norm:sp_reserve_write_off 10698 10698 0 0.00 0 12441 12 0 0
t$perf-norm:sp_supplier_invoice 5806 5806 0 0.00 0 9124 19 0 0
t$perf-norm:sp_supplier_order 5788 5788 0 0.00 0 7838 20 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 398 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 52 1 0 0
t$perf-norm:srv_make_invnt_saldo 639 639 0 0.00 0 673 20 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 3944 35 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 10776 10440 336 3.11 0 4105 16 0 336
v_all_customers 40682 40682 0 0.00 0 38 1 0 0
v_all_suppliers 32126 32126 0 0.00 0 15 1 0 0
v_all_wares 44878 44878 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1914 8 0 0
v_cancel_client_order 3824 3824 0 0.00 0 1039 3 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 1364 5 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 742 4 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 226 4 0 0
v_cancel_supplier_invoice 924 894 30 3.24 0 1392 17 0 30
v_cancel_supplier_order 882 882 0 0.00 0 1274 6 0 0
v_cancel_write_off 2090 2090 0 0.00 0 2236 15 0 0
v_max_id_clo_ord 5782 5782 0 0.00 0 4368 2 0 0
v_max_id_clo_res 1158 1158 0 0.00 0 2461 15 0 0
v_max_id_ord_sup 5797 5797 0 0.00 0 6257 6 0 0
v_max_non_paid_invoice 1788 1788 0 0.00 0 3140 6 0 0
v_max_non_paid_realizn 4185 4185 0 0.00 0 2810 4 0 0
v_min_id_clo_ord 5782 5782 0 0.00 0 3872 8 0 0
v_min_id_clo_res 1158 1158 0 0.00 0 2913 14 0 0
v_min_id_ord_sup 5797 5797 0 0.00 0 8091 14 0 0
v_min_non_paid_invoice 6272 6272 0 0.00 0 14697 24 0 0
v_min_non_paid_realizn 7733 7733 0 0.00 0 8230 22 0 0
v_our_firm 11292 11292 0 0.00 0 30 1 0 0
v_reserve_write_off 21396 21396 0 0.00 0 2344 5 0 0
x_lock_depdocs_on_canc_invoice 11936 11936 0 0.00 0 15123 120 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 6619 338 0 0
x_make_qstorno_1000_1200 5788 5788 0 0.00 1 34824 1233 0 0
x_make_qstorno_1200_2000 5806 5806 0 0.00 3 74328 3445 0 0
x_make_qstorno_2100_3300 474034 474034 0 0.00 0 49077 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-05-2022 01:39:11 to 17-05-2022 01:39:15.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:23 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 14606 8192000000 9893818064 10203426816 979874256 10.683 100 83 165 0 1008066832 6200528 21992048
23:10:43 22898 8192000000 9943016496 10263650304 1025467296 11.125 100 88 176 0 1056602880 8110992 23024592
23:12:11 12454 8192000000 9979182208 10307149824 1061726256 11.473 100 91 182 0 1094165824 9449392 22990176
23:13:30 9285 8192000000 10023302720 10367905792 1110398992 11.936 100 81 162 0 1140662464 10631600 19631872
23:14:41 10114 8192000000 10073928288 10432225280 1164942928 12.450 100 85 170 0 1196382032 11637504 19801600
23:15:53 4681 8192000000 10124694128 10493743104 1212017808 12.888 100 86 172 0 1246345184 14918864 19408512
23:17:00 3082 8192000000 10170253504 10541715456 1248574480 13.225 100 89 176 3 1283388064 14904640 19908944
23:18:04 2392 8192000000 10199248224 10580340736 1285219328 13.561 100 93 186 0 1319379248 12949024 21210896
23:19:08 1820 8192000000 10225336560 10609348608 1305590320 13.746 100 92 184 3 1338801088 12872640 20338128
23:20:12 2302 8192000000 10215118832 10613776384 1310065072 13.787 100 74 147 2 1338654240 11713408 16875760
23:21:16 5027 8192000000 10204524224 10614648832 1313995552 13.822 100 33 66 0 1329465952 7685408 7784992
23:22:22 7126 8192000000 10217519280 10632151040 1323380720 13.907 100 27 54 0 1341077392 11523296 6173376
23:23:29 47731 8192000000 10252578288 10653126656 1335325488 14.015 100 68 137 3 1357312048 5884624 16101936
23:25:18 2911 8192000000 10232675024 10644942848 1335603136 14.018 100 30 59 1 1348557824 5959552 6995136
23:26:21 51654 8192000000 10224724384 10648276992 1333689584 14.000 100 30 60 0 1347055696 5615424 7750688
23:28:13 6318 8192000000 10223407328 10637152256 1316129248 13.842 99 36 72 2 1330407040 5768320 8509472
23:29:21 5929 8192000000 10178506416 10587172864 1277632128 13.491 99 20 40 2 1287754432 6261792 3860512
23:30:28 64665 8192000000 10143707040 10544566272 1203092784 12.805 95 21 42 0 1214145328 6113024 4939520
23:32:35 4002 8192000000 10156515552 10555469824 1253524464 13.271 99 43 86 1 1269856448 5342000 10989984
23:33:44 5508 8192000000 10160319488 10567049216 1269001376 13.412 100 33 66 0 1283268336 6170768 8096192
23:34:54 5593 8192000000 10181992272 10591252480 1287533936 13.582 100 28 56 1 1304300496 10615792 6150768
23:36:03 29124 8192000000 10188883552 10602762240 1297398256 13.672 100 23 46 0 1308951952 5833168 5720528
23:37:35 8353 8192000000 10199173536 10608840704 1302864384 13.721 100 27 55 0 1314637248 4669488 7103376
23:38:49 5434 8192000000 10240271984 10646528000 1332655584 13.991 100 53 102 2 1351616400 7126832 11833984
23:40:06 16250 8192000000 10252479712 10661240832 1342686736 14.082 100 44 86 1 1361407600 8686176 10034688
23:41:29 40554 8192000000 10266872320 10686058496 1355440576 14.196 100 47 94 1 1381362352 15100016 10821760
23:43:24 4053 8192000000 10283784176 10703245312 1373947616 14.362 100 45 86 1 1392074784 7492816 10634352
23:44:34 15610 8192000000 10290136176 10716008448 1386375424 14.474 100 37 73 0 1405195568 10144464 8675680
23:45:55 4588 8192000000 10306814832 10723823616 1386738064 14.477 100 53 106 1 1414594528 15174960 12681504
23:47:08 13809 8192000000 10312027184 10737737728 1397252832 14.571 100 52 103 1 1427072752 18025824 11794096
23:48:34 2609 8192000000 10318126784 10738413568 1392142848 14.525 100 52 104 3 1422616720 18006560 12467312
23:49:39 14344 8192000000 10318304864 10738634752 1392492480 14.528 100 61 120 1 1430667408 23822512 14352416
23:50:58 3982 8192000000 10323899952 10744774656 1395809344 14.558 100 68 136 3 1434178608 21298256 17071008
23:52:05 7512 8192000000 10322341936 10735116288 1387895712 14.487 100 71 141 4 1429281616 23784496 17601408
23:53:17 3075 8192000000 10327782720 10742620160 1389649600 14.503 100 63 126 0 1434965408 29752768 15563040
23:54:26 2721 8192000000 10324470224 10737139712 1380640768 14.422 100 66 129 1 1431996272 35479872 15875632
23:55:37 2914 8192000000 10325017168 10727690240 1371078208 14.337 100 88 177 0 1434058560 41681872 21298480
23:56:48 2829 8192000000 10326358336 10728169472 1366115360 14.292 100 83 166 1 1430669936 43913936 20640640
23:57:56 3689 8192000000 10339699296 10738270208 1370736576 14.334 100 75 149 2 1440157888 50334656 19086656
23:59:05 1664 8192000000 10363328000 10764632064 1391047536 14.515 100 82 163 2 1466301200 54364304 20889360
00:00:15 2783 8192000000 10375763456 10777391104 1393884960 14.541 100 88 175 1 1477996064 61434240 22676864
00:01:22 2417 8192000000 10393825696 10794078208 1403459280 14.626 100 87 174 1 1491363984 64650384 23254320
00:02:29 4696 8192000000 10388415616 10807177216 1407370528 14.661 100 65 130 1 1494478480 69247312 17860640
00:03:39 6749 8192000000 10388609568 10815180800 1412491696 14.706 100 53 106 0 1494864176 68214848 14157632
00:04:52 11376 8192000000 10378318880 10807734272 1401818368 14.611 100 46 92 2 1483450096 68789840 12841888
00:06:08 12651 8192000000 10365314624 10812915712 1404923376 14.639 100 42 84 1 1480672432 63737760 12011296
00:07:25 23185 8192000000 10364017504 10801926144 1391680048 14.521 100 38 76 2 1465926288 63168816 11077424
00:09:28 35948 8192000000 10306156672 10779033600 1372361168 14.348 100 14 29 1 1419634144 43299472 3973504
00:11:12 24780 8192000000 10300196848 10735288320 1360412336 14.241 98 44 88 2 1378552112 7041504 11098272
00:12:43 24787 8192000000 10306509952 10746191872 1391288160 14.517 100 35 66 3 1408282832 9605552 7389120
00:14:09 44687 8192000000 10302508112 10752892928 1391896128 14.523 100 27 54 0 1407579376 9108928 6574320
00:15:56 85796 8192000000 10315549472 10760069120 1384172176 14.454 99 18 36 0 1406808416 18470144 4166096
00:18:49 109638 8192000000 10322553200 10762584064 1385571712 14.466 99 27 54 1 1399935200 7759008 6604480
00:21:43 14957 8192000000 10319254496 10755878912 1388704080 14.494 99 41 82 2 1403882672 5892640 9285952
00:23:00 64989 8192000000 10298775920 10750668800 1373474064 14.358 98 24 48 1 1387878272 8934880 5469328
00:25:09 28230 8192000000 10321018480 10776322048 1405753584 14.646 100 37 74 0 1428691920 13030816 9907520
00:26:43 19241 8192000000 10359029216 10792312832 1423559408 14.804 100 62 119 1 1453258208 15646448 14052352
00:28:15 52848 8192000000 10320092752 10794016768 1417561184 14.751 100 16 32 2 1437478656 15600368 4317104
00:30:10 28392 8192000000 10311016256 10772824064 1400122688 14.596 99 25 50 1 1416429392 10593904 5712800
00:31:44 40140 8192000000 10282403664 10748719104 1348940000 14.138 96 8 16 0 1360452672 9225712 2286960
00:33:46 5153 8192000000 10301787200 10771865600 1400921056 14.603 100 10 20 0 1415494976 11837296 2736624
00:34:52 37270 8192000000 10291480592 10732171264 1369949936 14.327 98 40 82 4 1385704688 5487328 10267424
00:36:30 57452 8192000000 10287337424 10758725632 1365361712 14.285 98 7 14 0 1376755856 9870288 1523856
00:38:28 64156 8192000000 10283143920 10739277824 1327530176 13.945 95 17 34 1 1337668448 6551040 3587232
00:40:32 44869 8192000000 10284695680 10729000960 1329344912 13.961 96 23 46 0 1346963504 12545360 5073232
00:42:17 50498 8192000000 10268814576 10722770944 1353234704 14.177 99 16 32 0 1377063392 19519296 4309392
00:44:20 30826 8192000000 10274748784 10723901440 1362816304 14.263 100 40 80 0 1382842672 11771648 8254720
00:46:07 24322 8192000000 10267938208 10713182208 1341474784 14.071 99 21 41 3 1354320192 8342000 4503408
00:47:37 96504 8192000000 10256245376 10713972736 1267479168 13.399 93 18 36 1 1282176768 10685248 4012352
00:50:15 83481 8192000000 10260748064 10704945152 1297311680 13.671 96 20 40 2 1314686512 12671136 4703696
00:52:39 89334 8192000000 10240105904 10681167872 1324037824 13.913 99 20 40 1 1338399152 9828768 4532560
00:55:14 57159 8192000000 10247252816 10688028672 1317439424 13.854 98 41 82 0 1337609792 10412048 9758320
00:57:12 63529 8192000000 10259918784 10720444416 1357875232 14.218 100 13 26 0 1373615904 13126768 2613904
00:59:15 33170 8192000000 10262038160 10716102656 1348791248 14.137 99 8 16 0 1362396592 11959104 1646240
01:00:51 95836 8192000000 10268843584 10732097536 1358372672 14.223 99 16 32 0 1374172032 11977600 3821760
01:03:29 68358 8192000000 10266430768 10719457280 1325918752 13.930 97 12 24 0 1341144544 12176720 3049072
01:05:49 21992 8192000000 10268836032 10719010816 1354860448 14.191 99 23 46 1 1367908624 8059968 4988208
01:07:11 22729 8192000000 10287008880 10733957120 1373998112 14.363 100 28 54 0 1388452960 8634960 5819888
01:08:35 52064 8192000000 10312984576 10773004288 1398231728 14.579 100 19 38 0 1414221584 11961456 4028400
01:10:28 97927 8192000000 10299731792 10766102528 1355166960 14.194 97 7 14 0 1377308224 20689248 1452016
01:13:06 54127 8192000000 10303991104 10758512640 1357303808 14.213 97 34 68 1 1379647184 15073216 7270160
01:15:00 106527 8192000000 10290889168 10757693440 1306680000 13.756 94 8 16 0 1326297216 17780928 1836288
01:17:48 126971 8192000000 10275136240 10723926016 1312808544 13.812 95 29 58 1 1326969328 7273872 6886912
01:20:56 12734 8192000000 10271636672 10733453312 1369907088 14.326 100 19 38 0 1383935088 9922016 4105984
01:22:17 60258 8192000000 10272366224 10736455680 1357392592 14.214 98 15 30 2 1374567632 13823680 3351360
01:24:21 82080 8192000000 10261984144 10715586560 1306761904 13.757 95 15 30 3 1318497232 8329840 3405488
01:26:45 78868 8192000000 10273508128 10727268352 1366265920 14.294 99 20 41 0 1384112944 13064448 4782576
01:29:04 78579 8192000000 10278335440 10747174912 1367466896 14.304 99 13 27 0 1384286944 13407200 3412848
01:31:23 24133 8192000000 10267248352 10720952320 1347847792 14.128 98 17 34 0 1358908816 6799360 4261664
01:32:50 74310 8192000000 10279726640 10743836672 1312221584 13.806 95 18 36 0 1333094816 16771248 4101984
01:35:27 49367 8192000000 10279443312 10726023168 1341367264 14.070 98 34 68 0 1366307168 16503616 8436288
01:37:24 42231 8192000000 10281064944 10734546944 1340446480 14.061 98 28 56 0 1362925040 16553392 5925168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-05-2022 01:39:17 to 17-05-2022 01:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 01:39:34 to 17-05-2022 01:39:36.

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          251225
MON$OLDEST_ACTIVE               253233
MON$OLDEST_SNAPSHOT             253233
MON$NEXT_TRANSACTION            253233
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                       2048480
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:433: 
:434: mscs-test-161.budzdorov.ru    Tue May 17 01:38:22 2022
:435:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:436: 
--- end of diff output ---

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

17.05.22 01:39:37. 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-17 01:39:39 No severe PSQL-related problems occured
17.05.22 01:39:40. End of report.