Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.05.2022 10: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 16352988 kB
MemAvailable 31359928 kB
Buffers 0 kB
Cached 15317684 kB
SwapCached 2544 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-05-25 10:38:04
DTS_END                         2022-05-25 10:37:17.0150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36536
TRN_ID                          226487
ATT_ID                          259
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: 8978.62 20561 1077434
customer order: creation 181.67 486 21800
customer order: refuse 14.43 397 1731
order to supplier: creation 46.78 1301 5613
order to supplier: removal 3.37 149960 404
invoice (draft): creation 47.51 2952 5701
invoice (draft): removal 49.29 1002 5915
invoice accept: apply 42.85 24932 5142
invoice accept: cancel 44.27 1169 5312
customer reserve: creation 3946.08 239 473530
customer reserve: removal 4307.67 93 516920
realization accept: apply 85.73 248 10287
realization accept: cancel 76.00 148 9120
payment from customer: creation 60.53 169 7263
payment from customer: removal 5.67 128 680
payment to supplier: creation 50.98 192 6118
payment to supplier: removal 3.54 115 425
service: total inventory turnovers 4.10 3604 492
service: total monetary turnovers 7.37 376 884
service: aggregate perf. data 0.77 16986 92
service: refresh index statistics 0.04 206714 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-05-2022 10:38:35 to 25-05-2022 10:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12616
TEST_TIME 2 8157
TEST_TIME 3 8438
TEST_TIME 4 7708
TEST_TIME 5 7632
TEST_TIME 6 7225
TEST_TIME 7 6906
TEST_TIME 8 7121
TEST_TIME 9 7034
TEST_TIME 10 7300
TEST_TIME 11 7309
TEST_TIME 12 7369
TEST_TIME 13 7295
TEST_TIME 14 7273
TEST_TIME 15 7628
TEST_TIME 16 7772
TEST_TIME 17 7706
TEST_TIME 18 8029
TEST_TIME 19 7968
TEST_TIME 20 8062
TEST_TIME 21 8397
TEST_TIME 22 8529
TEST_TIME 23 8792
TEST_TIME 24 8815
TEST_TIME 25 8956
TEST_TIME 26 9388
TEST_TIME 27 9552
TEST_TIME 28 9662
TEST_TIME 29 10048
TEST_TIME 30 10548
TEST_TIME 31 10648
TEST_TIME 32 10843
TEST_TIME 33 10833
TEST_TIME 34 10735
TEST_TIME 35 10685
TEST_TIME 36 10451
TEST_TIME 37 10563
TEST_TIME 38 10433
TEST_TIME 39 10364
TEST_TIME 40 10244
TEST_TIME 41 10123
TEST_TIME 42 9997
TEST_TIME 43 9911
TEST_TIME 44 9859
TEST_TIME 45 9931
TEST_TIME 46 9814
TEST_TIME 47 9913
TEST_TIME 48 9775
TEST_TIME 49 9685
TEST_TIME 50 9694
TEST_TIME 51 9770
TEST_TIME 52 9729
TEST_TIME 53 9816
TEST_TIME 54 9748
TEST_TIME 55 9707
TEST_TIME 56 9745
TEST_TIME 57 9748
TEST_TIME 58 9753
TEST_TIME 59 9731
TEST_TIME 60 9793
TEST_TIME 61 9884
TEST_TIME 62 9737
TEST_TIME 63 9756
TEST_TIME 64 9635
TEST_TIME 65 9606
TEST_TIME 66 9564
TEST_TIME 67 9491
TEST_TIME 68 9447
TEST_TIME 69 9466
TEST_TIME 70 9459
TEST_TIME 71 9397
TEST_TIME 72 9401
TEST_TIME 73 9433
TEST_TIME 74 9419
TEST_TIME 75 9438
TEST_TIME 76 9452
TEST_TIME 77 9511
TEST_TIME 78 9502
TEST_TIME 79 9495
TEST_TIME 80 9440
TEST_TIME 81 9381
TEST_TIME 82 9317
TEST_TIME 83 9337
TEST_TIME 84 9294
TEST_TIME 85 9230
TEST_TIME 86 9180
TEST_TIME 87 9144
TEST_TIME 88 9120
TEST_TIME 89 9181
TEST_TIME 90 9141
TEST_TIME 91 9137
TEST_TIME 92 9131
TEST_TIME 93 9131
TEST_TIME 94 9158
TEST_TIME 95 9158
TEST_TIME 96 9209
TEST_TIME 97 9272
TEST_TIME 98 9214
TEST_TIME 99 9194
TEST_TIME 100 9286
TEST_TIME 101 9299
TEST_TIME 102 9262
TEST_TIME 103 9199
TEST_TIME 104 9209
TEST_TIME 105 9179
TEST_TIME 106 9186
TEST_TIME 107 9140
TEST_TIME 108 9105
TEST_TIME 109 9076
TEST_TIME 110 9027
TEST_TIME 111 9009
TEST_TIME 112 9037
TEST_TIME 113 9010
TEST_TIME 114 8991
TEST_TIME 115 9023
TEST_TIME 116 9025
TEST_TIME 117 9060
TEST_TIME 118 9067
TEST_TIME 119 9037
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-05-2022 10:38:39 to 25-05-2022 10:38:42.

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 1514753 1514753 0 0.00 0 15438 13 0 0
doc_list_biud 1514737 1514737 0 0.00 0 66550 38 0 0
sp_add_invoice_to_stock 5236 5142 94 1.79 49 224137 24932 0 94
sp_cancel_adding_invoice 5312 5312 0 0.00 6 107961 1169 0 0
sp_cancel_client_order 1731 1731 0 0.00 6 14022 398 0 0
sp_cancel_customer_reserve 516920 516920 0 0.00 1 24883 93 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 6464 128 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 3643 115 0 0
sp_cancel_supplier_invoice 5918 5915 3 0.05 2 66571 1002 0 3
sp_cancel_supplier_order 404 404 0 0.00 13 1320140 149960 0 0
sp_cancel_write_off 9120 9120 0 0.00 2 17318 149 0 0
sp_client_order 21800 21800 0 0.00 5 21707 486 0 0
sp_customer_reserve 473530 473530 0 0.00 1 96140 239 0 0
sp_fill_shopping_cart 33104 33104 0 0.00 1 10658 72 0 0
sp_fill_shopping_cart_clo_res 473492 473492 0 0.00 0 19962 20 0 0
sp_get_clo_for_invoice 5090 5090 0 0.00 0 25471 294 0 0
sp_kill_cost_storno 15537 15537 0 0.00 0 9043 63 0 0
sp_kill_qstorno_ret_qs2qd 1049279 1049279 0 0.00 0 66545 42 0 0
sp_kill_qty_storno 554778 554778 0 0.00 0 66548 95 0 0
sp_lock_selected_doc 21204 21204 0 0.00 0 6925 6 0 0
sp_make_cost_storno 28757 28757 0 0.00 0 10674 70 0 0
sp_multiply_rows_for_pdistr 28756 28756 0 0.00 0 13182 36 0 0
sp_multiply_rows_for_qdistr 21800 21800 0 0.00 0 14401 209 0 0
sp_pay_from_customer 7263 7263 0 0.00 5 19790 169 0 0
sp_pay_to_supplier 6118 6118 0 0.00 7 10850 192 0 0
sp_payment_common 13380 13380 0 0.00 2 8894 45 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 1 14001 329 0 0
sp_qd_handle_on_invoice_upd_sts 10401 10401 0 0.00 0 28656 457 0 0
sp_qd_handle_on_reserve_upd_sts 10287 10287 0 0.00 0 12875 58 0 0
sp_reserve_write_off 10287 10287 0 0.00 9 14069 248 0 0
sp_supplier_invoice 5701 5701 0 0.00 9 84796 2952 0 0
sp_supplier_order 5613 5613 0 0.00 8 31500 1301 0 0
srv_aggregate_perf_data 92 92 0 0.00 2566 145146 16987 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 884 137612 37523 0 0
srv_find_qd_qs_mism 1027533 1027533 0 0.00 0 15437 10 0 0
srv_make_invnt_saldo 492 492 0 0.00 54 62170 3604 0 0
srv_make_money_saldo 884 884 0 0.00 1 16860 376 0 0
srv_recalc_idx_stat 5 5 0 0.00 49314 542787 206714 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5147 5147 0 0.00 1 17972 836 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 7698 440 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 6587 28 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 1031 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 1445 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 657 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 3348 38 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 82264 5732 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1662 16 0 0
t$perf-norm:sp_client_order 21800 21800 0 0.00 0 6413 34 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 3958 16 0 0
t$perf-norm:sp_pay_from_customer 7263 7263 0 0.00 0 4942 18 0 0
t$perf-norm:sp_pay_to_supplier 6118 6118 0 0.00 0 6810 19 0 0
t$perf-norm:sp_reserve_write_off 10287 10287 0 0.00 0 6986 21 0 0
t$perf-norm:sp_supplier_invoice 5701 5701 0 0.00 0 5998 28 0 0
t$perf-norm:sp_supplier_order 5613 5613 0 0.00 0 5043 33 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 717 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 69 3 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 609 12 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 1724 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 209 77 0 0
v_add_invoice_to_stock 10351 10164 187 1.80 0 4005 22 0 187
v_all_customers 39095 39095 0 0.00 0 144 3 0 0
v_all_suppliers 31732 31732 0 0.00 0 118 4 0 0
v_all_wares 43596 43596 0 0.00 0 112 3 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 388 12 0 0
v_cancel_client_order 3462 3462 0 0.00 0 4687 11 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 6388 15 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 2439 11 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3633 18 0 0
v_cancel_supplier_invoice 876 870 6 0.68 0 3837 24 0 6
v_cancel_supplier_order 808 808 0 0.00 0 4002 24 0 0
v_cancel_write_off 2016 2016 0 0.00 0 4105 23 0 0
v_max_id_clo_ord 5611 5611 0 0.00 0 252 4 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 1046 13 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 3801 6 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 136 7 0 0
v_max_non_paid_realizn 3946 3946 0 0.00 0 1489 7 0 0
v_min_id_clo_ord 5611 5611 0 0.00 0 2382 8 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 4762 14 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 10546 12 0 0
v_min_non_paid_invoice 6118 6118 0 0.00 0 8888 20 0 0
v_min_non_paid_realizn 7260 7260 0 0.00 0 6912 17 0 0
v_our_firm 11128 11128 0 0.00 0 46 4 0 0
v_reserve_write_off 20574 20574 0 0.00 0 5289 11 0 0
x_lock_depdocs_on_canc_invoice 10792 10792 0 0.00 0 17305 116 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 11921 323 0 0
x_make_qstorno_1000_1200 5613 5613 0 0.00 2 31483 1155 0 0
x_make_qstorno_1200_2000 5701 5701 0 0.00 4 84762 2753 0 0
x_make_qstorno_2100_3300 473527 473527 0 0.00 0 96119 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-05-2022 10:38:43 to 25-05-2022 10:38:47.

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
08:07:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 41051 8192000000 9735462032 10038075392 808783584 8.985 100 94 188 0 840407904 8537296 23087024
08:10:07 11827 8192000000 9768665936 10078146560 845084096 9.351 100 91 182 0 880149632 13341088 21724448
08:11:24 16249 8192000000 9799877136 10120216576 865385584 9.554 100 79 158 0 909592128 25209328 18997216
08:12:54 14548 8192000000 9873325696 10210205696 919757952 10.094 100 82 160 1 983860768 46878560 17224256
08:14:12 3195 8192000000 9921109600 10268491776 965445792 10.542 100 79 156 0 1046086160 64493968 16146400
08:15:17 1343 8192000000 9982739024 10340966400 1004843776 10.925 100 95 181 3 1100310304 77435472 18031056
08:16:20 1309 8192000000 10005282384 10366914560 1025123824 11.121 100 95 182 0 1119513104 76771472 17617808
08:17:23 983 8192000000 10020879984 10391838720 1047524576 11.337 100 98 182 0 1137655072 71637184 18493312
08:18:25 1299 8192000000 10034662400 10414559232 1059930576 11.456 100 98 191 3 1157708992 78420272 19358144
08:19:29 4307 8192000000 10024700272 10416148480 1063952816 11.494 100 60 120 0 1146598656 71029200 11616640
08:20:34 962 8192000000 10054802928 10439639040 1077854512 11.627 100 82 159 0 1173160768 78902768 16403488
08:21:37 957 8192000000 10070036512 10459570176 1080139552 11.649 100 89 172 1 1187796672 89315792 18341328
08:22:39 3205 8192000000 10031766208 10421886976 1084804976 11.693 100 69 137 2 1165205168 67079040 13321152
08:23:43 1145 8192000000 10050186752 10439880704 1092381312 11.765 100 83 158 0 1170478544 62352000 15745232
08:24:46 1092 8192000000 10067717312 10457960448 1095394640 11.794 100 96 181 0 1186607344 71953136 19259568
08:25:47 3882 8192000000 10070723344 10464342016 1107022880 11.904 100 74 146 0 1196074928 75436208 13615840
08:26:53 1247 8192000000 10057625568 10444623872 1105154768 11.887 100 91 180 0 1181189168 57584224 18450176
08:27:55 9313 8192000000 10029528224 10421112832 1079835968 11.646 99 67 129 0 1151603584 59310688 12456928
08:29:05 3404 8192000000 10052918784 10442600448 1093805904 11.779 100 76 145 1 1173554368 65285600 14462864
08:30:09 1387 8192000000 10041575568 10427199488 1091239552 11.754 100 95 180 1 1164125728 54357200 18528976
08:31:11 6122 8192000000 9999395120 10390761472 1074039088 11.591 100 60 118 0 1134969968 49051520 11879360
08:32:19 5110 8192000000 10011322176 10395324416 1062181744 11.477 99 61 115 3 1137088464 62140832 12765888
08:33:25 13266 8192000000 10013213072 10411180032 1060153408 11.458 100 59 118 0 1135241312 63209696 11878208
08:34:43 771 8192000000 9989388048 10361769984 1029884752 11.167 100 91 171 0 1113094960 65704736 17505472
08:35:44 1352 8192000000 10009428976 10385780736 1041883536 11.283 100 96 186 1 1135149664 73911968 19354160
08:36:47 1582 8192000000 10004418048 10374967296 1033437664 11.202 100 98 185 1 1121552688 70015392 18099632
08:37:51 1164 8192000000 10027583888 10398412800 1043105984 11.295 99 99 197 3 1146524480 83989312 19429184
08:38:59 7511 8192000000 10002950912 10384297984 1055507632 11.413 99 66 130 3 1123706016 55059648 13138736
08:40:13 1257 8192000000 10053311488 10436014080 1083684336 11.683 100 92 176 3 1175075296 70180400 21210560
08:41:18 2229 8192000000 10074924560 10462470144 1102758592 11.864 100 79 148 3 1198274304 80575168 14940544
08:42:26 3498 8192000000 10125167280 10529968128 1121498336 12.041 100 74 143 0 1243590912 106414880 15677696
08:43:34 1293 8192000000 10160126272 10556149760 1134166864 12.161 100 86 163 1 1277260368 125021968 18071536
08:44:38 4019 8192000000 10220220240 10623332352 1139980800 12.215 100 74 144 1 1331628288 175765360 15882128
08:45:45 1447 8192000000 10224455408 10624290816 1155127200 12.358 100 75 143 0 1339935456 169361232 15447024
08:46:50 1619 8192000000 10242471840 10655469568 1166321680 12.462 100 79 151 0 1358121680 175567248 16232752
08:47:56 1615 8192000000 10288394304 10688761856 1177806608 12.570 100 90 172 1 1397186448 200262576 19117264
08:49:02 2146 8192000000 10306934160 10711826432 1174142992 12.536 99 89 173 2 1413716064 220108384 19464688
08:50:07 4203 8192000000 10345973184 10757873664 1187195024 12.657 100 78 154 0 1465845680 261736112 16914544
08:51:17 1510 8192000000 10386070512 10802143232 1185319408 12.640 100 97 187 1 1496416912 289847680 21249824
08:52:24 873 8192000000 10394248320 10822897664 1192971712 12.711 100 96 189 0 1501389136 286406672 22010752
08:53:28 3923 8192000000 10385299600 10819895296 1189946560 12.683 100 69 136 0 1496246752 290289152 16011040
08:54:45 1471 8192000000 10407380192 10836647936 1192069632 12.703 100 90 175 0 1509029664 298222464 18737568
08:55:49 4557 8192000000 10429664640 10856398848 1192088576 12.703 100 59 114 1 1546609568 340044656 14476336
08:57:01 1395 8192000000 10443025840 10864238592 1185377104 12.640 100 91 182 0 1548036752 340914832 21744816
08:58:05 1236 8192000000 10459083600 10911903744 1190709360 12.690 100 97 187 1 1562443712 349059824 22674528
08:59:08 6710 8192000000 10455058016 10901458944 1174394768 12.538 100 49 98 0 1562053936 375096848 12562320
09:00:18 5454 8192000000 10439168736 10901446656 1172201168 12.517 100 50 100 0 1547902240 363186256 12514816
09:01:31 3159 8192000000 10388970496 10828525568 1150873520 12.318 100 59 114 1 1500704816 335915936 13915360
09:02:43 1659 8192000000 10426768528 10892996608 1168462064 12.482 100 76 152 0 1529047744 342444384 18141296
09:03:47 12102 8192000000 10346065456 10791927808 1161608384 12.418 100 45 87 2 1453022224 280420768 10993072
09:05:07 12846 8192000000 10334601920 10785693696 1140483168 12.220 99 39 78 0 1448170976 298130992 9556816
09:06:21 14971 8192000000 10243566848 10744168448 1123603680 12.061 99 24 47 1 1351889904 221895200 6391024
09:07:37 67494 8192000000 10133421664 10632773632 1095932864 11.799 96 23 46 2 1229013136 128218192 4862080
09:10:21 71455 8192000000 10116184128 10572369920 1123069744 12.056 98 27 54 1 1219612368 90896736 5645888
09:12:35 72758 8192000000 10117296128 10567053312 1096691952 11.806 96 31 58 2 1173839552 71313568 5834032
09:14:49 42772 8192000000 10055113520 10492592128 1112070720 11.952 99 31 56 2 1155162272 36942976 6148576
09:16:33 80596 8192000000 10065626912 10513666048 1129072624 12.113 99 40 80 1 1176161360 38169472 8919264
09:18:55 5698 8192000000 10092662320 10519486464 1125136976 12.075 100 46 86 1 1189716560 55805632 8773952
09:20:05 36648 8192000000 10194851920 10641494016 1129049248 12.112 99 42 84 0 1256423328 118569488 8804592
09:21:47 137612 8192000000 10109170288 10585014272 1110501040 11.937 98 18 36 1 1195074720 80693632 3880048
09:25:05 75046 8192000000 10084306880 10546155520 1127000368 12.093 100 14 28 0 1178372512 48200144 3172000
09:27:20 55082 8192000000 10130722032 10589491200 1146886624 12.280 100 15 30 0 1220387936 70164096 3337216
09:29:21 79428 8192000000 10056679216 10510712832 1093839488 11.779 97 14 28 1 1154562448 57682880 3040080
09:31:45 35838 8192000000 10092761552 10529034240 1104695872 11.882 98 45 90 2 1165341952 51731552 8914528
09:34:31 116709 8192000000 10127757120 10631348224 1099707056 11.835 97 28 56 2 1175552832 70017152 5828624
09:37:30 26567 8192000000 10128074560 10561257472 1148679808 12.297 100 31 62 1 1208460864 53733328 6047728
09:38:59 121189 8192000000 10061194688 10549342208 1084641472 11.692 95 18 36 1 1150702368 62308288 3752608
09:42:13 43539 8192000000 10102688032 10585464832 1138082432 12.197 100 19 38 1 1233912160 92072160 3757568
09:43:57 54302 8192000000 10087318576 10556112896 1137554080 12.193 100 16 30 3 1196542896 55838688 3150128
09:46:14 48826 8192000000 10092732080 10544590848 1119500400 12.022 98 14 28 1 1175612976 53139936 2972640
09:48:29 36848 8192000000 10135300768 10559156224 1107332672 11.907 96 45 82 1 1184643824 68706864 8604288
09:50:16 52514 8192000000 10099269632 10538872832 1112029152 11.952 98 15 30 1 1191125568 75977360 3119056
09:52:15 50563 8192000000 10101220560 10562596864 1109777264 11.930 98 18 36 1 1155900400 42273632 3849504
09:54:06 46128 8192000000 10097430032 10534674432 1105171792 11.887 97 45 89 0 1171785872 57868880 8745200
09:55:54 40480 8192000000 10079369184 10518646784 1108890624 11.922 98 39 78 1 1187545168 70842624 7811920
09:57:54 75340 8192000000 10089771632 10539921408 1115298608 11.983 99 49 94 1 1192376512 67291808 9786096
10:00:13 45902 8192000000 10056096752 10545729536 1118046336 12.009 100 17 34 0 1187132672 65746368 3339968
10:01:59 66321 8192000000 10046485232 10506272768 1118041264 12.008 99 6 12 0 1152805840 33720128 1044448
10:04:07 111621 8192000000 10101606240 10573598720 1112225440 11.953 98 12 24 0 1174687184 59690112 2771632
10:06:59 39367 8192000000 10057406064 10502455296 1083314176 11.679 96 16 28 2 1126312176 40230592 2767408
10:08:39 36713 8192000000 10094949376 10543230976 1088088448 11.724 97 21 39 1 1146850320 53826704 4935168
10:10:28 35765 8192000000 10120773792 10564018176 1097065136 11.810 97 22 44 1 1182809840 80361408 5383296
10:12:04 57706 8192000000 10137439728 10582818816 1121819120 12.044 99 33 63 2 1208015584 79475264 6721200
10:14:03 48516 8192000000 10080929168 10513768448 1122085472 12.047 99 20 38 2 1193591024 67639360 3866192
10:15:54 12191 8192000000 10120676848 10557550592 1143311648 12.247 100 57 114 2 1222701568 67711504 11678416
10:17:13 85015 8192000000 10086604960 10530594816 1103976496 11.875 97 31 62 0 1157498288 47442896 6078896
10:19:39 99740 8192000000 10062195856 10499518464 1088286480 11.726 97 42 78 2 1153203648 57174640 7742528
10:22:22 63675 8192000000 10077838400 10545778688 1110507232 11.937 99 32 64 0 1166157664 49371856 6278576
10:24:29 63014 8192000000 10023805120 10496815104 1093159936 11.773 97 6 12 0 1132926256 38250176 1516144
10:26:35 70977 8192000000 10077288304 10552094720 1119436624 12.022 99 17 34 1 1179241856 55998832 3806400
10:28:56 65349 8192000000 10077825696 10565726208 1129767952 12.119 100 9 18 0 1172262512 40417792 2076768
10:31:10 24941 8192000000 10122943856 10564616192 1124838080 12.073 99 34 68 0 1207352928 75280096 7234752
10:32:46 14831 8192000000 10115047232 10560651264 1142218576 12.236 100 27 53 1 1221913312 73940480 5754256
10:34:02 53334 8192000000 10114826896 10606899200 1125527328 12.079 98 28 56 0 1232262176 100272896 6461952
10:36:01 16620 8192000000 10138256864 10571317248 1120939440 12.036 99 43 87 2 1224602160 94058944 9603776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-05-2022 10:38:49 to 25-05-2022 10:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-05-2022 10:39:06 to 25-05-2022 10:39:08.

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          232205
MON$OLDEST_ACTIVE               232206
MON$OLDEST_SNAPSHOT             232206
MON$NEXT_TRANSACTION            232206
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                       2041440
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
--- end of diff output ---

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

25.05.22 10:39:09. 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-05-25 10:39:12 No severe PSQL-related problems occured
25.05.22 10:39:13. End of report.