Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.04.2022 04:37
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 16325936 kB
MemAvailable 31485560 kB
Buffers 20 kB
Cached 15295732 kB
SwapCached 3208 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-04-28 04:37:16
DTS_END                         2022-04-28 04:37:16.5750
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/47360
TRN_ID                          209643
ATT_ID                          585
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: 8588.90 29238 1030668
customer order: creation 158.08 489 18970
customer order: refuse 13.73 414 1648
order to supplier: creation 41.96 1437 5035
order to supplier: removal 3.12 168085 374
invoice (draft): creation 41.25 3156 4950
invoice (draft): removal 47.82 960 5738
invoice accept: apply 38.81 28558 4657
invoice accept: cancel 43.20 1235 5184
customer reserve: creation 3692.33 266 443079
customer reserve: removal 4238.03 98 508564
realization accept: apply 76.72 280 9207
realization accept: cancel 76.58 174 9189
payment from customer: creation 53.99 189 6479
payment from customer: removal 5.23 123 628
payment to supplier: creation 43.39 187 5207
payment to supplier: removal 3.13 126 375
service: total inventory turnovers 3.51 4228 421
service: total monetary turnovers 7.18 368 862
service: aggregate perf. data 0.81 20264 97
service: refresh index statistics 0.03 354114 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-04-2022 04:38:01 to 28-04-2022 04:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4105
TEST_TIME 2 4886
TEST_TIME 3 5152
TEST_TIME 4 6017
TEST_TIME 5 6071
TEST_TIME 6 5874
TEST_TIME 7 5947
TEST_TIME 8 6355
TEST_TIME 9 6386
TEST_TIME 10 6446
TEST_TIME 11 6802
TEST_TIME 12 6905
TEST_TIME 13 7032
TEST_TIME 14 7049
TEST_TIME 15 7289
TEST_TIME 16 7346
TEST_TIME 17 7349
TEST_TIME 18 7322
TEST_TIME 19 7786
TEST_TIME 20 7814
TEST_TIME 21 8379
TEST_TIME 22 8302
TEST_TIME 23 8164
TEST_TIME 24 8311
TEST_TIME 25 8474
TEST_TIME 26 8790
TEST_TIME 27 8898
TEST_TIME 28 9240
TEST_TIME 29 9645
TEST_TIME 30 9774
TEST_TIME 31 9949
TEST_TIME 32 10065
TEST_TIME 33 10669
TEST_TIME 34 10913
TEST_TIME 35 10677
TEST_TIME 36 10722
TEST_TIME 37 10438
TEST_TIME 38 10252
TEST_TIME 39 10125
TEST_TIME 40 10057
TEST_TIME 41 10055
TEST_TIME 42 9943
TEST_TIME 43 9820
TEST_TIME 44 9677
TEST_TIME 45 9540
TEST_TIME 46 9491
TEST_TIME 47 9504
TEST_TIME 48 9502
TEST_TIME 49 9489
TEST_TIME 50 9373
TEST_TIME 51 9226
TEST_TIME 52 9457
TEST_TIME 53 9360
TEST_TIME 54 9233
TEST_TIME 55 9190
TEST_TIME 56 9104
TEST_TIME 57 9030
TEST_TIME 58 8966
TEST_TIME 59 9018
TEST_TIME 60 8959
TEST_TIME 61 9003
TEST_TIME 62 8920
TEST_TIME 63 8941
TEST_TIME 64 8873
TEST_TIME 65 8874
TEST_TIME 66 8829
TEST_TIME 67 8861
TEST_TIME 68 8908
TEST_TIME 69 8983
TEST_TIME 70 8952
TEST_TIME 71 8964
TEST_TIME 72 8994
TEST_TIME 73 8994
TEST_TIME 74 9013
TEST_TIME 75 8994
TEST_TIME 76 9015
TEST_TIME 77 8979
TEST_TIME 78 8874
TEST_TIME 79 8826
TEST_TIME 80 8792
TEST_TIME 81 8742
TEST_TIME 82 8735
TEST_TIME 83 8694
TEST_TIME 84 8699
TEST_TIME 85 8722
TEST_TIME 86 8663
TEST_TIME 87 8634
TEST_TIME 88 8606
TEST_TIME 89 8703
TEST_TIME 90 8694
TEST_TIME 91 8712
TEST_TIME 92 8685
TEST_TIME 93 8705
TEST_TIME 94 8726
TEST_TIME 95 8689
TEST_TIME 96 8725
TEST_TIME 97 8752
TEST_TIME 98 8757
TEST_TIME 99 8736
TEST_TIME 100 8758
TEST_TIME 101 8771
TEST_TIME 102 8740
TEST_TIME 103 8710
TEST_TIME 104 8677
TEST_TIME 105 8671
TEST_TIME 106 8630
TEST_TIME 107 8612
TEST_TIME 108 8580
TEST_TIME 109 8547
TEST_TIME 110 8536
TEST_TIME 111 8529
TEST_TIME 112 8499
TEST_TIME 113 8490
TEST_TIME 114 8543
TEST_TIME 115 8532
TEST_TIME 116 8551
TEST_TIME 117 8538
TEST_TIME 118 8588
TEST_TIME 119 8670
TEST_TIME 120 8612

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-04-2022 04:38:05 to 28-04-2022 04:38:08.

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 1436585 1436585 0 0.00 0 40249 14 0 0
doc_list_biud 1436579 1436579 0 0.00 0 61655 40 0 0
sp_add_invoice_to_stock 4781 4657 124 2.59 44 252726 28559 0 124
sp_cancel_adding_invoice 5184 5184 0 0.00 6 77013 1235 0 0
sp_cancel_client_order 1648 1648 0 0.00 6 14333 415 0 0
sp_cancel_customer_reserve 508564 508564 0 0.00 1 61659 98 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 4 4882 123 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 5226 126 0 0
sp_cancel_supplier_invoice 5755 5738 17 0.29 3 16052 960 0 17
sp_cancel_supplier_order 374 374 0 0.00 7 1135108 168085 0 0
sp_cancel_write_off 9189 9189 0 0.00 1 15195 175 0 0
sp_client_order 18970 18970 0 0.00 6 63008 489 0 0
sp_customer_reserve 443079 443079 0 0.00 1 88967 267 0 0
sp_fill_shopping_cart 28937 28937 0 0.00 1 20763 81 0 0
sp_fill_shopping_cart_clo_res 443051 443051 0 0.00 0 18412 20 0 0
sp_get_clo_for_invoice 4617 4617 0 0.00 0 26941 369 0 0
sp_kill_cost_storno 15376 15376 0 0.00 0 40242 78 0 0
sp_kill_qstorno_ret_qs2qd 1032429 1032429 0 0.00 0 61648 44 0 0
sp_kill_qty_storno 544520 544520 0 0.00 0 61655 99 0 0
sp_lock_selected_doc 19175 19175 0 0.00 0 7642 8 0 0
sp_make_cost_storno 25510 25510 0 0.00 0 18567 88 0 0
sp_multiply_rows_for_pdistr 25509 25509 0 0.00 0 10213 40 0 0
sp_multiply_rows_for_qdistr 18969 18969 0 0.00 0 62979 182 0 0
sp_pay_from_customer 6479 6479 0 0.00 5 14964 189 0 0
sp_pay_to_supplier 5207 5207 0 0.00 6 15518 187 0 0
sp_payment_common 11686 11686 0 0.00 2 14918 45 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 14324 337 0 0
sp_qd_handle_on_invoice_upd_sts 9800 9800 0 0.00 0 60489 491 0 0
sp_qd_handle_on_reserve_upd_sts 9207 9207 0 0.00 0 13084 64 0 0
sp_reserve_write_off 9207 9207 0 0.00 8 18460 280 0 0
sp_supplier_invoice 4950 4950 0 0.00 9 69576 3156 0 0
sp_supplier_order 5035 5035 0 0.00 7 39720 1438 0 0
srv_aggregate_perf_data 97 97 0 0.00 2581 303440 20264 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 855 137629 39980 0 0
srv_find_qd_qs_mism 984222 984222 0 0.00 0 12981 11 0 0
srv_make_invnt_saldo 421 421 0 0.00 69 74498 4229 0 0
srv_make_money_saldo 862 862 0 0.00 2 10619 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 124926 729630 354115 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4661 4661 0 0.00 0 10153 934 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 2 7002 552 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 12433 41 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 649 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 985 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 10226 40 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 737 30 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 92248 6588 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 1201 19 0 0
t$perf-norm:sp_client_order 18970 18970 0 0.00 0 7841 36 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 473 12 0 0
t$perf-norm:sp_pay_from_customer 6479 6479 0 0.00 0 3458 18 0 0
t$perf-norm:sp_pay_to_supplier 5207 5207 0 0.00 0 10753 19 0 0
t$perf-norm:sp_reserve_write_off 9207 9207 0 0.00 0 39319 24 0 0
t$perf-norm:sp_supplier_invoice 4951 4951 0 0.00 0 3474 33 0 0
t$perf-norm:sp_supplier_order 5035 5035 0 0.00 0 2241 32 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 184 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 17662 286 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 1150 15 0 0
t$perf-norm:srv_make_money_saldo 862 862 0 0.00 0 1565 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 183 51 0 0
v_add_invoice_to_stock 9468 9220 248 2.61 0 4755 25 0 248
v_all_customers 34955 34955 0 0.00 0 62 3 0 0
v_all_suppliers 27636 27636 0 0.00 0 63 4 0 0
v_all_wares 37932 37932 0 0.00 0 153 4 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 2095 15 0 0
v_cancel_client_order 3296 3296 0 0.00 0 3535 12 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 4798 14 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 4707 14 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 1562 14 0 0
v_cancel_supplier_invoice 778 744 34 4.37 0 4699 30 0 34
v_cancel_supplier_order 748 748 0 0.00 0 2544 14 0 0
v_cancel_write_off 1840 1840 0 0.00 0 7155 26 0 0
v_max_id_clo_ord 5033 5033 0 0.00 0 4909 6 0 0
v_max_id_clo_res 933 933 0 0.00 0 2751 18 0 0
v_max_id_ord_sup 4938 4938 0 0.00 0 6709 7 0 0
v_max_non_paid_invoice 1360 1360 0 0.00 0 5785 11 0 0
v_max_non_paid_realizn 3281 3281 0 0.00 0 13291 11 0 0
v_min_id_clo_ord 5033 5033 0 0.00 0 380 6 0 0
v_min_id_clo_res 933 933 0 0.00 0 3348 14 0 0
v_min_id_ord_sup 4938 4938 0 0.00 0 5448 9 0 0
v_min_non_paid_invoice 5207 5207 0 0.00 0 5369 13 0 0
v_min_non_paid_realizn 6478 6478 0 0.00 0 14746 19 0 0
v_our_firm 9372 9372 0 0.00 0 63 4 0 0
v_reserve_write_off 18412 18412 0 0.00 0 4797 10 0 0
x_lock_depdocs_on_canc_invoice 10550 10550 0 0.00 0 16089 141 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 3171 357 0 0
x_make_qstorno_1000_1200 5035 5035 0 0.00 2 39691 1269 0 0
x_make_qstorno_1200_2000 4950 4950 0 0.00 4 69565 2930 0 0
x_make_qstorno_2100_3300 443077 443077 0 0.00 0 88952 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-04-2022 04:38:09 to 28-04-2022 04:38:13.

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
02:07:20 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 14021 8192000000 9700080368 9995694080 784694512 8.741 100 86 170 1 813465008 7681648 21088848
02:09:46 29174 8192000000 9757916480 10067648512 832483312 9.224 100 84 168 0 863821776 11305312 20033152
02:11:27 15774 8192000000 9808413568 10134720512 875496864 9.655 100 78 154 0 914732656 20975248 18260544
02:12:55 13255 8192000000 9875912304 10213560320 930474144 10.199 100 81 160 0 981009968 33111920 17423904
02:14:11 6533 8192000000 9921089216 10268499968 966955024 10.557 100 79 155 1 1031281248 48236896 16089328
02:15:20 2093 8192000000 9972461968 10324942848 1001299824 10.891 100 93 175 2 1091124608 72367600 17457184
02:16:26 1155 8192000000 10012129056 10378211328 1024878656 11.119 100 97 188 0 1130863536 87176160 18808720
02:17:30 1067 8192000000 10007427296 10376081408 1049886096 11.360 100 98 182 0 1129541136 61030064 18624976
02:18:34 1335 8192000000 10025933920 10402398208 1063581616 11.491 100 92 180 1 1149553856 66711248 19260992
02:19:36 1183 8192000000 10035814896 10412814336 1067779040 11.531 100 92 180 1 1155426480 68992032 18655408
02:20:40 2858 8192000000 10031228656 10409996288 1071858624 11.570 100 69 130 2 1151828128 67828816 12140688
02:21:43 9741 8192000000 10042163952 10422829056 1078764256 11.636 100 76 142 0 1160264720 67434416 14066048
02:22:55 5932 8192000000 10043121152 10431344640 1081190656 11.659 100 70 133 0 1165215728 70893600 13131472
02:24:02 1658 8192000000 10034946032 10425393152 1085192256 11.697 100 66 122 2 1150859408 53278976 12388176
02:25:05 1026 8192000000 10061565184 10446745600 1091980688 11.761 100 99 193 1 1182947568 72339408 18627472
02:26:06 1284 8192000000 10063661664 10447564800 1094637456 11.787 100 95 184 0 1185117584 70991600 19488528
02:27:09 2307 8192000000 10041554368 10435563520 1088074576 11.724 100 84 165 3 1170430112 64355616 17999920
02:28:16 1219 8192000000 10040020928 10425069568 1069329248 11.546 99 86 167 1 1158942432 72744352 16868832
02:29:18 1354 8192000000 10068551456 10452979712 1077433568 11.623 99 95 190 1 1190395584 93206448 19755568
02:30:21 1089 8192000000 10032973152 10420559872 1074514128 11.595 99 83 160 4 1152289504 60782896 16992480
02:31:23 2356 8192000000 9993505472 10374504448 1042504224 11.289 98 80 146 1 1118879408 61430496 14944688
02:32:26 1206 8192000000 10030809136 10411577344 1047526272 11.337 98 94 180 0 1153233968 87774384 17933312
02:33:28 1218 8192000000 10006141152 10385956864 1046985296 11.332 100 95 174 1 1133926352 68467600 18473456
02:34:34 1197 8192000000 10024726256 10402037760 1054258816 11.402 100 87 162 1 1147550528 77763712 15528000
02:35:35 1178 8192000000 9973170432 10344001536 1037878560 11.244 100 96 183 2 1090582528 34512048 18191920
02:36:37 1908 8192000000 9982690448 10363293696 1036789472 11.234 100 69 131 1 1102470464 51763312 13917680
02:37:47 6066 8192000000 9967671952 10342498304 1038735104 11.253 99 78 136 2 1090142848 37353520 14054224
02:39:09 3851 8192000000 10037722704 10422812672 1074928304 11.599 100 74 147 0 1164982304 74976752 15077248
02:40:23 17458 8192000000 10042649536 10424864768 1090055504 11.743 100 78 135 0 1163385104 60358496 12971104
02:41:42 11992 8192000000 10081207568 10473349120 1108579872 11.919 100 64 119 2 1204969968 83787392 12602704
02:42:59 11828 8192000000 10141260928 10537455616 1120640560 12.033 100 73 144 0 1260920432 124414240 15865632
02:44:12 4143 8192000000 10187824784 10584698880 1127636416 12.099 100 79 156 0 1303389504 158450240 17302848
02:45:23 1138 8192000000 10213096192 10605977600 1141622640 12.231 100 94 182 4 1328653504 167693280 19337584
02:46:27 2097 8192000000 10221768320 10621673472 1144978288 12.262 100 87 172 2 1336672144 172866944 18826912
02:47:34 868 8192000000 10223662064 10621558784 1156074544 12.366 100 94 177 3 1339411456 164376672 18960240
02:48:40 1539 8192000000 10253564176 10660188160 1163232656 12.434 100 84 157 1 1365315680 183197504 18885520
02:49:46 1714 8192000000 10267280240 10676420608 1175604880 12.549 100 80 160 0 1383822016 190313168 17903968
02:50:51 3143 8192000000 10292382976 10697334784 1179271760 12.583 100 76 144 2 1402243440 206519312 16452368
02:51:58 946 8192000000 10340821920 10763108352 1193335936 12.714 100 97 195 1 1452103472 235529264 23238272
02:53:04 1384 8192000000 10358809280 10767646720 1185677008 12.643 100 93 176 0 1470792144 265314928 19800208
02:54:07 2433 8192000000 10401665648 10811240448 1191099776 12.694 100 84 162 1 1506407376 296322768 18984832
02:55:17 958 8192000000 10434723840 10861109248 1198699328 12.764 100 95 186 2 1546048912 324843008 22506576
02:56:21 1149 8192000000 10450925792 10898292736 1195811280 12.737 100 95 189 1 1559687232 341041200 22834752
02:57:27 848 8192000000 10420731936 10873344000 1203316272 12.807 100 97 195 1 1525671680 299971808 22383600
02:58:31 5164 8192000000 10436858032 10864721920 1199142592 12.768 100 71 140 0 1546419648 330565664 16711392
02:59:52 9647 8192000000 10428350512 10865332224 1193858112 12.719 100 48 94 0 1539196960 332646096 12692752
03:01:16 6055 8192000000 10502025232 10951942144 1202201408 12.797 100 58 116 0 1603029248 385988448 14839392
03:02:25 6405 8192000000 10501693680 10937925632 1205099616 12.824 100 53 101 1 1598638928 381140432 12398880
03:04:24 3252 8192000000 10458812384 10884689920 1172915408 12.524 99 54 103 0 1562533392 377312464 12305520
03:05:32 20108 8192000000 10391349456 10903916544 1175908640 12.552 99 34 68 0 1495294720 310638960 8747120
03:06:53 12320 8192000000 10374232000 10828951552 1170123632 12.498 100 35 70 0 1483602128 305036784 8441712
03:08:37 41146 8192000000 10314633504 10780704768 1122710256 12.053 96 20 40 1 1402844976 275163760 4970960
03:10:19 37052 8192000000 10177199088 10735734784 1162374144 12.425 100 20 39 1 1315791104 149185952 4231008
03:12:54 137628 8192000000 10101167456 10627346432 1141756752 12.232 99 18 35 1 1232805440 86552896 4495792
03:16:33 51754 8192000000 10126399872 10589745152 1113885200 11.969 98 17 34 0 1207883888 90550320 3448368
03:18:26 64153 8192000000 10071136032 10507264000 1054981520 11.408 93 19 38 1 1110685488 52171840 3532128
03:20:31 87707 8192000000 10136116528 10592911360 1120588960 12.033 99 23 46 1 1208104624 82376288 5139376
03:23:11 39927 8192000000 10118023664 10592301056 1113391872 11.965 99 11 22 1 1221522016 105495936 2634208
03:25:16 48466 8192000000 10115670736 10555432960 1128946720 12.111 100 50 92 0 1214715552 75324784 10444048
03:27:05 114310 8192000000 10161210160 10608054272 1103590224 11.872 98 22 44 2 1194340096 86023488 4726384
03:30:00 106149 8192000000 10074712272 10513305600 1101061968 11.848 98 26 52 0 1154318064 48069616 5186480
03:32:47 25564 8192000000 10060236368 10501869568 1104666000 11.882 99 25 50 1 1152216384 41718496 5831888
03:34:13 48059 8192000000 10121666624 10562306048 1122356688 12.049 99 38 76 0 1197380560 67139408 7884464
03:36:03 58471 8192000000 10110455648 10614669312 1119759216 12.025 99 18 36 0 1231307280 107353696 4194368
03:38:39 114009 8192000000 10076100176 10574143488 1126639008 12.090 99 13 26 2 1167568336 39165056 1764272
03:41:33 29022 8192000000 10077777008 10512547840 1145080640 12.263 100 36 63 3 1179462752 27988736 6393376
03:43:03 48877 8192000000 10125953632 10571821056 1132879312 12.148 99 17 34 0 1185901312 49393248 3628752
03:44:58 106227 8192000000 10095689600 10559963136 1091992752 11.762 96 13 26 1 1179062720 84231552 2838416
03:47:47 49859 8192000000 10103615056 10553806848 1136666192 12.184 100 38 76 1 1177304112 32734112 7903808
03:50:58 60105 8192000000 10095640016 10509094912 1103610912 11.872 97 43 86 1 1167482624 55401040 8470672
03:53:05 56570 8192000000 10116462512 10588389376 1142942736 12.243 100 48 96 0 1214802608 62706448 9153424
03:55:06 86537 8192000000 10092884592 10552229888 1140478768 12.220 100 50 99 2 1205074544 53956640 10639136
03:57:38 63154 8192000000 10136252864 10581528576 1141420368 12.229 99 33 66 0 1221883072 73331872 7130832
04:00:50 89638 8192000000 10117109776 10570354688 1119286368 12.020 98 10 21 0 1195432608 73768560 2377680
04:03:38 23921 8192000000 10062475344 10508320768 1094806960 11.788 97 37 63 1 1144989264 43087872 7094432
04:05:02 81172 8192000000 10091814048 10578808832 1118868464 12.016 99 13 26 2 1208081152 86382816 2829872
04:07:39 22647 8192000000 10077046752 10513539072 1112359040 11.955 99 48 95 0 1150472032 29288448 8824544
04:09:02 8475 8192000000 10079826560 10496262144 1119413808 12.021 99 76 148 1 1166887664 31272048 16201808
04:10:11 21533 8192000000 10087489152 10505936896 1081762352 11.664 96 47 94 2 1142933296 51589264 9581680
04:11:36 34527 8192000000 10074407760 10509946880 1099442240 11.832 99 38 72 3 1162577488 56133104 7002144
04:13:12 30516 8192000000 10098835392 10536779776 1074170256 11.592 96 58 115 1 1180996784 95345056 11481472
04:14:43 107338 8192000000 10051479872 10481430528 1046585456 11.328 94 20 40 0 1089879232 39582320 3711456
04:17:31 105164 8192000000 10039013328 10545561600 1090022640 11.743 98 24 48 1 1183291568 87115792 6153136
04:20:16 72041 8192000000 10051870352 10509537280 1092326256 11.765 98 31 63 1 1146704320 48070896 6307168
04:22:29 50388 8192000000 10046001920 10512990208 1104632448 11.882 99 22 44 1 1167410416 58227008 4550960
04:24:20 52205 8192000000 10048948352 10515226624 1120210512 12.029 100 10 20 0 1151534064 29196800 2126752
04:26:12 39140 8192000000 10046644880 10510295040 1107176208 11.906 99 17 34 0 1157129008 46231248 3721552
04:27:53 114918 8192000000 10105720512 10558824448 1118009424 12.008 99 15 28 1 1169948464 49343504 2595536
04:31:03 36817 8192000000 10103850368 10573586432 1122256048 12.048 99 23 46 3 1198642112 71192752 5193312
04:32:43 21770 8192000000 10160243840 10603442176 1155544832 12.362 100 33 66 0 1248778304 86156656 7076816
04:34:20 52763 8192000000 10166702352 10667003904 1122608880 12.052 98 36 70 2 1248562272 118361792 7591600
04:36:18 62191 8192000000 9643444048 10022334464 719561328 8.074 63 11 22 0 780005904 58223184 2221392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-04-2022 04:38:15 to 28-04-2022 04:38:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-04-2022 04:38:32 to 28-04-2022 04:38:34.

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          208357
MON$OLDEST_ACTIVE               209829
MON$OLDEST_SNAPSHOT             209829
MON$NEXT_TRANSACTION            209829
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                       2063328
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:1471: 
:1472: mscs-test-161.budzdorov.ru    Thu Apr 28 04:37:27 2022
:1473:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1474: 
--- end of diff output ---

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

28.04.22 04:38:35. 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-04-28 04:38:37 No severe PSQL-related problems occured
28.04.22 04:38:39. End of report.