Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 19: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 15881208 kB
MemAvailable 30939880 kB
Buffers 0 kB
Cached 15857336 kB
SwapCached 2520 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-08-12 19:38:25
DTS_END                         2022-08-12 19:38:25.4060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36356
TRN_ID                          241565
ATT_ID                          683
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: 9432.05 19835 1131846
customer order: creation 172.88 339 20746
customer order: refuse 14.42 362 1730
order to supplier: creation 45.61 1234 5473
order to supplier: removal 3.57 113358 428
invoice (draft): creation 46.63 3016 5596
invoice (draft): removal 54.82 843 6578
invoice accept: apply 42.71 16734 5125
invoice accept: cancel 49.16 819 5899
customer reserve: creation 3873.68 169 464842
customer reserve: removal 4829.27 66 579513
realization accept: apply 85.23 168 10227
realization accept: cancel 84.83 108 10180
payment from customer: creation 59.53 121 7143
payment from customer: removal 5.71 86 685
payment to supplier: creation 48.95 126 5874
payment to supplier: removal 3.36 68 403
service: total inventory turnovers 4.60 1028 552
service: total monetary turnovers 6.34 27 761
service: aggregate perf. data 0.73 22549 87
service: refresh index statistics 0.03 235484 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-08-2022 19:40:34 to 12-08-2022 19:40:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4198
TEST_TIME 2 5644
TEST_TIME 3 5089
TEST_TIME 4 5395
TEST_TIME 5 5631
TEST_TIME 6 5420
TEST_TIME 7 5244
TEST_TIME 8 5741
TEST_TIME 9 5833
TEST_TIME 10 5974
TEST_TIME 11 6155
TEST_TIME 12 6269
TEST_TIME 13 6099
TEST_TIME 14 6322
TEST_TIME 15 6509
TEST_TIME 16 6699
TEST_TIME 17 6679
TEST_TIME 18 6758
TEST_TIME 19 6745
TEST_TIME 20 6874
TEST_TIME 21 7339
TEST_TIME 22 7611
TEST_TIME 23 7856
TEST_TIME 24 7963
TEST_TIME 25 8251
TEST_TIME 26 8540
TEST_TIME 27 8827
TEST_TIME 28 8925
TEST_TIME 29 9190
TEST_TIME 30 10839
TEST_TIME 31 11087
TEST_TIME 32 11213
TEST_TIME 33 10924
TEST_TIME 34 10760
TEST_TIME 35 10699
TEST_TIME 36 10529
TEST_TIME 37 10456
TEST_TIME 38 10289
TEST_TIME 39 10164
TEST_TIME 40 10162
TEST_TIME 41 10097
TEST_TIME 42 9977
TEST_TIME 43 9927
TEST_TIME 44 9816
TEST_TIME 45 9621
TEST_TIME 46 9657
TEST_TIME 47 9627
TEST_TIME 48 9485
TEST_TIME 49 9435
TEST_TIME 50 9424
TEST_TIME 51 9396
TEST_TIME 52 9367
TEST_TIME 53 9324
TEST_TIME 54 9177
TEST_TIME 55 9152
TEST_TIME 56 9230
TEST_TIME 57 9170
TEST_TIME 58 9228
TEST_TIME 59 9238
TEST_TIME 60 9243
TEST_TIME 61 9308
TEST_TIME 62 9304
TEST_TIME 63 9375
TEST_TIME 64 9347
TEST_TIME 65 9386
TEST_TIME 66 9393
TEST_TIME 67 9420
TEST_TIME 68 9484
TEST_TIME 69 9542
TEST_TIME 70 9556
TEST_TIME 71 9596
TEST_TIME 72 9514
TEST_TIME 73 9394
TEST_TIME 74 9505
TEST_TIME 75 9481
TEST_TIME 76 9419
TEST_TIME 77 9463
TEST_TIME 78 9386
TEST_TIME 79 9435
TEST_TIME 80 9382
TEST_TIME 81 9342
TEST_TIME 82 9314
TEST_TIME 83 9287
TEST_TIME 84 9311
TEST_TIME 85 9289
TEST_TIME 86 9257
TEST_TIME 87 9357
TEST_TIME 88 9350
TEST_TIME 89 9370
TEST_TIME 90 9360
TEST_TIME 91 9396
TEST_TIME 92 9396
TEST_TIME 93 9449
TEST_TIME 94 9463
TEST_TIME 95 9438
TEST_TIME 96 9499
TEST_TIME 97 9471
TEST_TIME 98 9385
TEST_TIME 99 9390
TEST_TIME 100 9390
TEST_TIME 101 9349
TEST_TIME 102 9304
TEST_TIME 103 9292
TEST_TIME 104 9292
TEST_TIME 105 9219
TEST_TIME 106 9245
TEST_TIME 107 9206
TEST_TIME 108 9279
TEST_TIME 109 9255
TEST_TIME 110 9250
TEST_TIME 111 9294
TEST_TIME 112 9356
TEST_TIME 113 9347
TEST_TIME 114 9343
TEST_TIME 115 9391
TEST_TIME 116 9397
TEST_TIME 117 9459
TEST_TIME 118 9446
TEST_TIME 119 9490
TEST_TIME 120 9433

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 19:40:38 to 12-08-2022 19:40:41.

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 1561446 1561446 0 0.00 0 15647 6 0 0
doc_list_biud 1561444 1561444 0 0.00 0 33255 31 0 0
sp_add_invoice_to_stock 5199 5125 74 1.42 24 167822 16734 0 74
sp_cancel_adding_invoice 5899 5899 0 0.00 5 67543 820 0 0
sp_cancel_client_order 1730 1730 0 0.00 4 11684 363 0 0
sp_cancel_customer_reserve 579513 579513 0 0.00 0 33257 67 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 2 7131 87 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 1915 69 0 0
sp_cancel_supplier_invoice 6585 6578 7 0.10 2 32588 844 0 7
sp_cancel_supplier_order 428 428 0 0.00 8 1154252 113359 0 0
sp_cancel_write_off 10180 10180 0 0.00 0 10428 109 0 0
sp_client_order 20746 20746 0 0.00 3 21204 339 0 0
sp_customer_reserve 464842 464842 0 0.00 0 44073 169 0 0
sp_fill_shopping_cart 31812 31812 0 0.00 0 13563 40 0 0
sp_fill_shopping_cart_clo_res 464838 464838 0 0.00 0 12491 13 0 0
sp_get_clo_for_invoice 5121 5121 0 0.00 0 32801 293 0 0
sp_kill_cost_storno 17167 17167 0 0.00 0 9833 56 0 0
sp_kill_qstorno_ret_qs2qd 1176212 1176212 0 0.00 0 33203 36 0 0
sp_kill_qty_storno 619675 619675 0 0.00 0 33255 76 0 0
sp_lock_selected_doc 21099 21099 0 0.00 0 7300 6 0 0
sp_make_cost_storno 28365 28365 0 0.00 0 14989 61 0 0
sp_multiply_rows_for_pdistr 28364 28364 0 0.00 0 6909 25 0 0
sp_multiply_rows_for_qdistr 20746 20746 0 0.00 0 21192 154 0 0
sp_pay_from_customer 7143 7143 0 0.00 4 13300 122 0 0
sp_pay_to_supplier 5874 5874 0 0.00 4 14995 127 0 0
sp_payment_common 13017 13017 0 0.00 1 10520 30 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 11678 324 0 0
sp_qd_handle_on_invoice_upd_sts 11019 11019 0 0.00 0 30727 332 0 0
sp_qd_handle_on_reserve_upd_sts 10227 10227 0 0.00 0 9494 50 0 0
sp_reserve_write_off 10227 10227 0 0.00 6 15686 168 0 0
sp_supplier_invoice 5596 5596 0 0.00 6 73161 3016 0 0
sp_supplier_order 5473 5473 0 0.00 4 44484 1234 0 0
srv_aggregate_perf_data 87 87 0 0.00 3167 211763 22549 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1274 146428 46747 0 0
srv_find_qd_qs_mism 1083511 1083511 0 0.00 0 14937 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 38 31881 1028 0 0
srv_make_money_saldo 761 761 0 0.00 0 1746 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 152534 324616 235485 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5125 5125 0 0.00 0 15195 145 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 4246 109 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 7366 25 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1512 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1128 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 2144 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1530 15 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 24328 1473 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 474 3 0 0
t$perf-norm:sp_client_order 20746 20746 0 0.00 0 27568 16 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 534 3 0 0
t$perf-norm:sp_pay_from_customer 7143 7143 0 0.00 0 8972 10 0 0
t$perf-norm:sp_pay_to_supplier 5874 5874 0 0.00 0 8577 13 0 0
t$perf-norm:sp_reserve_write_off 10227 10227 0 0.00 0 9605 10 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 10051 21 0 0
t$perf-norm:sp_supplier_order 5473 5473 0 0.00 0 4769 13 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 269 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 14596 42 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 2893 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 6 0 0
v_add_invoice_to_stock 10388 10240 148 1.42 0 4431 19 0 148
v_all_customers 37372 37372 0 0.00 0 19 0 0 0
v_all_suppliers 30508 30508 0 0.00 0 16 1 0 0
v_all_wares 41492 41492 0 0.00 0 63 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 8700 16 0 0
v_cancel_client_order 3460 3460 0 0.00 0 4727 11 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 5344 12 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1182 7 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1750 6 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 922 14 0 14
v_cancel_supplier_order 856 856 0 0.00 0 613 3 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4446 17 0 0
v_max_id_clo_ord 5472 5472 0 0.00 0 420 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 2536 22 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 4312 3 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 1425 3 0 0
v_max_non_paid_realizn 3938 3938 0 0.00 0 4480 4 0 0
v_min_id_clo_ord 5472 5472 0 0.00 0 7825 16 0 0
v_min_id_clo_res 974 974 0 0.00 0 6069 21 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 4415 7 0 0
v_min_non_paid_invoice 5874 5874 0 0.00 0 7694 19 0 0
v_min_non_paid_realizn 7143 7143 0 0.00 0 8248 24 0 0
v_our_firm 10530 10530 0 0.00 0 13 1 0 0
v_reserve_write_off 20454 20454 0 0.00 0 5031 7 0 0
x_lock_depdocs_on_canc_invoice 12069 12069 0 0.00 0 10362 116 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 5410 334 0 0
x_make_qstorno_1000_1200 5473 5473 0 0.00 1 42767 1157 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 3 72647 2869 0 0
x_make_qstorno_2100_3300 464842 464842 0 0.00 0 44067 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 19:40:42 to 12-08-2022 19:40:46.

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
17:08:29 8 8192000000 8801882576 8814104576 7908448 0.096 2 2 3 0 8049536 66144 74944
17:09:30 20932 8192000000 9904257856 10212044800 982036416 10.704 100 88 176 0 1011880832 6306016 23538400
17:10:56 16542 8192000000 9953423648 10275921920 1035975904 11.226 100 90 180 0 1067808944 8413712 23419328
17:12:18 6541 8192000000 9971901680 10306252800 1061480432 11.471 100 80 159 1 1092185104 9499248 21205424
17:13:30 9097 8192000000 10014655056 10362822656 1106006960 11.895 100 75 148 3 1134571664 10172640 18392064
17:14:42 5530 8192000000 10079345472 10432970752 1167530928 12.474 100 89 178 0 1200499872 11953664 21015280
17:15:50 3290 8192000000 10117094400 10485157888 1207355136 12.845 100 75 150 0 1236495872 11317872 17822864
17:16:55 2360 8192000000 10154545360 10529599488 1246847616 13.209 100 80 156 1 1276506176 12694352 16964208
17:17:59 2368 8192000000 10180185504 10564116480 1272274016 13.442 100 80 159 0 1302667888 12825136 17568736
17:19:04 1406 8192000000 10209086400 10595303424 1294733184 13.647 100 80 159 0 1324623264 12720912 17169168
17:20:07 17211 8192000000 10207290608 10606616576 1304118960 13.733 100 50 100 0 1323523856 8034976 11369920
17:21:25 2022 8192000000 10204739792 10614054912 1313468288 13.818 100 44 88 0 1333033120 9430832 10134000
17:22:29 11124 8192000000 10207881584 10620370944 1317468032 13.854 100 27 55 0 1329982896 5969184 6545680
17:23:45 13960 8192000000 10206813312 10625826816 1323631456 13.910 100 33 65 0 1338539424 6642032 8265936
17:25:06 14461 8192000000 10213612512 10640179200 1326530128 13.936 100 23 45 1 1337899808 6227984 5141696
17:26:22 4626 8192000000 10222843072 10640207872 1317336112 13.853 99 28 57 0 1330275296 6789760 6149424
17:27:27 8363 8192000000 10217265376 10640388096 1328259168 13.951 100 16 32 2 1338049552 5980960 3809424
17:28:37 6119 8192000000 10215510208 10629582848 1317074672 13.850 100 22 44 2 1328873632 6741440 5057520
17:29:43 21959 8192000000 10176881040 10594643968 1258351408 13.315 96 29 57 1 1270098288 5213664 6533216
17:31:05 8219 8192000000 10127029296 10523844608 1197310160 12.751 95 22 42 0 1207603520 5025296 5268064
17:32:14 5563 8192000000 10145136080 10543566848 1249286944 13.232 100 24 48 1 1259997504 5299808 5410752
17:33:24 12365 8192000000 10152907760 10552926208 1219979552 12.961 96 33 66 1 1231962496 4681008 7301936
17:34:38 12631 8192000000 10149067504 10555027456 1259861520 13.329 100 22 43 0 1270618960 5227920 5529520
17:35:54 5756 8192000000 10165470288 10575716352 1279017856 13.504 100 17 34 0 1288913536 5676848 4218832
17:37:03 5757 8192000000 10189592640 10603286528 1298901040 13.685 100 26 52 0 1309910784 4761120 6248624
17:38:12 13744 8192000000 10208629888 10626555904 1320093392 13.878 100 17 34 0 1328561072 4636032 3831648
17:39:43 102001 8192000000 10252480144 10673860608 1344888640 14.101 100 33 64 2 1361996400 9717888 7389872
17:42:28 2116 8192000000 10284592496 10700988416 1378752880 14.405 100 72 138 0 1405953040 12142240 15057920
17:43:34 19137 8192000000 10291420096 10713124864 1377566736 14.395 100 58 112 0 1404838224 14514240 12757248
17:44:56 13978 8192000000 10301802320 10725416960 1392831728 14.531 100 40 80 0 1415947552 13846576 9269248
17:46:16 15900 8192000000 10314893504 10747125760 1401008960 14.604 100 35 70 1 1428307424 19164112 8134352
17:47:36 11387 8192000000 10335308448 10758021120 1410490400 14.688 100 68 131 0 1446939776 20393536 16055840
17:48:56 1636 8192000000 10339254048 10762698752 1407311376 14.660 99 66 130 1 1446640864 21876688 17452800
17:50:06 6524 8192000000 10346603824 10765942784 1417969968 14.755 100 61 121 2 1454641472 21910768 14760736
17:51:24 11310 8192000000 10335335152 10750447616 1397048128 14.569 100 67 133 0 1446510640 32670560 16791952
17:52:40 2552 8192000000 10335208608 10750922752 1384196048 14.454 98 64 127 2 1438894448 38074880 16623520
17:53:46 4831 8192000000 10347500752 10762498048 1388862736 14.496 99 65 127 2 1447045056 41463968 16718352
17:55:02 2413 8192000000 10373155856 10789806080 1418234976 14.757 100 66 132 1 1480117168 45047296 16834896
17:56:10 6388 8192000000 10355339600 10776084480 1383047728 14.444 98 47 93 0 1446990448 51240976 12701744
17:57:23 1273 8192000000 10381145216 10791227392 1410157600 14.685 100 82 162 0 1483782176 52755312 20869264
17:58:29 10018 8192000000 10370270192 10783141888 1396833408 14.567 100 64 128 0 1474520800 60251872 17435520
17:59:47 3213 8192000000 10374588544 10787946496 1397840736 14.576 100 65 130 0 1478947520 63709552 17397232
18:00:53 1425 8192000000 10387754368 10803126272 1405443376 14.643 100 60 119 1 1487956352 65930144 16582832
18:01:59 9758 8192000000 10366676560 10785173504 1389559056 14.502 100 45 88 0 1468123456 66144560 12419840
18:03:17 11651 8192000000 10358873184 10784825344 1388257712 14.490 100 42 82 0 1463763808 64110256 11395840
18:04:46 29945 8192000000 10351457552 10788507648 1382835760 14.442 100 32 64 0 1464959168 73065952 9057456
18:06:27 32972 8192000000 10329652272 10775527424 1347967744 14.129 99 25 48 0 1428587104 73230336 7389024
18:08:04 44225 8192000000 10280056192 10720583680 1289670976 13.601 95 16 32 1 1355197040 60877200 4648864
18:09:51 98985 8192000000 10271979280 10715701248 1326339024 13.934 98 11 22 0 1344516016 15736160 2440832
18:12:40 8370 8192000000 10272393968 10705821696 1342040752 14.076 98 49 95 0 1361834704 9278368 10515584
18:13:50 60645 8192000000 10268394704 10715148288 1351476000 14.161 99 15 30 0 1365947792 10673264 3798528
18:15:51 120871 8192000000 10282145056 10734772224 1365607360 14.288 99 26 52 0 1380806400 10009728 5189312
18:18:53 99119 8192000000 10293855728 10740432896 1352243328 14.168 98 14 29 0 1365623488 10203536 3176624
18:21:44 69088 8192000000 10278285920 10728321024 1357927920 14.219 98 11 22 0 1370280400 9515216 2837264
18:23:53 137086 8192000000 10292737280 10762747904 1379614544 14.413 99 19 38 0 1396200320 12361264 4224512
18:28:06 25212 8192000000 10320926544 10767597568 1408855504 14.674 100 46 92 0 1428545296 8639856 11049936
18:29:49 39049 8192000000 10306081680 10770685952 1409697408 14.681 100 19 38 1 1421530768 7395808 4437552
18:32:05 72084 8192000000 10309739168 10773729280 1386695216 14.476 98 16 32 0 1401396784 11074752 3626816
18:34:37 44931 8192000000 10295544368 10765930496 1363650848 14.270 97 7 12 0 1375498832 10476576 1371408
18:36:31 15631 8192000000 10302113216 10754334720 1363692640 14.270 97 39 78 0 1381731952 8748992 9290320
18:37:48 40356 8192000000 10304216368 10764849152 1379742064 14.414 99 24 48 1 1394627776 9500976 5384736
18:39:29 35405 8192000000 10284016608 10737635328 1336814336 14.029 96 19 38 0 1352733200 11865936 4052928
18:41:05 99424 8192000000 10291732592 10738475008 1283037136 13.541 92 28 56 0 1300262736 11377024 5848576
18:43:50 24518 8192000000 10255764656 10689286144 1337381200 14.034 98 20 41 0 1350668208 8963776 4323232
18:45:16 42031 8192000000 10272425968 10716160000 1320672832 13.883 97 18 36 0 1335355408 10543648 4138928
18:46:59 105961 8192000000 10267825936 10715860992 1301958096 13.713 95 10 20 0 1321242400 17105552 2178752
18:49:53 53833 8192000000 10297330624 10738724864 1366366016 14.294 99 25 50 0 1384634128 12857552 5410560
18:51:48 68709 8192000000 10266458400 10723037184 1320872144 13.885 96 25 50 2 1337958000 11215008 5870848
18:53:57 45483 8192000000 10261902400 10711367680 1336874608 14.029 98 28 56 0 1357629072 14541792 6212672
18:55:43 86509 8192000000 10253550880 10720149504 1300018224 13.695 95 9 18 0 1318996304 16798720 2179360
18:58:18 52907 8192000000 10233040464 10679504896 1304393104 13.735 96 19 38 1 1319079936 10617728 4069104
19:00:17 70973 8192000000 10258509168 10712473600 1345283824 14.105 99 11 22 0 1355011856 7342704 2385328
19:02:32 26720 8192000000 10247977792 10692849664 1332789024 13.992 99 32 61 0 1347346560 7796000 6761536
19:04:00 47879 8192000000 10259711136 10707005440 1330193520 13.969 98 26 52 1 1349204048 12859488 6151040
19:05:53 90325 8192000000 10267873552 10725183488 1344914368 14.102 98 11 22 0 1358725184 11474608 2336208
19:08:28 76954 8192000000 10296282880 10752368640 1373802448 14.361 99 13 24 0 1392157568 15685072 2670048
19:10:45 43571 8192000000 10282895248 10737680384 1369132384 14.319 99 27 54 0 1391176624 16269552 5774688
19:12:29 68311 8192000000 10309928736 10746720256 1377928656 14.398 99 28 56 1 1397270016 13478976 5862384
19:14:40 146423 8192000000 10286850112 10737971200 1349503744 14.143 97 14 29 0 1366642896 13510896 3628256
19:18:26 52593 8192000000 10281166144 10737651712 1356307920 14.204 98 18 36 0 1371766816 10717376 4741520
19:20:22 66946 8192000000 10281205888 10745946112 1346953408 14.120 97 15 29 0 1361518640 11163920 3401312
19:22:55 59025 8192000000 10293406032 10753507328 1385712912 14.468 100 14 28 0 1398883440 9641200 3529328
19:24:54 89922 8192000000 10292813920 10761412608 1368746752 14.316 98 10 20 0 1391030480 19857360 2426368
19:27:27 23102 8192000000 10290409504 10734354432 1353888224 14.182 98 44 88 0 1374672624 10973472 9810928
19:28:55 55411 8192000000 10275623104 10741215232 1360804736 14.245 99 21 42 0 1380647712 14803296 5039680
19:30:53 32571 8192000000 10280699664 10728079360 1341415376 14.070 97 22 44 0 1356877456 10580704 4881376
19:32:30 19202 8192000000 10293334000 10742890496 1355982624 14.201 98 28 56 0 1374736992 12818736 5935632
19:33:51 99032 8192000000 10257190656 10699497472 1283987424 13.549 94 12 24 0 1296236736 9683968 2565344
19:36:36 118119 8192000000 9744982416 10119311360 854997392 9.450 63 12 24 0 867667776 10457904 2212480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-08-2022 19:40:48 to 12-08-2022 19:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2022 19:41:04 to 12-08-2022 19:41:06.

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          241503
MON$OLDEST_ACTIVE               241775
MON$OLDEST_SNAPSHOT             241775
MON$NEXT_TRANSACTION            241775
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                       2036256
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:147: 
:148: mscs-test-161.budzdorov.ru    Fri Aug 12 19:38:56 2022
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:150: 
--- end of diff output ---

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

12.08.22 19:41:07. 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-08-12 19:41:09 No severe PSQL-related problems occured
12.08.22 19:41:11. End of report.