Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.07.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 16231392 kB
MemAvailable 31105976 kB
Buffers 0 kB
Cached 15459056 kB
SwapCached 2528 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-07-08 16:38:30
DTS_END                         2022-07-08 16:38:30.7040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51308
TRN_ID                          236836
ATT_ID                          668
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: 9502.34 22873 1140281
customer order: creation 169.51 376 20341
customer order: refuse 14.48 335 1738
order to supplier: creation 45.99 1246 5519
order to supplier: removal 3.57 106909 428
invoice (draft): creation 45.29 3012 5435
invoice (draft): removal 55.14 843 6617
invoice accept: apply 42.42 16922 5090
invoice accept: cancel 49.63 672 5956
customer reserve: creation 3927.35 167 471282
customer reserve: removal 4856.02 61 582723
realization accept: apply 83.70 168 10044
realization accept: cancel 79.04 98 9485
payment from customer: creation 60.03 122 7203
payment from customer: removal 5.66 95 679
payment to supplier: creation 48.54 134 5825
payment to supplier: removal 3.47 61 416
service: total inventory turnovers 5.29 999 635
service: total monetary turnovers 6.30 41 756
service: aggregate perf. data 0.88 21737 105
service: refresh index statistics 0.03 303465 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-07-2022 16:39:28 to 08-07-2022 16:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5067
TEST_TIME 2 4649
TEST_TIME 3 4739
TEST_TIME 4 5508
TEST_TIME 5 5304
TEST_TIME 6 5186
TEST_TIME 7 5805
TEST_TIME 8 6226
TEST_TIME 9 6337
TEST_TIME 10 6535
TEST_TIME 11 6544
TEST_TIME 12 6479
TEST_TIME 13 6395
TEST_TIME 14 6527
TEST_TIME 15 6700
TEST_TIME 16 7054
TEST_TIME 17 7046
TEST_TIME 18 7519
TEST_TIME 19 7497
TEST_TIME 20 7530
TEST_TIME 21 7733
TEST_TIME 22 8429
TEST_TIME 23 8576
TEST_TIME 24 8876
TEST_TIME 25 9875
TEST_TIME 26 10559
TEST_TIME 27 10936
TEST_TIME 28 10823
TEST_TIME 29 10646
TEST_TIME 30 10628
TEST_TIME 31 10490
TEST_TIME 32 10281
TEST_TIME 33 10166
TEST_TIME 34 10180
TEST_TIME 35 9990
TEST_TIME 36 9923
TEST_TIME 37 10083
TEST_TIME 38 9996
TEST_TIME 39 10002
TEST_TIME 40 9963
TEST_TIME 41 9872
TEST_TIME 42 9968
TEST_TIME 43 9831
TEST_TIME 44 9721
TEST_TIME 45 9681
TEST_TIME 46 9579
TEST_TIME 47 9494
TEST_TIME 48 9466
TEST_TIME 49 9582
TEST_TIME 50 9499
TEST_TIME 51 9504
TEST_TIME 52 9576
TEST_TIME 53 9684
TEST_TIME 54 9835
TEST_TIME 55 9867
TEST_TIME 56 9816
TEST_TIME 57 9852
TEST_TIME 58 9809
TEST_TIME 59 9979
TEST_TIME 60 10004
TEST_TIME 61 9868
TEST_TIME 62 9771
TEST_TIME 63 9721
TEST_TIME 64 9667
TEST_TIME 65 9645
TEST_TIME 66 9628
TEST_TIME 67 9616
TEST_TIME 68 9612
TEST_TIME 69 9631
TEST_TIME 70 9673
TEST_TIME 71 9625
TEST_TIME 72 9702
TEST_TIME 73 9759
TEST_TIME 74 9716
TEST_TIME 75 9836
TEST_TIME 76 9894
TEST_TIME 77 9853
TEST_TIME 78 10054
TEST_TIME 79 10035
TEST_TIME 80 9912
TEST_TIME 81 9873
TEST_TIME 82 9809
TEST_TIME 83 9876
TEST_TIME 84 9792
TEST_TIME 85 9759
TEST_TIME 86 9730
TEST_TIME 87 9719
TEST_TIME 88 9642
TEST_TIME 89 9639
TEST_TIME 90 9623
TEST_TIME 91 9584
TEST_TIME 92 9619
TEST_TIME 93 9636
TEST_TIME 94 9625
TEST_TIME 95 9671
TEST_TIME 96 9629
TEST_TIME 97 9689
TEST_TIME 98 9694
TEST_TIME 99 9689
TEST_TIME 100 9676
TEST_TIME 101 9721
TEST_TIME 102 9690
TEST_TIME 103 9749
TEST_TIME 104 9699
TEST_TIME 105 9646
TEST_TIME 106 9637
TEST_TIME 107 9632
TEST_TIME 108 9589
TEST_TIME 109 9550
TEST_TIME 110 9507
TEST_TIME 111 9530
TEST_TIME 112 9507
TEST_TIME 113 9512
TEST_TIME 114 9489
TEST_TIME 115 9523
TEST_TIME 116 9497
TEST_TIME 117 9545
TEST_TIME 118 9578
TEST_TIME 119 9569
TEST_TIME 120 9510

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-07-2022 16:39:31 to 08-07-2022 16:39:35.

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 1576103 1576103 0 0.00 0 20703 6 0 0
doc_list_biud 1576102 1576102 0 0.00 0 29333 28 0 0
sp_add_invoice_to_stock 5173 5090 83 1.60 44 198201 16923 0 83
sp_cancel_adding_invoice 5956 5956 0 0.00 4 94704 673 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 13809 336 0 0
sp_cancel_customer_reserve 582723 582723 0 0.00 0 29335 62 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 2 7499 96 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 2089 62 0 0
sp_cancel_supplier_invoice 6626 6617 9 0.13 1 12410 844 0 9
sp_cancel_supplier_order 428 428 0 0.00 6 1082990 106909 0 0
sp_cancel_write_off 9485 9485 0 0.00 1 5453 99 0 0
sp_client_order 20341 20341 0 0.00 3 25099 376 0 0
sp_customer_reserve 471282 471282 0 0.00 0 103294 168 0 0
sp_fill_shopping_cart 31293 31293 0 0.00 1 8864 32 0 0
sp_fill_shopping_cart_clo_res 471274 471274 0 0.00 0 20171 14 0 0
sp_get_clo_for_invoice 5076 5076 0 0.00 0 27885 330 0 0
sp_kill_cost_storno 16536 16536 0 0.00 0 6388 52 0 0
sp_kill_qstorno_ret_qs2qd 1181976 1181976 0 0.00 0 29333 34 0 0
sp_kill_qty_storno 622065 622065 0 0.00 0 29333 71 0 0
sp_lock_selected_doc 20834 20834 0 0.00 0 8868 5 0 0
sp_make_cost_storno 28146 28146 0 0.00 0 20701 46 0 0
sp_multiply_rows_for_pdistr 28146 28146 0 0.00 0 6375 26 0 0
sp_multiply_rows_for_qdistr 20341 20341 0 0.00 0 16605 183 0 0
sp_pay_from_customer 7203 7203 0 0.00 4 17645 123 0 0
sp_pay_to_supplier 5825 5825 0 0.00 5 37358 134 0 0
sp_payment_common 13028 13028 0 0.00 1 9049 30 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 13799 302 0 0
sp_qd_handle_on_invoice_upd_sts 11030 11030 0 0.00 0 21798 295 0 0
sp_qd_handle_on_reserve_upd_sts 10044 10044 0 0.00 0 21317 59 0 0
sp_reserve_write_off 10044 10044 0 0.00 6 21342 168 0 0
sp_supplier_invoice 5435 5435 0 0.00 6 88175 3012 0 0
sp_supplier_order 5519 5519 0 0.00 5 24552 1247 0 0
srv_aggregate_perf_data 105 105 0 0.00 2505 314697 21738 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1760 150606 43082 0 0
srv_find_qd_qs_mism 1092026 1092026 0 0.00 0 18114 5 0 0
srv_make_invnt_saldo 635 635 0 0.00 37 36291 999 0 0
srv_make_money_saldo 756 756 0 0.00 1 5838 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 198155 407624 303466 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5090 5090 0 0.00 0 12447 154 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 2284 64 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 10218 19 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 1866 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 7139 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 625 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 6563 26 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 20417 1401 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 7138 12 0 0
t$perf-norm:sp_client_order 20341 20341 0 0.00 0 6915 16 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 1382 7 0 0
t$perf-norm:sp_pay_from_customer 7203 7203 0 0.00 0 5475 8 0 0
t$perf-norm:sp_pay_to_supplier 5825 5825 0 0.00 0 5869 9 0 0
t$perf-norm:sp_reserve_write_off 10044 10044 0 0.00 0 13735 10 0 0
t$perf-norm:sp_supplier_invoice 5435 5435 0 0.00 0 7339 14 0 0
t$perf-norm:sp_supplier_order 5519 5519 0 0.00 0 6312 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1491 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 635 635 0 0.00 0 1891 25 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 4629 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10314 10148 166 1.60 0 12142 21 0 166
v_all_customers 37200 37200 0 0.00 0 83 0 0 0
v_all_suppliers 30524 30524 0 0.00 0 106 1 0 0
v_all_wares 40682 40682 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2651 11 0 0
v_cancel_client_order 3476 3476 0 0.00 0 4390 11 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 6666 13 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 4695 17 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2054 9 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 5200 24 0 18
v_cancel_supplier_order 856 856 0 0.00 0 1387 7 0 0
v_cancel_write_off 2022 2022 0 0.00 0 3845 18 0 0
v_max_id_clo_ord 5519 5519 0 0.00 0 1046 1 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 4329 20 0 0
v_max_id_ord_sup 5433 5433 0 0.00 0 3394 4 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 4754 8 0 0
v_max_non_paid_realizn 3982 3982 0 0.00 0 2505 4 0 0
v_min_id_clo_ord 5519 5519 0 0.00 0 2228 6 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 5701 11 0 0
v_min_id_ord_sup 5433 5433 0 0.00 0 6938 7 0 0
v_min_non_paid_invoice 5825 5825 0 0.00 0 9045 19 0 0
v_min_non_paid_realizn 7203 7203 0 0.00 0 6492 25 0 0
v_our_firm 9924 9924 0 0.00 0 17 1 0 0
v_reserve_write_off 20088 20088 0 0.00 0 10899 9 0 0
x_lock_depdocs_on_canc_invoice 12179 12179 0 0.00 0 14454 109 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 28246 482 0 0
x_make_qstorno_1000_1200 5519 5519 0 0.00 1 24546 1182 0 0
x_make_qstorno_1200_2000 5435 5435 0 0.00 2 82465 2898 0 0
x_make_qstorno_2100_3300 471282 471282 0 0.00 0 103293 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 16:39:36 to 08-07-2022 16:39:40.

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:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:35 28699 8192000000 9891472528 10196201472 970559408 10.592 100 89 178 1 1000334144 6036160 23738576
14:11:07 25343 8192000000 9945407904 10265120768 1025767984 11.128 100 89 178 0 1056868560 8357728 22742848
14:12:36 12945 8192000000 9974747856 10303004672 1055553760 11.414 100 85 170 0 1087001792 10191808 21256224
14:13:54 9280 8192000000 10016244160 10359070720 1100165792 11.839 100 81 162 0 1130790336 10606032 20018512
14:15:09 6741 8192000000 10080073072 10432196608 1155966704 12.365 100 83 166 0 1186830080 11906912 18956464
14:16:19 4346 8192000000 10104382928 10471493632 1196471456 12.744 100 82 164 0 1228557168 13663664 18422048
14:17:27 3038 8192000000 10144962304 10517262336 1235314368 13.103 100 84 168 2 1267019056 13194960 18509728
14:18:31 1295 8192000000 10187739488 10567888896 1277718016 13.492 100 91 180 1 1311440480 13243568 20478896
14:19:34 6707 8192000000 10196827840 10589085696 1293381168 13.635 100 64 126 1 1316846432 9322192 14143072
14:20:43 4171 8192000000 10191604320 10595364864 1303982368 13.731 100 38 76 1 1321153120 8490368 8680384
14:21:50 4338 8192000000 10200526320 10610528256 1312218944 13.806 100 37 75 0 1327783424 7865088 7699392
14:22:56 11144 8192000000 10203128000 10618265600 1318013024 13.859 100 19 38 0 1328001632 6145504 3843104
14:24:08 25046 8192000000 10205713936 10627026944 1318504192 13.863 100 30 61 1 1331494528 5431504 7558832
14:25:36 4783 8192000000 10227404720 10635595776 1320559840 13.882 100 40 81 0 1336795728 7693008 8542880
14:26:42 5337 8192000000 10223877024 10639196160 1327484224 13.944 100 24 48 1 1339561168 5847520 6229424
14:27:51 59442 8192000000 10187591712 10594217984 1213742768 12.904 91 40 80 0 1228532080 4254224 10535088
14:29:51 5916 8192000000 10182993984 10587852800 1275220064 13.469 99 27 54 1 1291035248 9491456 6323728
14:30:58 9474 8192000000 10153031024 10558177280 1213133696 12.898 95 11 21 0 1224719344 9506064 2079584
14:32:07 8031 8192000000 10140433312 10539663360 1239022400 13.137 99 21 42 0 1249059344 5422704 4614240
14:33:19 46732 8192000000 10150246528 10555625472 1205531184 12.828 95 25 50 0 1216553328 4979904 6042240
14:35:06 5973 8192000000 10182470672 10584530944 1280089296 13.514 100 41 82 1 1295572592 6025776 9457520
14:36:12 5419 8192000000 10195126304 10602225664 1298366992 13.680 100 30 57 1 1310152560 5420704 6364864
14:37:19 91196 8192000000 10208568672 10624212992 1311669312 13.801 100 34 67 0 1324527216 5199696 7658208
14:39:54 24918 8192000000 10247734416 10665553920 1343741856 14.091 100 37 73 0 1359696080 8136784 7817440
14:41:27 9494 8192000000 10259124384 10680070144 1361758656 14.253 100 39 79 0 1381495360 10463728 9272976
14:43:24 32608 8192000000 10286245104 10711715840 1378637328 14.404 100 33 66 0 1400975008 14111152 8226528
14:44:59 2631 8192000000 10316866432 10732609536 1394540128 14.546 100 64 126 1 1430024912 21106448 14378336
14:46:06 9132 8192000000 10328800064 10754818048 1406672784 14.654 100 50 99 0 1439941648 21482880 11785984
14:47:20 5260 8192000000 10341094592 10770759680 1418058736 14.755 100 57 114 3 1456080624 24624224 13397664
14:48:31 14693 8192000000 10359616480 10780090368 1406878064 14.656 99 58 116 0 1453498176 32658992 13961120
14:49:58 6811 8192000000 10374170096 10803965952 1434567840 14.902 100 50 100 0 1483985248 36832832 12584576
14:51:13 1758 8192000000 10379491648 10796580864 1425071088 14.818 100 71 139 0 1484260544 41432064 17757392
14:52:19 7328 8192000000 10377020640 10801041408 1417089680 14.747 99 60 118 3 1480306352 47866112 15350560
14:53:34 5485 8192000000 10381360608 10798948352 1417571264 14.751 100 63 124 1 1482299184 48999200 15728720
14:54:45 3669 8192000000 10386265856 10794061824 1418269872 14.757 100 85 167 0 1483756896 44951248 20535776
14:55:55 4061 8192000000 10383638800 10796466176 1417822912 14.753 100 70 138 0 1488360416 52928256 17609248
14:57:02 6469 8192000000 10381325920 10803200000 1421029696 14.782 100 52 101 2 1487206272 52700080 13476496
14:58:26 8299 8192000000 10390233296 10822172672 1423391536 14.803 100 39 78 1 1496554288 61897888 11264864
14:59:50 5671 8192000000 10390688880 10810306560 1412206576 14.704 100 54 108 0 1492116384 65188432 14721376
15:00:59 19553 8192000000 10353693456 10793930752 1401273664 14.606 100 30 58 1 1465277872 55080512 8923696
15:02:20 11476 8192000000 10357331184 10792521728 1394734096 14.548 100 43 86 0 1468178448 61547840 11896512
15:03:36 41084 8192000000 10305311280 10756956160 1370534848 14.332 99 17 34 1 1426182352 50248448 5399056
15:05:19 89333 8192000000 10214963072 10648629248 1155817456 12.364 85 17 34 0 1170169200 10338000 4013744
15:07:54 16942 8192000000 10265232672 10688864256 1342521200 14.080 100 46 93 0 1361088192 7761440 10805552
15:09:14 91006 8192000000 10260043520 10702839808 1293586352 13.637 95 19 36 0 1314711920 17596016 3529552
15:11:49 150605 8192000000 10253292112 10695368704 1270108272 13.423 94 26 52 0 1286776064 11121712 5546080
15:15:21 10769 8192000000 10283244832 10715742208 1360297728 14.240 100 57 112 0 1385839232 11154528 14386976
15:16:37 54516 8192000000 10275913664 10718674944 1363863808 14.272 99 28 54 0 1382184496 10697056 7623632
15:19:18 94945 8192000000 10279621280 10736656384 1377444752 14.394 100 17 34 0 1392831776 11288832 4098192
15:22:18 26113 8192000000 10285661328 10738364416 1381978496 14.434 100 25 51 0 1394973328 7256592 5738240
15:23:46 74037 8192000000 10282344416 10752823296 1390890304 14.514 100 14 28 1 1402208016 7887168 3430544
15:26:02 44519 8192000000 10294524672 10757087232 1385073392 14.462 99 16 32 0 1398159728 9960656 3125680
15:27:48 50300 8192000000 10312096208 10766028800 1399635648 14.592 100 18 36 1 1413387152 9602528 4148976
15:29:39 30859 8192000000 10319834448 10782572544 1412551488 14.707 100 28 56 0 1432249968 12877392 6821088
15:31:18 64583 8192000000 10299580272 10774196224 1386143120 14.471 98 26 52 0 1404966272 12784496 6038656
15:33:23 44641 8192000000 10313906880 10757332992 1371272464 14.338 98 17 35 0 1384389072 9160880 3955728
15:35:09 45184 8192000000 10306966144 10760159232 1388840576 14.496 100 36 72 0 1408694752 11884016 7970160
15:36:56 37293 8192000000 10320995248 10773594112 1388808000 14.495 99 26 52 0 1410560640 15966448 5786192
15:38:48 119072 8192000000 10293070160 10750881792 1363257920 14.267 97 27 54 0 1381442480 12135536 6049024
15:42:44 56881 8192000000 10271896672 10739040256 1361292064 14.249 98 6 12 0 1371665296 9136176 1237056
15:44:41 58746 8192000000 10294210224 10755088384 1380108064 14.418 99 26 50 0 1394091312 8029520 5953728
15:46:42 27940 8192000000 10301039472 10758303744 1373650992 14.360 98 43 87 1 1391627472 7966384 10010096
15:48:14 61255 8192000000 10275982512 10720591872 1317488992 13.854 95 18 36 1 1336238240 14314848 4434400
15:50:24 114402 8192000000 10285181008 10746683392 1340343184 14.061 97 14 28 0 1364135104 20538432 3253488
15:53:18 66920 8192000000 10271758944 10734510080 1286170176 13.569 93 16 33 0 1306965664 17373920 3421568
15:55:34 33843 8192000000 10290830288 10733764608 1357417872 14.214 99 30 60 0 1386110000 21655568 7036560
15:57:09 65009 8192000000 10261923664 10733633536 1316240960 13.843 96 5 10 0 1338738528 21571952 925616
15:59:44 44556 8192000000 10269847536 10717495296 1342251792 14.078 98 21 40 0 1353726304 7662560 3811952
16:01:29 70251 8192000000 10259680128 10726748160 1338571264 14.045 98 14 28 0 1354667008 12420576 3675168
16:03:51 52730 8192000000 10265090320 10725736448 1352371136 14.169 99 15 31 0 1369316656 13517232 3428288
16:05:44 59304 8192000000 10272988704 10726875136 1358064528 14.220 99 10 20 0 1373087408 12900176 2122704
16:07:58 85131 8192000000 10265434064 10728763392 1345909536 14.111 97 13 26 0 1356410560 7817824 2683200
16:10:26 45977 8192000000 10283151280 10748784640 1365280080 14.285 98 14 28 0 1376146720 8283056 2583584
16:12:12 50192 8192000000 10295041024 10755874816 1391869760 14.523 100 29 58 0 1409209344 10517200 6822384
16:14:17 49525 8192000000 10308894784 10758991872 1372590544 14.350 98 26 52 0 1389284880 10479680 6214656
16:16:15 36192 8192000000 10301792496 10760990720 1390981824 14.515 100 31 60 1 1412644928 15200624 6462480
16:17:54 24498 8192000000 10315179792 10767663104 1399410032 14.590 100 33 66 0 1417345120 10812096 7122992
16:19:24 28282 8192000000 10312014592 10764197888 1387168416 14.481 99 24 48 0 1404743328 12440400 5134512
16:20:54 10117 8192000000 10327808784 10770534400 1402589040 14.618 100 50 100 0 1427738752 14159264 10990448
16:22:06 18123 8192000000 10325181408 10761527296 1385251648 14.463 99 59 114 1 1408145856 9577536 13316672
16:23:25 68050 8192000000 10307432608 10771472384 1398952672 14.586 100 33 66 1 1420373552 13838400 7582480
16:25:40 39265 8192000000 10294756592 10758172672 1370231520 14.329 98 13 24 0 1384587232 11593808 2761904
16:27:29 64347 8192000000 10261120160 10720518144 1326629504 13.937 96 10 20 1 1337795088 8837984 2327600
16:30:00 62131 8192000000 10271520976 10725101568 1310784016 13.793 95 15 28 1 1325389392 11357072 3248304
16:32:05 56365 8192000000 10297001824 10748407808 1373479552 14.358 100 22 45 0 1392565840 14141312 4944976
16:34:03 51979 8192000000 10288146304 10740613120 1364155696 14.275 99 17 34 1 1384706992 15808528 4742768
16:36:02 57850 8192000000 10279681472 10744279040 1379000448 14.408 100 20 40 0 1398832848 14375120 5457280
16:38:17 27177 8192000000 9230677552 9496166400 382876368 4.465 28 1 2 0 389512800 6594080 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-07-2022 16:39:41 to 08-07-2022 16:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-07-2022 16:39:58 to 08-07-2022 16:39:59.

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          239118
MON$OLDEST_ACTIVE               239119
MON$OLDEST_SNAPSHOT             239119
MON$NEXT_TRANSACTION            239119
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                       2016000
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Fri Jul  8 16:38:50 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Fri Jul  8 16:38:53 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
--- end of diff output ---

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

08.07.22 16:40:00. 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-07-08 16:40:03 No severe PSQL-related problems occured
08.07.22 16:40:04. End of report.