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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16545484 kB
MemAvailable 31459788 kB
Buffers 0 kB
Cached 15195444 kB
SwapCached 2632 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-18 01:38:58
DTS_END                         2022-05-18 01:38:17.6410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50090
TRN_ID                          250564
ATT_ID                          274
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: 9690.37 19335 1162844
customer order: creation 190.13 351 22815
customer order: refuse 15.77 308 1892
order to supplier: creation 50.05 1219 6006
order to supplier: removal 3.41 88419 409
invoice (draft): creation 49.68 3016 5961
invoice (draft): removal 51.27 750 6152
invoice accept: apply 46.46 16816 5575
invoice accept: cancel 47.56 742 5707
customer reserve: creation 4224.78 169 506974
customer reserve: removal 4686.18 51 562341
realization accept: apply 92.56 154 11107
realization accept: cancel 90.53 107 10863
payment from customer: creation 65.22 101 7827
payment from customer: removal 5.83 69 700
payment to supplier: creation 53.73 112 6447
payment to supplier: removal 4.02 78 482
service: total inventory turnovers 5.39 898 647
service: total monetary turnovers 6.85 25 822
service: aggregate perf. data 0.94 15089 113
service: refresh index statistics 0.03 258233 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-05-2022 01:40:25 to 18-05-2022 01:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1984
TEST_TIME 2 3589
TEST_TIME 3 4303
TEST_TIME 4 4460
TEST_TIME 5 4701
TEST_TIME 6 4523
TEST_TIME 7 4789
TEST_TIME 8 5183
TEST_TIME 9 5589
TEST_TIME 10 6060
TEST_TIME 11 6356
TEST_TIME 12 6810
TEST_TIME 13 7082
TEST_TIME 14 7794
TEST_TIME 15 7979
TEST_TIME 16 8339
TEST_TIME 17 8574
TEST_TIME 18 8936
TEST_TIME 19 9006
TEST_TIME 20 9549
TEST_TIME 21 10186
TEST_TIME 22 10828
TEST_TIME 23 10722
TEST_TIME 24 10798
TEST_TIME 25 10766
TEST_TIME 26 11010
TEST_TIME 27 11467
TEST_TIME 28 11607
TEST_TIME 29 11507
TEST_TIME 30 11304
TEST_TIME 31 11193
TEST_TIME 32 10878
TEST_TIME 33 10777
TEST_TIME 34 10802
TEST_TIME 35 10751
TEST_TIME 36 10805
TEST_TIME 37 10604
TEST_TIME 38 10572
TEST_TIME 39 10587
TEST_TIME 40 10511
TEST_TIME 41 10426
TEST_TIME 42 10426
TEST_TIME 43 10337
TEST_TIME 44 10376
TEST_TIME 45 10259
TEST_TIME 46 10118
TEST_TIME 47 10049
TEST_TIME 48 9938
TEST_TIME 49 9977
TEST_TIME 50 9937
TEST_TIME 51 10025
TEST_TIME 52 10033
TEST_TIME 53 9984
TEST_TIME 54 9941
TEST_TIME 55 10024
TEST_TIME 56 10101
TEST_TIME 57 10212
TEST_TIME 58 10169
TEST_TIME 59 10141
TEST_TIME 60 10264
TEST_TIME 61 10228
TEST_TIME 62 10139
TEST_TIME 63 10097
TEST_TIME 64 9985
TEST_TIME 65 9989
TEST_TIME 66 9895
TEST_TIME 67 9950
TEST_TIME 68 9865
TEST_TIME 69 9861
TEST_TIME 70 9874
TEST_TIME 71 9848
TEST_TIME 72 9880
TEST_TIME 73 9907
TEST_TIME 74 9848
TEST_TIME 75 9839
TEST_TIME 76 9896
TEST_TIME 77 9923
TEST_TIME 78 9966
TEST_TIME 79 9956
TEST_TIME 80 10019
TEST_TIME 81 9950
TEST_TIME 82 9855
TEST_TIME 83 9777
TEST_TIME 84 9796
TEST_TIME 85 9710
TEST_TIME 86 9651
TEST_TIME 87 9668
TEST_TIME 88 9669
TEST_TIME 89 9741
TEST_TIME 90 9704
TEST_TIME 91 9738
TEST_TIME 92 9739
TEST_TIME 93 9700
TEST_TIME 94 9764
TEST_TIME 95 9844
TEST_TIME 96 9780
TEST_TIME 97 9819
TEST_TIME 98 9864
TEST_TIME 99 9829
TEST_TIME 100 9843
TEST_TIME 101 9782
TEST_TIME 102 9739
TEST_TIME 103 9709
TEST_TIME 104 9674
TEST_TIME 105 9621
TEST_TIME 106 9579
TEST_TIME 107 9610
TEST_TIME 108 9577
TEST_TIME 109 9596
TEST_TIME 110 9573
TEST_TIME 111 9596
TEST_TIME 112 9608
TEST_TIME 113 9610
TEST_TIME 114 9720
TEST_TIME 115 9683
TEST_TIME 116 9740
TEST_TIME 117 9730
TEST_TIME 118 9759
TEST_TIME 119 9768
TEST_TIME 120 9692

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-05-2022 01:40:28 to 18-05-2022 01:40:32.

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 1632296 1632296 0 0.00 0 12056 6 0 0
doc_list_biud 1632296 1632296 0 0.00 0 72780 23 0 0
sp_add_invoice_to_stock 5710 5575 135 2.36 24 205883 16817 0 135
sp_cancel_adding_invoice 5707 5707 0 0.00 4 71266 743 0 0
sp_cancel_client_order 1892 1892 0 0.00 3 22431 309 0 0
sp_cancel_customer_reserve 562341 562341 0 0.00 1 20429 51 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 2479 69 0 0
sp_cancel_pay_to_supplier 482 482 0 0.00 2 4093 78 0 0
sp_cancel_supplier_invoice 6156 6152 4 0.06 1 72786 751 0 4
sp_cancel_supplier_order 409 409 0 0.00 13 1218693 88420 0 0
sp_cancel_write_off 10863 10863 0 0.00 1 10001 107 0 0
sp_client_order 22815 22815 0 0.00 4 13295 352 0 0
sp_customer_reserve 506974 506974 0 0.00 0 71907 170 0 0
sp_fill_shopping_cart 34782 34782 0 0.00 1 14630 36 0 0
sp_fill_shopping_cart_clo_res 506970 506970 0 0.00 0 15704 14 0 0
sp_get_clo_for_invoice 5568 5568 0 0.00 0 23597 301 0 0
sp_kill_cost_storno 17752 17752 0 0.00 0 7384 51 0 0
sp_kill_qstorno_ret_qs2qd 1142106 1142106 0 0.00 0 72777 28 0 0
sp_kill_qty_storno 604038 604038 0 0.00 0 72779 61 0 0
sp_lock_selected_doc 23092 23092 0 0.00 0 8667 5 0 0
sp_make_cost_storno 30948 30948 0 0.00 0 12047 45 0 0
sp_multiply_rows_for_pdistr 30948 30948 0 0.00 0 7681 27 0 0
sp_multiply_rows_for_qdistr 22815 22815 0 0.00 0 13286 181 0 0
sp_pay_from_customer 7827 7827 0 0.00 4 11187 101 0 0
sp_pay_to_supplier 6447 6447 0 0.00 4 12064 112 0 0
sp_payment_common 14274 14274 0 0.00 1 7122 26 0 0
sp_qd_handle_on_cancel_clo 1892 1892 0 0.00 0 22424 284 0 0
sp_qd_handle_on_invoice_upd_sts 11274 11274 0 0.00 0 17094 282 0 0
sp_qd_handle_on_reserve_upd_sts 11107 11107 0 0.00 0 13188 50 0 0
sp_reserve_write_off 11107 11107 0 0.00 6 13201 154 0 0
sp_supplier_invoice 5961 5961 0 0.00 6 108428 3017 0 0
sp_supplier_order 6006 6006 0 0.00 5 74450 1219 0 0
srv_aggregate_perf_data 113 113 0 0.00 2600 110059 15089 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1688 159891 47892 0 0
srv_find_qd_qs_mism 1110441 1110441 0 0.00 0 9406 5 0 0
srv_make_invnt_saldo 647 647 0 0.00 30 67508 899 0 0
srv_make_money_saldo 822 822 0 0.00 1 1749 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 174085 365242 258234 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5575 5575 0 0.00 0 16988 162 0 0
t$perf-norm:sp_cancel_adding_invoice 494 494 0 0.00 0 11013 113 0 0
t$perf-norm:sp_cancel_client_order 1892 1892 0 0.00 0 3151 13 0 0
t$perf-norm:sp_cancel_customer_reserve 826 826 0 0.00 0 4369 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 2397 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 482 482 0 0.00 0 779 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 480 480 0 0.00 0 1922 19 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 15877 1491 0 0
t$perf-norm:sp_cancel_write_off 1138 1138 0 0.00 0 4797 14 0 0
t$perf-norm:sp_client_order 22815 22815 0 0.00 0 15635 16 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 5797 15 0 0
t$perf-norm:sp_pay_from_customer 7827 7827 0 0.00 0 6714 10 0 0
t$perf-norm:sp_pay_to_supplier 6447 6447 0 0.00 0 9836 11 0 0
t$perf-norm:sp_reserve_write_off 11107 11107 0 0.00 0 7077 11 0 0
t$perf-norm:sp_supplier_invoice 5961 5961 0 0.00 0 5877 17 0 0
t$perf-norm:sp_supplier_order 6006 6006 0 0.00 0 8373 18 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 405 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 146 4 0 0
t$perf-norm:srv_make_invnt_saldo 647 647 0 0.00 0 720 17 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 3202 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 11398 11128 270 2.36 0 5045 17 0 270
v_all_customers 41734 41734 0 0.00 0 15 1 0 0
v_all_suppliers 33168 33168 0 0.00 0 17 1 0 0
v_all_wares 45630 45630 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 988 988 0 0.00 0 5076 12 0 0
v_cancel_client_order 3784 3784 0 0.00 0 3542 5 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1287 3 0 0
v_cancel_customer_reserve 1652 1652 0 0.00 0 862 4 0 0
v_cancel_payment_to_supplier 964 964 0 0.00 0 820 4 0 0
v_cancel_supplier_invoice 968 960 8 0.82 0 3379 21 0 8
v_cancel_supplier_order 818 818 0 0.00 0 396 4 0 0
v_cancel_write_off 2276 2276 0 0.00 0 1534 13 0 0
v_max_id_clo_ord 6006 6006 0 0.00 0 160 1 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 2779 17 0 0
v_max_id_ord_sup 5961 5961 0 0.00 0 5550 5 0 0
v_max_non_paid_invoice 1830 1830 0 0.00 0 320 3 0 0
v_max_non_paid_realizn 4131 4131 0 0.00 0 3382 5 0 0
v_min_id_clo_ord 6006 6006 0 0.00 0 3494 4 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 1454 7 0 0
v_min_id_ord_sup 5961 5961 0 0.00 0 6392 7 0 0
v_min_non_paid_invoice 6447 6447 0 0.00 0 6225 20 0 0
v_min_non_paid_realizn 7827 7827 0 0.00 0 7116 17 0 0
v_our_firm 11288 11288 0 0.00 0 14 1 0 0
v_reserve_write_off 22214 22214 0 0.00 0 5133 6 0 0
x_lock_depdocs_on_canc_invoice 11379 11379 0 0.00 0 24162 94 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 5101 263 0 0
x_make_qstorno_1000_1200 6006 6006 0 0.00 1 74435 1145 0 0
x_make_qstorno_1200_2000 5961 5961 0 0.00 2 108401 2885 0 0
x_make_qstorno_2100_3300 506974 506974 0 0.00 0 71902 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-05-2022 01:40:33 to 18-05-2022 01:40:37.

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:21 21 8192000000 8802398864 8815149056 5315664 0.064 2 1 2 0 5415264 56816 42784
23:09:22 26917 8192000000 9890330896 10197254144 967597728 10.563 100 82 164 0 995704640 6314864 21792048
23:10:50 20348 8192000000 9946957168 10267095040 1021047088 11.082 100 87 174 0 1052186560 8842816 22296656
23:12:20 10508 8192000000 9962654128 10294136832 1050664832 11.367 100 79 158 0 1081066016 10678736 19722448
23:13:40 4167 8192000000 10024845872 10365689856 1108615568 11.919 100 89 178 0 1141684256 11823584 21245104
23:14:50 10504 8192000000 10079150048 10434637824 1151033664 12.319 100 74 148 0 1180254064 11856192 17364208
23:16:03 4334 8192000000 10120207504 10490122240 1211119856 12.879 100 79 156 3 1241581824 13380896 17081072
23:17:09 4175 8192000000 10163624160 10538967040 1252200992 13.258 100 89 176 3 1285114128 13237952 19675184
23:18:15 2075 8192000000 10198747888 10576932864 1281084144 13.523 100 91 184 1 1315156272 12964560 21107568
23:19:18 14758 8192000000 10207293504 10595471360 1295569760 13.655 100 74 146 2 1323141488 11282448 16289280
23:20:34 3211 8192000000 10205539120 10608869376 1308252544 13.770 100 47 93 2 1326618528 7169920 11196064
23:21:38 13403 8192000000 10211337680 10623168512 1320239584 13.879 100 26 52 0 1335279664 9598240 5441840
23:22:52 32110 8192000000 10213950976 10634182656 1327295792 13.943 100 21 41 1 1336887168 5470624 4120752
23:24:31 6665 8192000000 10225920032 10648408064 1334659472 14.009 100 14 28 1 1347880688 9993456 3227760
23:25:43 6640 8192000000 10227424112 10646364160 1338636416 14.045 100 17 34 1 1348145776 5616896 3892464
23:26:53 68886 8192000000 10221906048 10650488832 1331203296 13.978 99 25 50 0 1342309440 4615840 6490304
23:29:02 6034 8192000000 10191634384 10604535808 1291982496 13.622 99 29 57 2 1304237792 5664336 6590960
23:30:08 9417 8192000000 10166987248 10575872000 1255281248 13.287 98 25 50 2 1270883184 9921472 5680464
23:31:19 10313 8192000000 10154545248 10555875328 1242691520 13.171 98 24 48 1 1253620400 5219392 5709488
23:32:29 36486 8192000000 10134246736 10521440256 1162372272 12.425 92 27 54 2 1174336112 4863936 7099904
23:34:06 5995 8192000000 10174338416 10578964480 1276192336 13.478 100 34 67 2 1294516416 10920768 7403312
23:35:16 4560 8192000000 10192610080 10603737088 1302164496 13.715 100 36 72 2 1316320192 5852624 8303072
23:36:30 13789 8192000000 10200941824 10615910400 1310057296 13.787 100 21 42 2 1320848496 6052464 4738736
23:37:46 31636 8192000000 10214141744 10630283264 1322308800 13.898 100 29 58 2 1336111648 5446672 8356176
23:39:26 45561 8192000000 10262580912 10672824320 1348048496 14.130 100 51 101 1 1370688320 11803392 10836432
23:41:16 26358 8192000000 10277128208 10699485184 1369329200 14.321 100 49 98 0 1395190368 14871696 10989472
23:42:48 8572 8192000000 10299910192 10718863360 1385872096 14.469 100 56 112 0 1416080640 16831056 13377488
23:44:04 18619 8192000000 10314051696 10736713728 1397431136 14.572 100 48 96 0 1427230048 18549952 11248960
23:45:37 21999 8192000000 10318347504 10748137472 1405444304 14.643 100 35 71 1 1435664192 21223200 8996688
23:47:04 16508 8192000000 10349315584 10769739776 1421079840 14.782 100 57 113 3 1462746992 28483520 13183632
23:48:25 3318 8192000000 10354883264 10774577152 1405793520 14.647 99 65 130 1 1451789168 30643232 15352416
23:49:33 11328 8192000000 10355369712 10784092160 1423487120 14.804 100 45 90 0 1466881072 32222560 11171392
23:50:47 4730 8192000000 10372184416 10788982784 1418294496 14.758 100 79 158 0 1476705264 39227536 19183232
23:51:55 5633 8192000000 10366535568 10785828864 1383073952 14.444 97 64 128 2 1441427488 42277360 16076176
23:53:02 3149 8192000000 10374906832 10791821312 1418785760 14.762 100 77 154 1 1476713072 38544784 19382528
23:54:09 11483 8192000000 10318250848 10745110528 1373347728 14.357 99 42 83 2 1420356384 35846944 11161712
23:55:28 3190 8192000000 10341008992 10752397312 1381097424 14.426 100 76 152 1 1439421872 39471328 18853120
23:56:36 8803 8192000000 10324651296 10754392064 1370224624 14.329 99 44 86 1 1425820592 44357984 11237984
23:58:00 11782 8192000000 10327786592 10759049216 1378171008 14.400 100 46 93 1 1441233536 50814080 12248448
23:59:36 19037 8192000000 10316242096 10763759616 1377391136 14.393 100 23 46 0 1424350192 40292976 6666080
00:00:57 30018 8192000000 10297856384 10745405440 1379645776 14.413 100 25 50 0 1412356336 26315584 6394976
00:02:43 18093 8192000000 10268412928 10705612800 1320024624 13.877 98 22 44 1 1347949600 22389888 5535088
00:04:07 29702 8192000000 10269880496 10706792448 1332831920 13.993 99 14 28 0 1363813440 26652976 4328544
00:05:45 33669 8192000000 10268131200 10717257728 1354772928 14.190 100 13 26 1 1373389600 15314720 3301952
00:07:31 75594 8192000000 10270484192 10713612288 1344979440 14.102 99 16 31 0 1358164608 9860096 3325072
00:09:47 78463 8192000000 10280623712 10715865088 1330172448 13.969 97 20 41 0 1341208400 6458432 4577520
00:12:08 61211 8192000000 10298676080 10733502464 1367348800 14.303 99 29 58 0 1388100496 13576528 7175168
00:14:19 81600 8192000000 10289131648 10718203904 1340206000 14.059 97 20 40 0 1354930896 10178880 4546016
00:16:42 21851 8192000000 10268054944 10717683712 1366242240 14.293 99 37 71 1 1384684672 10132272 8310160
00:18:09 1687 8192000000 10282057776 10720735232 1370267232 14.329 100 68 133 2 1394358832 7422752 16668848
00:19:12 69378 8192000000 10286285888 10742276096 1383584912 14.449 100 16 33 1 1397607568 10049744 3972912
00:22:25 14960 8192000000 10283343808 10738958336 1372282880 14.347 99 19 38 1 1389170928 12282080 4605968
00:23:55 83764 8192000000 10282103856 10741800960 1383268080 14.446 100 19 38 1 1393804624 6512096 4024448
00:26:32 60378 8192000000 10291403824 10753789952 1384188208 14.454 99 21 42 0 1397011360 8062000 4761152
00:28:42 37573 8192000000 10290493360 10746806272 1324262864 13.915 95 14 25 1 1337323312 10115680 2944768
00:30:25 36830 8192000000 10284294592 10727116800 1333109408 13.995 96 24 48 0 1346252496 7617792 5525296
00:32:14 39006 8192000000 10311072560 10755203072 1382872240 14.442 99 32 64 0 1407006176 16192336 7941600
00:34:02 43758 8192000000 10292196704 10745864192 1373122176 14.355 99 13 26 1 1392048944 16054208 2872560
00:35:51 53028 8192000000 10279109328 10733473792 1325898432 13.930 95 10 20 2 1336584432 8775440 1910560
00:37:46 66922 8192000000 10278790592 10728361984 1333602944 14.000 97 12 24 0 1353790576 17838016 2349616
00:39:53 143623 8192000000 10257244432 10706079744 1336387088 14.025 97 23 46 0 1359376368 17930064 5059216
00:43:30 102869 8192000000 10260976720 10714578944 1328766800 13.956 96 15 30 1 1351027616 19309328 2951488
00:46:26 45857 8192000000 10271395136 10727239680 1341034560 14.067 97 14 28 1 1358768592 14549072 3184960
00:48:14 81641 8192000000 10273950224 10731507712 1337339968 14.033 97 13 25 0 1352745120 12645008 2760144
00:50:36 79579 8192000000 10253372368 10698104832 1276395184 13.480 93 18 36 1 1287761728 7295680 4070864
00:53:03 56041 8192000000 10282150704 10725163008 1357459616 14.215 99 28 56 1 1379063680 15200464 6403600
00:54:59 159891 8192000000 10279011376 10735894528 1291380032 13.617 94 9 18 0 1314002816 20587152 2035632
00:58:51 32311 8192000000 10288361936 10723921920 1349115232 14.140 98 33 65 3 1365453680 8224160 8114288
01:00:24 77300 8192000000 10293318640 10725453824 1368995440 14.318 100 57 110 0 1389629408 7896848 12737120
01:02:42 53567 8192000000 10275663440 10744389632 1383964576 14.452 100 8 16 0 1393624256 7941664 1718016
01:04:35 45909 8192000000 10273776848 10743341056 1370796128 14.334 99 19 38 1 1389312416 13330160 5186128
01:06:21 76330 8192000000 10282457312 10736619520 1347594144 14.126 97 14 28 1 1360178528 9395984 3188400
01:08:44 25790 8192000000 10280579264 10734120960 1369927280 14.326 99 45 90 3 1387288880 7755200 9606400
01:10:13 59300 8192000000 10284751024 10735411200 1351784928 14.164 97 13 26 0 1362659392 8157616 2716848
01:12:39 71842 8192000000 10275951696 10747908096 1331904896 13.984 96 10 20 0 1350200640 15757216 2538528
01:14:52 16627 8192000000 10312447792 10742558720 1361039088 14.247 98 53 108 1 1384515440 11110336 12366016
01:16:08 91470 8192000000 10279821056 10723303424 1333342160 13.997 97 12 24 0 1344964256 8814592 2807504
01:18:44 151972 8192000000 10269098496 10724651008 1368615296 14.315 99 25 50 0 1384617200 9952608 6049296
01:22:16 62223 8192000000 10275273872 10738335744 1367609088 14.306 99 8 16 1 1381024448 11070112 2345248
01:24:22 36563 8192000000 10265578080 10726711296 1355585008 14.198 99 12 23 0 1370692720 12338800 2768912
01:25:59 47655 8192000000 10278922992 10722672640 1343029664 14.085 98 12 24 0 1358516272 12690736 2795872
01:27:50 54891 8192000000 10255856976 10698657792 1320603936 13.882 97 17 34 0 1344377200 19563216 4210048
01:29:45 31965 8192000000 10268261600 10714796032 1332383520 13.989 98 15 30 0 1356731616 20658832 3689264
01:31:27 84179 8192000000 10248007168 10706116608 1291341744 13.616 95 18 36 2 1308788304 13219376 4227184
01:34:04 47572 8192000000 10264985424 10713006080 1340846368 14.065 99 22 44 1 1358886976 12404272 5636336
01:35:52 90963 8192000000 10276329776 10730033152 1303350800 13.726 96 20 40 0 1327708128 19536544 4820784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-05-2022 01:40:39 to 18-05-2022 01:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-05-2022 01:40:54 to 18-05-2022 01:40:56.

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          250070
MON$OLDEST_ACTIVE               251073
MON$OLDEST_SNAPSHOT             251073
MON$NEXT_TRANSACTION            251073
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                       2028832
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.33592 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33592 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33592 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33592 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Wed May 18 01:39:07 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed May 18 01:39:08 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

18.05.22 01:40:57. 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-18 01:41:00 No severe PSQL-related problems occured
18.05.22 01:41:01. End of report.