Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2022 16: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 15380720 kB
MemAvailable 30469728 kB
Buffers 0 kB
Cached 16318136 kB
SwapCached 2088 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-19 16:38:33
DTS_END                         2022-10-19 16:38:33.2530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32828
TRN_ID                          237276
ATT_ID                          629
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: 9562.20 22426 1147464
customer order: creation 164.93 359 19791
customer order: refuse 14.28 336 1714
order to supplier: creation 44.23 1147 5308
order to supplier: removal 3.75 91915 450
invoice (draft): creation 44.98 3261 5397
invoice (draft): removal 57.01 676 6841
invoice accept: apply 39.46 16461 4735
invoice accept: cancel 51.80 694 6216
customer reserve: creation 3717.58 160 446109
customer reserve: removal 5127.99 53 615359
realization accept: apply 81.37 154 9764
realization accept: cancel 88.56 98 10627
payment from customer: creation 58.17 95 6980
payment from customer: removal 5.58 83 670
payment to supplier: creation 47.78 125 5733
payment to supplier: removal 3.26 76 391
service: total inventory turnovers 4.53 1164 543
service: total monetary turnovers 6.10 22 732
service: aggregate perf. data 0.83 19791 100
service: refresh index statistics 0.03 311859 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2022 16:39:39 to 19-10-2022 16:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2821
TEST_TIME 2 3146
TEST_TIME 3 3356
TEST_TIME 4 3786
TEST_TIME 5 4069
TEST_TIME 6 3936
TEST_TIME 7 4081
TEST_TIME 8 4068
TEST_TIME 9 3947
TEST_TIME 10 4279
TEST_TIME 11 4533
TEST_TIME 12 4722
TEST_TIME 13 4859
TEST_TIME 14 5561
TEST_TIME 15 5838
TEST_TIME 16 6008
TEST_TIME 17 6440
TEST_TIME 18 7556
TEST_TIME 19 7560
TEST_TIME 20 8089
TEST_TIME 21 8211
TEST_TIME 22 8769
TEST_TIME 23 9356
TEST_TIME 24 9662
TEST_TIME 25 9642
TEST_TIME 26 9688
TEST_TIME 27 9642
TEST_TIME 28 9633
TEST_TIME 29 9736
TEST_TIME 30 9752
TEST_TIME 31 9675
TEST_TIME 32 9691
TEST_TIME 33 9621
TEST_TIME 34 9538
TEST_TIME 35 9728
TEST_TIME 36 9656
TEST_TIME 37 9670
TEST_TIME 38 9769
TEST_TIME 39 9731
TEST_TIME 40 9800
TEST_TIME 41 9865
TEST_TIME 42 9771
TEST_TIME 43 9908
TEST_TIME 44 9787
TEST_TIME 45 9574
TEST_TIME 46 9509
TEST_TIME 47 9599
TEST_TIME 48 9479
TEST_TIME 49 9463
TEST_TIME 50 9399
TEST_TIME 51 9488
TEST_TIME 52 9535
TEST_TIME 53 9442
TEST_TIME 54 9393
TEST_TIME 55 9352
TEST_TIME 56 9226
TEST_TIME 57 9330
TEST_TIME 58 9421
TEST_TIME 59 9384
TEST_TIME 60 9357
TEST_TIME 61 9377
TEST_TIME 62 9389
TEST_TIME 63 9477
TEST_TIME 64 9504
TEST_TIME 65 9578
TEST_TIME 66 9546
TEST_TIME 67 9530
TEST_TIME 68 9550
TEST_TIME 69 9678
TEST_TIME 70 9724
TEST_TIME 71 9843
TEST_TIME 72 9831
TEST_TIME 73 9817
TEST_TIME 74 9748
TEST_TIME 75 9720
TEST_TIME 76 9685
TEST_TIME 77 9624
TEST_TIME 78 9604
TEST_TIME 79 9578
TEST_TIME 80 9611
TEST_TIME 81 9559
TEST_TIME 82 9571
TEST_TIME 83 9564
TEST_TIME 84 9530
TEST_TIME 85 9527
TEST_TIME 86 9498
TEST_TIME 87 9480
TEST_TIME 88 9628
TEST_TIME 89 9631
TEST_TIME 90 9642
TEST_TIME 91 9718
TEST_TIME 92 9675
TEST_TIME 93 9779
TEST_TIME 94 9768
TEST_TIME 95 9782
TEST_TIME 96 9774
TEST_TIME 97 9770
TEST_TIME 98 9839
TEST_TIME 99 9816
TEST_TIME 100 9735
TEST_TIME 101 9669
TEST_TIME 102 9670
TEST_TIME 103 9687
TEST_TIME 104 9625
TEST_TIME 105 9593
TEST_TIME 106 9546
TEST_TIME 107 9537
TEST_TIME 108 9535
TEST_TIME 109 9559
TEST_TIME 110 9509
TEST_TIME 111 9523
TEST_TIME 112 9505
TEST_TIME 113 9563
TEST_TIME 114 9571
TEST_TIME 115 9564
TEST_TIME 116 9610
TEST_TIME 117 9594
TEST_TIME 118 9587
TEST_TIME 119 9606
TEST_TIME 120 9564

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-10-2022 16:39:42 to 19-10-2022 16:39:46.

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 1561820 1561820 0 0.00 0 39001 5 0 0
doc_list_biud 1561820 1561820 0 0.00 0 42045 26 0 0
sp_add_invoice_to_stock 4828 4735 93 1.92 28 192903 16461 0 93
sp_cancel_adding_invoice 6216 6216 0 0.00 5 91192 694 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 14087 336 0 0
sp_cancel_customer_reserve 615359 615359 0 0.00 0 42047 53 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 10424 84 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 6265 76 0 0
sp_cancel_supplier_invoice 6852 6841 11 0.16 2 38842 676 0 11
sp_cancel_supplier_order 450 450 0 0.00 9 991413 91915 0 0
sp_cancel_write_off 10627 10627 0 0.00 1 20999 99 0 0
sp_client_order 19791 19791 0 0.00 3 35594 359 0 0
sp_customer_reserve 446109 446109 0 0.00 0 120861 161 0 0
sp_fill_shopping_cart 30485 30485 0 0.00 1 12016 37 0 0
sp_fill_shopping_cart_clo_res 446105 446105 0 0.00 0 20814 11 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 34543 258 0 0
sp_kill_cost_storno 17904 17904 0 0.00 0 7430 47 0 0
sp_kill_qstorno_ret_qs2qd 1248636 1248636 0 0.00 0 42042 29 0 0
sp_kill_qty_storno 655702 655702 0 0.00 0 42044 61 0 0
sp_lock_selected_doc 20202 20202 0 0.00 0 7230 5 0 0
sp_make_cost_storno 27208 27208 0 0.00 0 10429 50 0 0
sp_multiply_rows_for_pdistr 27208 27208 0 0.00 0 11065 25 0 0
sp_multiply_rows_for_qdistr 19791 19791 0 0.00 0 13617 151 0 0
sp_pay_from_customer 6980 6980 0 0.00 4 22357 95 0 0
sp_pay_to_supplier 5733 5733 0 0.00 4 13391 126 0 0
sp_payment_common 12713 12713 0 0.00 2 22225 29 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 14078 302 0 0
sp_qd_handle_on_invoice_upd_sts 10947 10947 0 0.00 0 18152 280 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 11633 46 0 0
sp_reserve_write_off 9764 9764 0 0.00 5 16148 154 0 0
sp_supplier_invoice 5397 5397 0 0.00 7 101179 3261 0 0
sp_supplier_order 5308 5308 0 0.00 4 36135 1147 0 0
srv_aggregate_perf_data 100 100 0 0.00 2033 240009 19792 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1445 195479 49221 0 0
srv_find_qd_qs_mism 1101879 1101879 0 0.00 0 39000 4 0 0
srv_make_invnt_saldo 543 543 0 0.00 37 64065 1165 0 0
srv_make_money_saldo 732 732 0 0.00 1 1352 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 218323 395709 311860 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4735 4735 0 0.00 0 13523 151 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 5607 100 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 3806 15 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2641 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1397 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 413 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 755 13 0 0
t$perf-norm:sp_cancel_supplier_order 450 450 0 0.00 0 20836 1503 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 678 4 0 0
t$perf-norm:sp_client_order 19791 19791 0 0.00 0 9894 15 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 1459 5 0 0
t$perf-norm:sp_pay_from_customer 6980 6980 0 0.00 0 4975 10 0 0
t$perf-norm:sp_pay_to_supplier 5733 5733 0 0.00 0 4282 9 0 0
t$perf-norm:sp_reserve_write_off 9764 9764 0 0.00 0 13494 12 0 0
t$perf-norm:sp_supplier_invoice 5397 5397 0 0.00 0 3889 11 0 0
t$perf-norm:sp_supplier_order 5308 5308 0 0.00 0 12566 19 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 351 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 511 13 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 1572 21 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 523 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 192 50 0 0
v_add_invoice_to_stock 9648 9462 186 1.92 0 12461 23 0 186
v_all_customers 36704 36704 0 0.00 0 26 0 0 0
v_all_suppliers 29844 29844 0 0.00 0 26 1 0 0
v_all_wares 39582 39582 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1053 7 0 0
v_cancel_client_order 3428 3428 0 0.00 0 4724 7 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 10414 14 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2712 9 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 498 3 0 0
v_cancel_supplier_invoice 768 746 22 2.86 0 6989 38 0 22
v_cancel_supplier_order 900 900 0 0.00 0 843 4 0 0
v_cancel_write_off 2062 2062 0 0.00 0 1857 12 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 346 1 0 0
v_max_id_clo_res 969 969 0 0.00 0 2919 16 0 0
v_max_id_ord_sup 5380 5380 0 0.00 0 2555 3 0 0
v_max_non_paid_invoice 1499 1499 0 0.00 0 4200 7 0 0
v_max_non_paid_realizn 3628 3628 0 0.00 0 4270 6 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 6117 8 0 0
v_min_id_clo_res 969 969 0 0.00 0 8805 22 0 0
v_min_id_ord_sup 5380 5380 0 0.00 0 4234 8 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 5892 16 0 0
v_min_non_paid_realizn 6980 6980 0 0.00 0 22221 22 0 0
v_our_firm 9582 9582 0 0.00 0 31 1 0 0
v_reserve_write_off 19528 19528 0 0.00 0 11287 7 0 0
x_lock_depdocs_on_canc_invoice 12684 12684 0 0.00 0 23017 114 0 0
x_lock_depdocs_on_canc_sup_ord 450 450 0 0.00 0 10141 289 0 0
x_make_qstorno_1000_1200 5308 5308 0 0.00 1 36117 1080 0 0
x_make_qstorno_1200_2000 5397 5397 0 0.00 2 97639 3122 0 0
x_make_qstorno_2100_3300 446109 446109 0 0.00 0 120858 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2022 16:39:47 to 19-10-2022 16:39:51.

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:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:37 22773 8192000000 9918218272 10232451072 997678608 10.856 100 87 174 0 1025666064 6066640 21920816
14:11:10 5056 8192000000 9949092912 10271301632 1032809488 11.195 100 90 180 0 1064697872 8595760 23292624
14:12:34 6840 8192000000 9984184256 10314993664 1065112256 11.505 100 91 182 0 1097509360 9903600 22493504
14:13:47 11629 8192000000 10034788160 10386673664 1122664784 12.052 100 71 142 0 1149831552 10685856 16480912
14:15:01 3262 8192000000 10067307936 10425622528 1158787920 12.392 100 81 162 0 1190312992 12490304 19034768
14:16:08 3418 8192000000 10113072112 10478698496 1202159136 12.796 100 81 162 0 1234190752 13701056 18330560
14:17:16 1382 8192000000 10153686896 10526617600 1239517232 13.142 100 90 179 1 1276168064 17533664 19117168
14:18:19 2121 8192000000 10191713312 10572283904 1274374832 13.462 100 85 170 0 1306515040 13405776 18734432
14:19:23 1930 8192000000 10211170096 10597863424 1297919984 13.676 100 84 169 0 1329993680 13905280 18168416
14:20:26 13837 8192000000 10211657520 10608676864 1306287920 13.752 100 65 129 0 1328768304 6948288 15532096
14:21:40 9131 8192000000 10198546480 10612187136 1311234464 13.797 100 19 38 0 1321879648 6607968 4037216
14:22:50 12801 8192000000 10199159216 10622521344 1321904080 13.894 100 16 33 1 1330671216 5275152 3491984
14:24:03 8797 8192000000 10212545232 10639245312 1334332720 14.006 100 19 39 0 1345557536 6882528 4342288
14:25:13 31973 8192000000 10238933248 10660253696 1340547760 14.062 100 38 76 0 1357123232 8496560 8078912
14:26:49 7465 8192000000 10237137328 10659606528 1341621408 14.072 100 27 54 0 1354549808 6500816 6427584
14:27:59 9415 8192000000 10223186528 10644180992 1331085504 13.977 100 19 38 0 1341034736 5426368 4522864
14:29:11 39840 8192000000 10181208656 10594463744 1253512208 13.270 95 21 41 0 1263123152 5399472 4211472
14:30:52 6856 8192000000 10142317120 10535632896 1195250736 12.732 94 20 40 0 1205878048 6014944 4612368
14:32:00 7812 8192000000 10131389968 10530635776 1208919440 12.859 96 21 42 0 1219251568 5292640 5039488
14:33:09 11763 8192000000 10164245120 10554064896 1239008448 13.137 98 36 74 1 1253893056 5568640 9315968
14:34:23 19893 8192000000 10171844352 10581671936 1276295440 13.479 100 18 36 0 1290387440 10337760 3754240
14:35:43 7561 8192000000 10181463184 10596405248 1292845328 13.630 100 19 39 1 1303270384 5924624 4500432
14:36:52 8858 8192000000 10194883888 10612117504 1304428800 13.735 100 25 50 0 1316131184 5804928 5897456
14:38:01 11245 8192000000 10213138608 10629382144 1319480368 13.872 100 18 35 0 1327710768 4624592 3605808
14:39:12 45672 8192000000 10241668688 10659614720 1339693968 14.055 100 33 62 0 1353328224 6172128 7462128
14:41:13 15453 8192000000 10273094448 10692673536 1363688400 14.270 100 42 80 1 1382065200 8896128 9480672
14:42:35 22236 8192000000 10295516736 10718576640 1382411088 14.438 100 52 102 0 1408672048 14204176 12056784
14:44:05 17810 8192000000 10312730512 10736394240 1391713520 14.521 100 38 74 0 1417671616 17003760 8954336
14:45:33 17200 8192000000 10325336736 10758623232 1413877280 14.718 100 38 76 0 1445195808 20678592 10639936
14:47:07 16486 8192000000 10342312832 10769870848 1420264480 14.775 100 41 80 1 1458055024 26977744 10812800
14:48:30 10583 8192000000 10362115648 10788163584 1430897104 14.869 100 53 107 0 1473246928 29005200 13344624
14:49:47 3896 8192000000 10378983296 10800836608 1435880016 14.913 100 74 146 2 1485457536 31521664 18055856
14:50:58 2796 8192000000 10386933424 10808635392 1439959152 14.949 100 68 134 0 1492382160 35092400 17330608
14:52:05 1440 8192000000 10400668560 10817224704 1444222336 14.987 100 80 159 1 1503252432 39151568 19878528
14:53:13 1477 8192000000 10348077072 10753622016 1393793872 14.540 99 88 176 3 1454477984 37728048 22956064
14:54:22 11724 8192000000 10366574240 10787176448 1414698432 14.726 100 61 122 0 1472674304 42026448 15949424
14:55:36 4886 8192000000 10364781264 10777804800 1394515184 14.546 99 72 142 0 1465863696 51779312 19569200
14:56:43 29092 8192000000 10323484480 10768023552 1377514064 14.394 98 30 60 1 1421157840 34972304 8671472
14:58:14 22014 8192000000 10311158048 10751393792 1374161952 14.364 99 32 63 0 1420616000 37960240 8493808
14:59:39 19683 8192000000 10290161232 10719944704 1343563248 14.090 99 23 45 1 1382999440 32765808 6670384
15:01:00 17141 8192000000 10256703872 10685988864 1318220112 13.861 99 21 41 0 1350548160 26379520 5948528
15:02:22 24858 8192000000 10280468736 10711662592 1363543536 14.269 100 28 56 0 1394041008 24030544 6466928
15:03:49 26586 8192000000 10269657648 10711375872 1352615120 14.171 99 14 26 0 1369421680 13405008 3401552
15:05:35 32084 8192000000 10261976480 10708918272 1336742816 14.028 98 11 22 0 1352748224 13099088 2906320
15:07:09 35165 8192000000 10257465824 10723102720 1339406496 14.052 99 9 18 0 1354611072 12538624 2665952
15:08:44 33166 8192000000 10277137648 10711187456 1353507904 14.179 100 21 44 0 1372660400 14355632 4796864
15:10:20 106957 8192000000 10282004208 10724757504 1354843088 14.191 99 25 49 0 1367873936 8256208 4774640
15:13:18 71575 8192000000 10274900656 10726363136 1357401152 14.214 99 6 12 0 1371900416 13219456 1279808
15:15:30 106248 8192000000 10289678784 10731487232 1345848752 14.110 98 11 22 0 1366220880 18127024 2245104
15:18:17 68902 8192000000 10286275280 10746359808 1363953280 14.273 99 7 14 0 1378945104 13500736 1491088
15:20:43 34950 8192000000 10311774448 10753994752 1391354992 14.518 100 27 53 0 1408251728 11124576 5772160
15:22:30 91456 8192000000 10314271968 10748227584 1384484528 14.457 100 24 48 2 1407691120 17497792 5708800
15:25:03 150050 8192000000 10328322560 10777903104 1402201328 14.615 100 35 70 0 1430042544 20075712 7765504
15:28:37 38010 8192000000 10337081776 10778042368 1410999472 14.693 100 44 84 2 1440119024 19908144 9211408
15:30:21 101831 8192000000 10316771440 10776453120 1407953760 14.666 100 17 35 0 1423722688 11625408 4143520
15:33:15 43640 8192000000 10310040592 10770374656 1406367776 14.652 100 12 24 0 1424550992 14783968 3399248
15:35:06 49215 8192000000 10308794544 10785181696 1397626096 14.574 99 21 39 0 1421791920 19075344 5090480
15:37:06 56700 8192000000 10297952560 10749284352 1348062048 14.130 96 18 38 0 1363300720 11419392 3819280
15:39:17 57812 8192000000 10283674608 10751635456 1332649776 13.991 96 10 20 0 1352631712 17831184 2150752
15:41:24 34152 8192000000 10291578640 10741071872 1354330848 14.186 98 16 32 0 1369159200 11791440 3036912
15:43:01 37903 8192000000 10287886240 10736066560 1344413264 14.097 97 37 74 0 1364692960 11624320 8655376
15:44:50 65642 8192000000 10294541968 10743218176 1363568304 14.269 99 22 44 1 1381679552 13548128 4563120
15:46:57 50819 8192000000 10294808016 10756251648 1367074016 14.301 99 18 32 0 1397503152 26886640 3542496
15:49:14 49590 8192000000 10282765136 10736218112 1338871008 14.047 98 14 28 0 1354823584 12870800 3081776
15:51:07 77963 8192000000 10245267504 10689892352 1308845968 13.776 98 21 42 0 1330975872 17455152 4674752
15:54:22 42813 8192000000 10262549696 10696089600 1310388976 13.790 97 42 84 0 1328040912 8572928 9079008
15:56:06 1535 8192000000 10263541792 10704568320 1351162800 14.158 100 33 67 1 1367283552 8959584 7161168
15:57:08 42157 8192000000 10267048624 10716663808 1362332000 14.258 100 31 62 0 1381507472 12127728 7047744
15:58:53 86385 8192000000 10238101584 10692161536 1286747760 13.575 95 19 38 0 1306245920 14839312 4658848
16:01:21 56420 8192000000 10241109168 10696081408 1341660512 14.072 100 10 20 0 1357924384 13776864 2487008
16:03:18 17338 8192000000 10244406992 10691842048 1335512656 14.017 99 8 16 0 1346462336 9181824 1767856
16:04:42 91635 8192000000 10270982624 10720325632 1337461680 14.035 98 15 30 0 1356542656 15586864 3494112
16:07:13 195479 8192000000 10294590896 10744430592 1358248880 14.222 99 8 16 0 1374130624 14587024 1294720
16:11:42 98759 8192000000 10308578512 10762502144 1383844064 14.451 99 12 24 0 1397826384 11461616 2520704
16:14:21 42620 8192000000 10334032864 10774753280 1409039376 14.675 100 47 92 1 1430055216 11200624 9815216
16:16:07 95923 8192000000 10300282336 10751389696 1373189952 14.356 97 22 43 0 1393344544 15232032 4922560
16:18:53 93746 8192000000 10307945312 10771525632 1379639328 14.413 98 20 40 0 1392466928 8463072 4364528
16:21:27 66104 8192000000 10305967984 10786123776 1408161792 14.668 100 7 14 0 1424221520 14598576 1461152
16:23:34 58709 8192000000 10306886080 10769629184 1376916816 14.389 98 9 18 0 1389544096 10570016 2057264
16:26:06 71852 8192000000 10302176560 10765672448 1383759472 14.450 98 18 36 1 1400500304 12782432 3958400
16:28:22 45939 8192000000 10283739936 10742018048 1309204544 13.779 93 11 22 0 1320680912 9156944 2319424
16:30:15 37529 8192000000 10303042848 10758778880 1388764704 14.495 100 15 30 0 1407808736 15628720 3415312
16:31:54 47420 8192000000 10262463248 10715467776 1299447984 13.690 94 11 22 0 1318480080 16624608 2407488
16:33:48 62489 8192000000 10278022928 10743930880 1360725984 14.244 99 27 54 0 1382446784 15553344 6167456
16:36:16 55783 8192000000 10271764448 10726686720 1304488048 13.736 95 21 42 0 1321567488 12715168 4364272
16:38:13 36128 8192000000 9392049216 9695977472 530080320 6.077 39 11 22 0 541924256 9451552 2392384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2022 16:39:53 to 19-10-2022 16:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2022 16:40:09 to 19-10-2022 16:40:11.

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          236886
MON$OLDEST_ACTIVE               238060
MON$OLDEST_SNAPSHOT             238060
MON$NEXT_TRANSACTION            238060
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                       2033696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:138: 
:139: mscs-test-161.budzdorov.ru    Wed Oct 19 16:38:52 2022
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Wed Oct 19 16:39:09 2022
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:145: 
--- end of diff output ---

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

19.10.22 16:40:12. 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-10-19 16:40:14 No severe PSQL-related problems occured
19.10.22 16:40:16. End of report.