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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15812148 kB
MemAvailable 30909208 kB
Buffers 0 kB
Cached 15904604 kB
SwapCached 2512 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-08-15 07:37:19
DTS_END                         2022-08-15 07:37:19.5040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33198
TRN_ID                          223862
ATT_ID                          628
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: 8586.41 24472 1030369
customer order: creation 170.72 467 20486
customer order: refuse 14.62 382 1754
order to supplier: creation 45.28 1363 5434
order to supplier: removal 3.28 149935 394
invoice (draft): creation 46.18 3228 5542
invoice (draft): removal 48.15 978 5778
invoice accept: apply 40.45 25610 4854
invoice accept: cancel 42.76 1135 5131
customer reserve: creation 3753.52 245 450422
customer reserve: removal 4136.06 93 496327
realization accept: apply 82.93 251 9952
realization accept: cancel 71.12 161 8534
payment from customer: creation 60.73 162 7288
payment from customer: removal 5.24 150 629
payment to supplier: creation 49.48 200 5937
payment to supplier: removal 3.53 107 424
service: total inventory turnovers 4.22 3244 506
service: total monetary turnovers 7.08 432 849
service: aggregate perf. data 1.03 13530 124
service: refresh index statistics 0.03 287762 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2022 07:38:41 to 15-08-2022 07:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8116
TEST_TIME 2 6641
TEST_TIME 3 6211
TEST_TIME 4 6216
TEST_TIME 5 6115
TEST_TIME 6 5950
TEST_TIME 7 6084
TEST_TIME 8 6427
TEST_TIME 9 6679
TEST_TIME 10 6650
TEST_TIME 11 6909
TEST_TIME 12 7214
TEST_TIME 13 7172
TEST_TIME 14 7217
TEST_TIME 15 7152
TEST_TIME 16 7241
TEST_TIME 17 7536
TEST_TIME 18 7996
TEST_TIME 19 7968
TEST_TIME 20 8058
TEST_TIME 21 8187
TEST_TIME 22 8332
TEST_TIME 23 8146
TEST_TIME 24 8368
TEST_TIME 25 8445
TEST_TIME 26 8684
TEST_TIME 27 8861
TEST_TIME 28 9243
TEST_TIME 29 9665
TEST_TIME 30 10098
TEST_TIME 31 10782
TEST_TIME 32 11159
TEST_TIME 33 10942
TEST_TIME 34 10855
TEST_TIME 35 10870
TEST_TIME 36 10727
TEST_TIME 37 10531
TEST_TIME 38 10455
TEST_TIME 39 10347
TEST_TIME 40 10282
TEST_TIME 41 10156
TEST_TIME 42 10166
TEST_TIME 43 10049
TEST_TIME 44 9881
TEST_TIME 45 9831
TEST_TIME 46 9774
TEST_TIME 47 9662
TEST_TIME 48 9546
TEST_TIME 49 9482
TEST_TIME 50 9412
TEST_TIME 51 9312
TEST_TIME 52 9231
TEST_TIME 53 9364
TEST_TIME 54 9267
TEST_TIME 55 9183
TEST_TIME 56 9059
TEST_TIME 57 8989
TEST_TIME 58 8870
TEST_TIME 59 8867
TEST_TIME 60 8814
TEST_TIME 61 8812
TEST_TIME 62 8771
TEST_TIME 63 8827
TEST_TIME 64 8834
TEST_TIME 65 8962
TEST_TIME 66 8985
TEST_TIME 67 8993
TEST_TIME 68 8983
TEST_TIME 69 8977
TEST_TIME 70 8994
TEST_TIME 71 8992
TEST_TIME 72 8906
TEST_TIME 73 8891
TEST_TIME 74 8852
TEST_TIME 75 8803
TEST_TIME 76 8786
TEST_TIME 77 8739
TEST_TIME 78 8686
TEST_TIME 79 8718
TEST_TIME 80 8671
TEST_TIME 81 8643
TEST_TIME 82 8680
TEST_TIME 83 8675
TEST_TIME 84 8684
TEST_TIME 85 8736
TEST_TIME 86 8826
TEST_TIME 87 8789
TEST_TIME 88 8816
TEST_TIME 89 8785
TEST_TIME 90 8834
TEST_TIME 91 8828
TEST_TIME 92 8743
TEST_TIME 93 8726
TEST_TIME 94 8754
TEST_TIME 95 8681
TEST_TIME 96 8648
TEST_TIME 97 8676
TEST_TIME 98 8662
TEST_TIME 99 8641
TEST_TIME 100 8670
TEST_TIME 101 8731
TEST_TIME 102 8704
TEST_TIME 103 8721
TEST_TIME 104 8734
TEST_TIME 105 8720
TEST_TIME 106 8717
TEST_TIME 107 8789
TEST_TIME 108 8768
TEST_TIME 109 8774
TEST_TIME 110 8769
TEST_TIME 111 8803
TEST_TIME 112 8737
TEST_TIME 113 8737
TEST_TIME 114 8751
TEST_TIME 115 8695
TEST_TIME 116 8639
TEST_TIME 117 8630
TEST_TIME 118 8609
TEST_TIME 119 8604
TEST_TIME 120 8603

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-08-2022 07:38:44 to 15-08-2022 07:38:48.

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 1445524 1445524 0 0.00 0 15191 13 0 0
doc_list_biud 1445517 1445517 0 0.00 0 31478 37 0 0
sp_add_invoice_to_stock 4968 4854 114 2.29 38 193714 25611 0 114
sp_cancel_adding_invoice 5131 5131 0 0.00 5 75616 1136 0 0
sp_cancel_client_order 1754 1754 0 0.00 6 16700 383 0 0
sp_cancel_customer_reserve 496327 496327 0 0.00 1 21475 93 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 4 8893 150 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 7773 107 0 0
sp_cancel_supplier_invoice 5786 5778 8 0.13 2 28925 978 0 8
sp_cancel_supplier_order 394 394 0 0.00 7 1250198 149936 0 0
sp_cancel_write_off 8534 8534 0 0.00 2 10006 161 0 0
sp_client_order 20486 20486 0 0.00 5 32587 467 0 0
sp_customer_reserve 450422 450422 0 0.00 0 41836 245 0 0
sp_fill_shopping_cart 31448 31448 0 0.00 1 25908 84 0 0
sp_fill_shopping_cart_clo_res 450401 450401 0 0.00 0 12574 18 0 0
sp_get_clo_for_invoice 4829 4829 0 0.00 0 41673 337 0 0
sp_kill_cost_storno 14718 14718 0 0.00 0 15188 73 0 0
sp_kill_qstorno_ret_qs2qd 1007360 1007360 0 0.00 0 29629 41 0 0
sp_kill_qty_storno 532697 532697 0 0.00 0 31472 94 0 0
sp_lock_selected_doc 20525 20525 0 0.00 0 11981 9 0 0
sp_make_cost_storno 28005 28005 0 0.00 0 12180 79 0 0
sp_multiply_rows_for_pdistr 28004 28004 0 0.00 0 9455 35 0 0
sp_multiply_rows_for_qdistr 20485 20485 0 0.00 0 18617 194 0 0
sp_pay_from_customer 7288 7288 0 0.00 5 17065 162 0 0
sp_pay_to_supplier 5937 5937 0 0.00 6 13161 201 0 0
sp_payment_common 13225 13225 0 0.00 2 6638 40 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 16689 305 0 0
sp_qd_handle_on_invoice_upd_sts 9959 9959 0 0.00 0 31466 458 0 0
sp_qd_handle_on_reserve_upd_sts 9951 9951 0 0.00 0 15881 49 0 0
sp_reserve_write_off 9952 9952 0 0.00 9 15912 252 0 0
sp_supplier_invoice 5542 5542 0 0.00 8 133472 3229 0 0
sp_supplier_order 5434 5434 0 0.00 7 51913 1364 0 0
srv_aggregate_perf_data 124 124 0 0.00 2293 223719 13531 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 768 134635 36786 0 0
srv_find_qd_qs_mism 981980 981980 0 0.00 0 14469 10 0 0
srv_make_invnt_saldo 506 506 0 0.00 41 55491 3244 0 0
srv_make_money_saldo 849 849 0 0.00 1 29790 432 0 0
srv_recalc_idx_stat 4 4 0 0.00 141897 445710 287762 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4857 4857 0 0.00 0 16816 815 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 7010 398 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 5193 30 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 7942 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 41676 83 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 297 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2184 34 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 84942 6272 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 7010 27 0 0
t$perf-norm:sp_client_order 20488 20488 0 0.00 0 8025 33 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 1949 16 0 0
t$perf-norm:sp_pay_from_customer 7289 7289 0 0.00 0 12428 20 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 8485 20 0 0
t$perf-norm:sp_reserve_write_off 9952 9952 0 0.00 0 41051 29 0 0
t$perf-norm:sp_supplier_invoice 5542 5542 0 0.00 0 4623 29 0 0
t$perf-norm:sp_supplier_order 5435 5435 0 0.00 0 11564 31 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 514 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 11171 166 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 1165 11 0 0
t$perf-norm:srv_make_money_saldo 849 849 0 0.00 0 337 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 178 50 0 0
v_add_invoice_to_stock 9880 9652 228 2.30 0 12543 30 0 228
v_all_customers 38120 38120 0 0.00 0 115 3 0 0
v_all_suppliers 30728 30728 0 0.00 0 90 4 0 0
v_all_wares 40964 40964 0 0.00 0 142 3 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 278 13 0 0
v_cancel_client_order 3508 3508 0 0.00 0 8785 16 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 2448 15 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 1 12157 26 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1129 11 0 0
v_cancel_supplier_invoice 828 812 16 1.93 0 1173 25 0 16
v_cancel_supplier_order 788 788 0 0.00 0 2102 13 0 0
v_cancel_write_off 2018 2018 0 0.00 0 9814 33 0 0
v_max_id_clo_ord 5429 5429 0 0.00 0 2349 5 0 0
v_max_id_clo_res 999 999 0 0.00 0 2524 13 0 0
v_max_id_ord_sup 5535 5535 0 0.00 0 6625 10 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 2353 10 0 0
v_max_non_paid_realizn 3619 3619 0 0.00 0 2750 8 0 0
v_min_id_clo_ord 5429 5429 0 0.00 0 3601 10 0 0
v_min_id_clo_res 999 999 0 0.00 0 3527 23 0 0
v_min_id_ord_sup 5535 5535 0 0.00 0 5405 9 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 5481 13 0 0
v_min_non_paid_realizn 7288 7288 0 0.00 0 6115 15 0 0
v_our_firm 10182 10182 0 0.00 0 88 4 0 0
v_reserve_write_off 19902 19902 0 0.00 0 10769 11 0 0
x_lock_depdocs_on_canc_invoice 10503 10503 0 0.00 0 14124 111 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 9515 346 0 0
x_make_qstorno_1000_1200 5434 5434 0 0.00 2 51902 1208 0 0
x_make_qstorno_1200_2000 5542 5542 0 0.00 4 133457 2969 0 0
x_make_qstorno_2100_3300 450422 450422 0 0.00 0 41825 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2022 07:38:49 to 15-08-2022 07:38:53.

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
05:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:23 40149 8192000000 9724839456 10023055360 793004576 8.825 100 92 183 0 823509664 8107008 22398080
05:10:08 20099 8192000000 9755557888 10066784256 830588976 9.205 100 87 174 0 865360352 13998176 20773200
05:11:34 14305 8192000000 9814550496 10137214976 877957344 9.679 100 87 174 0 920380416 22108560 20314512
05:12:52 7298 8192000000 9867095712 10202304512 923285520 10.128 100 87 168 0 980112704 38545840 18281344
05:14:01 3243 8192000000 9911198928 10257240064 960207664 10.491 100 90 177 1 1031423520 52468960 18746896
05:15:08 2129 8192000000 9974740048 10329419776 989312576 10.775 100 88 169 0 1095425904 88528192 17585136
05:16:13 6348 8192000000 9971720048 10335600640 1018041696 11.053 100 80 152 0 1101083104 68267024 14774384
05:17:21 873 8192000000 10008873232 10381324288 1046008448 11.322 100 89 173 0 1131116432 67262816 17845168
05:18:23 1343 8192000000 10042834320 10424066048 1061886192 11.475 100 95 190 0 1160183648 78907200 19390256
05:19:26 1116 8192000000 10039436000 10421940224 1072642880 11.577 100 88 166 1 1170345584 80330288 17372416
05:20:28 8843 8192000000 10042229600 10437603328 1076528320 11.614 100 69 134 1 1168019584 77920080 13571184
05:21:38 1216 8192000000 10048284944 10435272704 1084719648 11.692 100 92 170 2 1169570144 67671856 17178640
05:22:40 877 8192000000 10046528704 10436571136 1088042560 11.724 100 90 169 2 1166084176 60786368 17255248
05:23:42 1035 8192000000 10038012448 10430099456 1093311040 11.774 100 75 144 2 1159513264 50362336 15839888
05:24:44 1039 8192000000 10071145264 10461409280 1094925088 11.789 100 91 175 1 1190988112 78690720 17372304
05:25:46 1341 8192000000 10055215312 10445668352 1096703584 11.806 100 96 180 3 1179499376 64269568 18526224
05:26:48 1058 8192000000 10054460000 10444156928 1099346864 11.831 100 95 183 4 1176994384 58025520 19622000
05:27:51 1002 8192000000 10058603040 10447060992 1096793824 11.807 100 89 168 4 1182105264 66947280 18364160
05:28:52 6743 8192000000 10043969664 10447138816 1104094864 11.876 100 60 111 1 1171465344 56626432 10744048
05:30:00 1023 8192000000 10068023056 10457255936 1093400496 11.775 100 81 161 0 1186542544 76827136 16314912
05:31:02 955 8192000000 10025074768 10406682624 1067947872 11.532 100 91 173 0 1146832960 59558432 19326656
05:32:04 1472 8192000000 9987263792 10365210624 1040276704 11.267 98 65 112 3 1110775408 59072608 11426096
05:33:06 5483 8192000000 9959152560 10351607808 1053326528 11.393 100 33 59 0 1080522448 21427584 5768336
05:34:12 1003 8192000000 9986481040 10364342272 1052617104 11.386 100 89 164 0 1106088960 36853168 16618688
05:35:14 4375 8192000000 9956056096 10337886208 1016679216 11.040 99 58 114 1 1077016880 48605440 11732224
05:36:19 1469 8192000000 9989342480 10364755968 1016893360 11.042 99 88 166 2 1101860368 69484752 15482256
05:37:22 1586 8192000000 9972591936 10351468544 1033918672 11.206 99 75 139 2 1096560768 47933504 14708592
05:38:32 1305 8192000000 10013185488 10393600000 1059717776 11.454 100 85 167 1 1137171232 59948464 17504992
05:39:37 1515 8192000000 10044341984 10430894080 1068253536 11.535 100 90 175 1 1164217344 76922048 19041760
05:40:45 8634 8192000000 10053904496 10437316608 1077849712 11.627 100 72 134 1 1169132608 79145440 12137456
05:41:57 2966 8192000000 10078333376 10467897344 1097421424 11.813 100 76 150 0 1202135680 88817328 15896928
05:43:02 1072 8192000000 10116593328 10502152192 1109651456 11.929 100 90 175 1 1235197280 107610304 17935520
05:44:07 8674 8192000000 10141629168 10539700224 1099813776 11.836 99 67 132 0 1255402832 140851360 14737696
05:45:18 1180 8192000000 10155789728 10545012736 1127674352 12.099 100 97 182 3 1269428240 122825872 18928016
05:46:22 812 8192000000 10187863296 10579976192 1145229232 12.265 100 97 181 3 1303123472 138356432 19537808
05:47:27 7531 8192000000 10239598448 10639884288 1161970256 12.422 100 77 154 0 1353773472 175323424 16479792
05:48:38 2010 8192000000 10258573232 10658512896 1168808032 12.486 100 84 155 0 1370644704 184687776 17148896
05:49:44 1297 8192000000 10280938000 10683826176 1176208576 12.555 100 93 174 2 1391961968 197772512 17980880
05:50:48 1169 8192000000 10324627456 10724786176 1190796848 12.691 100 96 180 3 1434448064 223440576 20210640
05:51:51 1413 8192000000 10373213024 10775760896 1192932256 12.711 100 96 188 0 1485584496 271431440 21220800
05:52:56 761 8192000000 10414435824 10832818176 1197501840 12.753 100 95 190 0 1524492384 304604192 22386352
05:54:00 3451 8192000000 10437669904 10891669504 1204180416 12.815 100 83 159 0 1543080192 320373184 18526592
05:55:07 12089 8192000000 10458204320 10914377728 1202118384 12.796 100 68 136 0 1564650992 345794544 16738064
05:56:28 3413 8192000000 10429237600 10859655168 1185728528 12.644 99 47 89 1 1540770512 343750832 11291152
05:57:33 1402 8192000000 10460417568 10898604032 1205220480 12.825 100 75 145 1 1562836592 339887408 17728704
05:58:40 888 8192000000 10463017408 10905751552 1189432880 12.678 99 88 175 1 1570119616 360345568 20341168
05:59:45 12277 8192000000 10463952896 10889789440 1164300944 12.444 98 55 104 1 1550180144 372980288 12898912
06:01:00 1948 8192000000 10482388176 10933301248 1158262160 12.387 99 70 129 0 1583995280 409846448 15886672
06:02:06 4402 8192000000 10510462752 10956103680 1169515744 12.492 99 60 120 0 1610588416 425887488 15185184
06:03:12 13703 8192000000 10515272240 11001700352 1161828528 12.420 100 39 77 0 1621027168 448646128 10552512
06:04:34 9927 8192000000 10479709072 11011096576 1143244960 12.246 99 35 70 0 1615057280 462263456 9548864
06:05:51 14722 8192000000 10404261520 10908278784 1147709536 12.288 100 36 72 1 1553905376 396119904 10075936
06:07:09 16635 8192000000 10319577600 10841628672 1131816784 12.138 99 36 72 0 1457629136 316173408 9638944
06:08:31 30530 8192000000 10203119648 10682527744 1145790720 12.270 100 14 28 0 1303202464 154186576 3225168
06:10:09 92344 8192000000 10121097200 10604986368 1134848736 12.167 99 21 40 0 1228624464 89173424 4602304
06:12:47 42987 8192000000 10104513792 10578763776 1124240032 12.067 99 26 52 0 1182086176 52547008 5299136
06:16:17 54869 8192000000 10069329824 10509967360 1104256208 11.878 97 33 66 0 1145402848 34684560 6462080
06:18:13 48834 8192000000 10085035760 10526203904 1109613664 11.929 98 17 34 0 1170532720 57427408 3491648
06:20:07 134635 8192000000 10114397008 10562109440 1116337776 11.992 98 23 46 0 1141384320 20270096 4776448
06:24:07 73979 8192000000 10097674656 10549260288 1133437392 12.154 99 23 45 0 1190782208 52137296 5207520
06:26:43 50620 8192000000 10070739280 10532954112 1115388752 11.983 98 20 40 0 1171783584 52061760 4333072
06:28:38 21540 8192000000 10127441120 10565160960 1144714816 12.260 100 51 96 3 1237516304 81939968 10861520
06:30:00 49941 8192000000 10063616320 10574290944 1122971136 12.055 99 6 12 0 1184594320 60452928 1170256
06:31:59 62119 8192000000 10051138144 10518376448 1123916704 12.064 99 20 40 1 1153347936 25448160 3983072
06:34:01 26845 8192000000 10058197312 10514427904 1139882640 12.214 100 38 73 1 1174635472 25993232 8759600
06:35:45 75004 8192000000 10032946080 10510057472 1107673312 11.910 98 9 16 0 1148262160 38659472 1929376
06:38:02 126654 8192000000 10084710048 10563407872 1063458768 11.490 94 22 45 0 1140171232 71432400 5280064
06:41:17 102098 8192000000 10083865168 10560327680 1111580400 11.947 98 17 34 0 1181369728 66138192 3651136
06:44:00 52097 8192000000 10061567296 10503241728 1110617216 11.938 98 18 36 0 1129580352 15494992 3468144
06:46:06 38176 8192000000 10087206992 10542108672 1114365440 11.974 98 47 93 0 1188293552 64810160 9117952
06:47:45 29907 8192000000 10087414480 10503835648 1081922224 11.666 96 58 111 0 1144196624 50568160 11706240
06:49:16 84576 8192000000 10102143040 10551259136 1117460704 12.003 100 59 118 1 1212744448 82894160 12389584
06:51:54 83622 8192000000 10050232112 10511335424 1107335904 11.907 98 8 16 0 1159171312 49820256 2015152
06:54:41 55655 8192000000 10066641216 10522808320 1094314704 11.784 97 12 22 0 1145492688 48539088 2638896
06:56:42 50359 8192000000 10096212816 10523176960 1112435760 11.955 98 34 63 1 1173756912 54708800 6612352
06:58:38 47327 8192000000 10114801376 10578788352 1090610224 11.748 96 21 43 0 1180934576 85412416 4911936
07:00:26 28912 8192000000 10130449120 10566787072 1135475216 12.173 100 32 64 0 1223791744 81313360 7003168
07:01:55 75501 8192000000 10106391440 10571845632 1125760960 12.081 98 25 51 0 1252003264 120550592 5691712
07:04:15 37045 8192000000 10085545152 10518523904 1108729312 11.920 98 33 66 1 1153243696 38027088 6487296
07:05:56 34389 8192000000 10101846928 10540859392 1100877696 11.846 98 35 70 1 1159048704 51019952 7151056
07:07:35 50984 8192000000 10096080512 10545070080 1094312944 11.784 97 30 60 1 1191994064 91086128 6594992
07:09:52 41433 8192000000 10077030240 10532769792 1104003744 11.876 98 54 106 2 1168888800 53324512 11560544
07:11:59 72709 8192000000 10079469952 10591723520 1127686304 12.100 100 5 10 0 1172372080 43583296 1102480
07:14:14 79511 8192000000 10088517056 10528358400 1114778848 11.978 98 27 52 1 1173922000 53274384 5868768
07:16:35 23538 8192000000 10118408752 10547032064 1122947056 12.055 99 40 74 0 1193245248 62129168 8169024
07:18:02 69760 8192000000 10053482896 10522001408 1096278448 11.802 97 17 35 0 1173477136 73416720 3781968
07:20:14 34383 8192000000 10078764160 10523893760 1100818416 11.845 98 35 70 1 1156952496 48879152 7254928
07:21:49 32186 8192000000 10080178448 10519433216 1102453392 11.861 98 32 64 0 1151989360 43271504 6264464
07:23:27 65565 8192000000 10085442048 10603892736 1118764048 12.015 99 28 56 0 1216268960 91781488 5723424
07:25:48 19613 8192000000 10096203152 10521169920 1093552272 11.776 96 42 82 1 1144380128 42737952 8089904
07:27:08 6570 8192000000 10095210416 10511679488 1111577920 11.947 100 49 97 0 1188909616 67887168 9444528
07:28:17 121052 8192000000 10123887104 10580070400 1097004320 11.809 97 30 60 0 1184578208 81549728 6024160
07:31:46 62804 8192000000 10040888208 10518859776 1117764512 12.006 100 4 8 0 1160282160 41807824 709824
07:33:49 54558 8192000000 10039044912 10516471808 1105980432 11.894 98 19 33 0 1145210576 35535504 3694640
07:35:44 56026 8192000000 10082882304 10552115200 1111577216 11.947 99 14 28 0 1147824560 33135216 3112128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-08-2022 07:38:55 to 15-08-2022 07:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2022 07:39:12 to 15-08-2022 07:39:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          223706
MON$OLDEST_ACTIVE               223974
MON$OLDEST_SNAPSHOT             223974
MON$NEXT_TRANSACTION            223974
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-30 07:30:08.6030 GMT
MON$PAGES                       2046752
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:282: 
:283: mscs-test-161.budzdorov.ru    Mon Aug 15 07:37:20 2022
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Mon Aug 15 07:37:29 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:289: 
:290: 
:291: mscs-test-161.budzdorov.ru    Mon Aug 15 07:37:31 2022
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:293: 
:294: 
:295: mscs-test-161.budzdorov.ru    Mon Aug 15 07:37:31 2022
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:297: 
--- end of diff output ---

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

15.08.22 07:39:15. There are 3 logs in /var/tmp/logs-oltp40 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-08-15 07:39:17 No severe PSQL-related problems occured
15.08.22 07:39:18. End of report.