Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.09.2021 13: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15806228 kB
MemAvailable 30819120 kB
Buffers 0 kB
Cached 15838344 kB
SwapCached 392 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.1
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 2021-09-10 13:37:08
DTS_END                         2021-09-10 13:37:08.7990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50530
TRN_ID                          232666
ATT_ID                          608
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: 9152.94 27875 1098353
customer order: creation 163.52 455 19622
customer order: refuse 13.88 395 1666
order to supplier: creation 44.39 1509 5327
order to supplier: removal 3.42 144390 410
invoice (draft): creation 44.13 4082 5296
invoice (draft): removal 51.56 1218 6187
invoice accept: apply 40.97 21883 4916
invoice accept: cancel 46.50 1032 5580
customer reserve: creation 3873.07 209 464768
customer reserve: removal 4595.45 82 551454
realization accept: apply 78.37 213 9404
realization accept: cancel 74.47 165 8937
payment from customer: creation 56.70 134 6804
payment from customer: removal 5.61 123 673
payment to supplier: creation 46.14 161 5537
payment to supplier: removal 3.55 102 426
service: total inventory turnovers 3.47 4303 416
service: total monetary turnovers 6.76 582 811
service: aggregate perf. data 0.96 17074 115
service: refresh index statistics 0.03 359382 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-09-2021 13:38:23 to 10-09-2021 13:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4162
TEST_TIME 2 3534
TEST_TIME 3 4149
TEST_TIME 4 4830
TEST_TIME 5 4834
TEST_TIME 6 4674
TEST_TIME 7 4849
TEST_TIME 8 5065
TEST_TIME 9 5372
TEST_TIME 10 5719
TEST_TIME 11 5707
TEST_TIME 12 6199
TEST_TIME 13 6354
TEST_TIME 14 6470
TEST_TIME 15 6702
TEST_TIME 16 6980
TEST_TIME 17 6985
TEST_TIME 18 7105
TEST_TIME 19 7348
TEST_TIME 20 7482
TEST_TIME 21 7463
TEST_TIME 22 7606
TEST_TIME 23 7906
TEST_TIME 24 7875
TEST_TIME 25 8069
TEST_TIME 26 8271
TEST_TIME 27 8264
TEST_TIME 28 8197
TEST_TIME 29 8282
TEST_TIME 30 8823
TEST_TIME 31 8886
TEST_TIME 32 9700
TEST_TIME 33 10822
TEST_TIME 34 10691
TEST_TIME 35 10435
TEST_TIME 36 10494
TEST_TIME 37 10376
TEST_TIME 38 10516
TEST_TIME 39 10506
TEST_TIME 40 10345
TEST_TIME 41 10161
TEST_TIME 42 10104
TEST_TIME 43 10000
TEST_TIME 44 9916
TEST_TIME 45 9885
TEST_TIME 46 9862
TEST_TIME 47 9791
TEST_TIME 48 9735
TEST_TIME 49 9707
TEST_TIME 50 9761
TEST_TIME 51 9694
TEST_TIME 52 9667
TEST_TIME 53 9792
TEST_TIME 54 9754
TEST_TIME 55 9743
TEST_TIME 56 9677
TEST_TIME 57 9664
TEST_TIME 58 9714
TEST_TIME 59 9736
TEST_TIME 60 9792
TEST_TIME 61 9771
TEST_TIME 62 9784
TEST_TIME 63 9865
TEST_TIME 64 9905
TEST_TIME 65 9863
TEST_TIME 66 9782
TEST_TIME 67 9680
TEST_TIME 68 9594
TEST_TIME 69 9583
TEST_TIME 70 9557
TEST_TIME 71 9495
TEST_TIME 72 9414
TEST_TIME 73 9385
TEST_TIME 74 9350
TEST_TIME 75 9337
TEST_TIME 76 9432
TEST_TIME 77 9450
TEST_TIME 78 9420
TEST_TIME 79 9430
TEST_TIME 80 9502
TEST_TIME 81 9440
TEST_TIME 82 9522
TEST_TIME 83 9537
TEST_TIME 84 9541
TEST_TIME 85 9610
TEST_TIME 86 9562
TEST_TIME 87 9495
TEST_TIME 88 9503
TEST_TIME 89 9434
TEST_TIME 90 9401
TEST_TIME 91 9461
TEST_TIME 92 9415
TEST_TIME 93 9376
TEST_TIME 94 9341
TEST_TIME 95 9343
TEST_TIME 96 9313
TEST_TIME 97 9311
TEST_TIME 98 9300
TEST_TIME 99 9319
TEST_TIME 100 9313
TEST_TIME 101 9410
TEST_TIME 102 9414
TEST_TIME 103 9386
TEST_TIME 104 9441
TEST_TIME 105 9445
TEST_TIME 106 9455
TEST_TIME 107 9437
TEST_TIME 108 9448
TEST_TIME 109 9368
TEST_TIME 110 9361
TEST_TIME 111 9348
TEST_TIME 112 9326
TEST_TIME 113 9267
TEST_TIME 114 9238
TEST_TIME 115 9263
TEST_TIME 116 9232
TEST_TIME 117 9227
TEST_TIME 118 9199
TEST_TIME 119 9225
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-09-2021 13:38:27 to 10-09-2021 13:38:30.

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 1528540 1528540 0 0.00 0 35275 8 0 0
doc_list_biud 1528539 1528539 0 0.00 0 35120 37 0 0
sp_add_invoice_to_stock 5038 4916 122 2.42 46 203507 21884 0 122
sp_cancel_adding_invoice 5580 5580 0 0.00 6 63587 1032 0 0
sp_cancel_client_order 1666 1666 0 0.00 4 9122 395 0 0
sp_cancel_customer_reserve 551454 551454 0 0.00 1 26672 82 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 9376 124 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 3575 102 0 0
sp_cancel_supplier_invoice 6197 6187 10 0.16 2 35128 1218 0 10
sp_cancel_supplier_order 410 410 0 0.00 5 1260128 144391 0 0
sp_cancel_write_off 8937 8937 0 0.00 1 16544 165 0 0
sp_client_order 19622 19622 0 0.00 4 39245 455 0 0
sp_customer_reserve 464768 464768 0 0.00 0 99574 209 0 0
sp_fill_shopping_cart 30239 30239 0 0.00 1 16035 46 0 0
sp_fill_shopping_cart_clo_res 464763 464763 0 0.00 0 24640 19 0 0
sp_get_clo_for_invoice 4910 4910 0 0.00 0 22447 348 0 0
sp_kill_cost_storno 15616 15616 0 0.00 0 35266 87 0 0
sp_kill_qstorno_ret_qs2qd 1118442 1118442 0 0.00 0 35118 45 0 0
sp_kill_qty_storno 588548 588548 0 0.00 0 35120 96 0 0
sp_lock_selected_doc 19978 19978 0 0.00 0 9635 6 0 0
sp_make_cost_storno 26655 26655 0 0.00 0 27996 67 0 0
sp_multiply_rows_for_pdistr 26655 26655 0 0.00 0 12948 41 0 0
sp_multiply_rows_for_qdistr 19622 19622 0 0.00 0 13537 220 0 0
sp_pay_from_customer 6804 6804 0 0.00 4 20433 134 0 0
sp_pay_to_supplier 5537 5537 0 0.00 5 28001 162 0 0
sp_payment_common 12340 12340 0 0.00 1 9480 25 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 9114 354 0 0
sp_qd_handle_on_invoice_upd_sts 10490 10490 0 0.00 0 27626 459 0 0
sp_qd_handle_on_reserve_upd_sts 9404 9404 0 0.00 0 15780 66 0 0
sp_reserve_write_off 9404 9404 0 0.00 7 21083 213 0 0
sp_supplier_invoice 5296 5296 0 0.00 6 93371 4082 0 0
sp_supplier_order 5327 5327 0 0.00 5 42788 1510 0 0
srv_aggregate_perf_data 115 115 0 0.00 2327 157698 17074 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 886 129333 39040 0 0
srv_find_qd_qs_mism 1051828 1051828 0 0.00 0 12656 7 0 0
srv_make_invnt_saldo 416 416 0 0.00 62 101282 4303 0 0
srv_make_money_saldo 811 811 0 0.00 1 12733 582 0 0
srv_recalc_idx_stat 4 4 0 0.00 220843 567701 359382 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 0 12510 203 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 2117 93 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 4289 19 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 2990 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2145 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 473 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3115 30 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 18802 1783 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2449 9 0 0
t$perf-norm:sp_client_order 19622 19622 0 0.00 0 19496 20 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 2396 10 0 0
t$perf-norm:sp_pay_from_customer 6804 6804 0 0.00 0 5369 10 0 0
t$perf-norm:sp_pay_to_supplier 5537 5537 0 0.00 0 2203 7 0 0
t$perf-norm:sp_reserve_write_off 9404 9404 0 0.00 0 3554 12 0 0
t$perf-norm:sp_supplier_invoice 5296 5296 0 0.00 0 5215 23 0 0
t$perf-norm:sp_supplier_order 5327 5327 0 0.00 0 3599 22 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 495 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 82 3 0 0
t$perf-norm:srv_make_invnt_saldo 416 416 0 0.00 0 1222 11 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 1345 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 7 0 0
v_add_invoice_to_stock 10064 9820 244 2.42 0 9285 22 0 244
v_all_customers 35974 35974 0 0.00 0 22 1 0 0
v_all_suppliers 29342 29342 0 0.00 0 50 1 0 0
v_all_wares 39240 39240 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 227 7 0 0
v_cancel_client_order 3332 3332 0 0.00 0 2206 7 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 7847 18 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 664 4 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 2209 11 0 0
v_cancel_supplier_invoice 788 768 20 2.53 0 714 15 0 20
v_cancel_supplier_order 820 820 0 0.00 0 406 6 0 0
v_cancel_write_off 2002 2002 0 0.00 0 681 15 0 0
v_max_id_clo_ord 5324 5324 0 0.00 0 1435 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 2495 12 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 5927 5 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 178 2 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 4588 4 0 0
v_min_id_clo_ord 5324 5324 0 0.00 0 12292 13 0 0
v_min_id_clo_res 983 983 0 0.00 0 9231 18 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 6081 9 0 0
v_min_non_paid_invoice 5537 5537 0 0.00 0 9474 15 0 0
v_min_non_paid_realizn 6803 6803 0 0.00 0 6563 17 0 0
v_our_firm 9722 9722 0 0.00 0 18 1 0 0
v_reserve_write_off 18807 18807 0 0.00 0 11232 7 0 0
x_lock_depdocs_on_canc_invoice 11383 11383 0 0.00 0 19656 148 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 14753 485 0 0
x_make_qstorno_1000_1200 5327 5327 0 0.00 2 38457 1406 0 0
x_make_qstorno_1200_2000 5296 5296 0 0.00 3 93363 3924 0 0
x_make_qstorno_2100_3300 464768 464768 0 0.00 0 97016 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-09-2021 13:38:31 to 10-09-2021 13:38:35.

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
11:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 21448 8192000000 9720008384 10017116160 795835232 8.854 100 85 170 0 824489008 7904672 20749104
11:09:47 24631 8192000000 9775440800 10083991552 840231024 9.302 100 86 172 0 874583904 13409696 20943184
11:11:24 12246 8192000000 9825891120 10144735232 876958256 9.669 100 85 170 0 931129056 34644368 19526432
11:12:40 12337 8192000000 9865286704 10199932928 919645504 10.093 100 76 152 0 977586752 41652080 16289168
11:13:59 16858 8192000000 9934795296 10278424576 960497024 10.494 100 72 144 0 1026708016 51089408 15121584
11:15:18 5244 8192000000 9966534208 10320125952 994883360 10.829 100 83 164 0 1081635072 69798512 16953200
11:16:25 2852 8192000000 9991830368 10353082368 1028337904 11.152 100 87 171 1 1103330096 57761376 17230816
11:17:29 1958 8192000000 10019810976 10386726912 1047103024 11.333 100 89 177 2 1136617808 71507760 18007024
11:18:32 1492 8192000000 10058917312 10428416000 1067780960 11.531 100 93 182 1 1160062464 73598400 18683104
11:19:35 26339 8192000000 9962970560 10364669952 1071945552 11.571 100 28 56 0 1107775424 29813536 6016336
11:21:03 9532 8192000000 9994868368 10391646208 1076369520 11.613 100 31 61 3 1101274160 18685424 6219216
11:22:13 3494 8192000000 10000379216 10394341376 1086890336 11.713 100 46 95 1 1116758688 20133712 9734640
11:23:17 12133 8192000000 9989870176 10401599488 1089135792 11.734 100 22 45 1 1110756384 16209888 5410704
11:24:35 12216 8192000000 10020189152 10415239168 1095549952 11.795 100 56 112 2 1121504432 14134592 11819888
11:25:48 4366 8192000000 10031575776 10429591552 1099299792 11.831 100 40 80 1 1142296768 34689632 8307344
11:26:55 9085 8192000000 9999797152 10404556800 1077325296 11.622 98 32 63 1 1100771248 16347104 7098848
11:28:05 10074 8192000000 9963466080 10361348096 1039900368 11.264 98 19 38 0 1060440496 16145344 4394784
11:29:15 20260 8192000000 9965186528 10365435904 1016840896 11.042 96 28 55 2 1036716720 14053984 5821840
11:30:39 2971 8192000000 9938569872 10309644288 1004082240 10.918 99 50 100 2 1040358272 24843136 11432896
11:31:43 5310 8192000000 9951549408 10332831744 1024247104 11.113 99 59 118 0 1052828880 14995360 13586416
11:32:51 61864 8192000000 9937210656 10335457280 1007942736 10.955 97 29 57 2 1029452640 15814816 5695088
11:34:58 6076 8192000000 9984907984 10374332416 1067984976 11.533 100 34 68 0 1089533872 14237968 7310928
11:36:05 3980 8192000000 9988269328 10386518016 1070495616 11.557 100 32 64 0 1104347184 27145296 6706272
11:37:13 7777 8192000000 10005507296 10398912512 1082486528 11.671 100 49 99 0 1111222512 18520128 10215856
11:38:27 128961 8192000000 10071003040 10496270336 1101575040 11.853 100 48 96 1 1182949904 71370112 10004752
11:41:41 17424 8192000000 10071546944 10478637056 1114369072 11.974 100 56 112 1 1181144352 54321904 12453376
11:43:03 30643 8192000000 10106575376 10520301568 1127071776 12.094 100 40 79 1 1207284416 71325872 8886768
11:44:38 17893 8192000000 10173905696 10589483008 1141526240 12.230 100 59 116 1 1269637216 115059712 13051264
11:46:02 29152 8192000000 10188380096 10622582784 1151455712 12.323 100 52 104 2 1283959248 120573216 11930320
11:47:36 5796 8192000000 10224629792 10644021248 1168977664 12.487 100 67 129 0 1333863488 150800432 14085392
11:48:46 8889 8192000000 10238557968 10657525760 1174419120 12.538 100 64 122 2 1347000656 159032464 13549072
11:49:59 6354 8192000000 10283354528 10699325440 1193898816 12.720 100 68 135 0 1393949680 186223312 13827552
11:51:09 2882 8192000000 10248554208 10657460224 1160054816 12.404 98 75 150 2 1347014496 169876976 17082704
11:52:19 1277 8192000000 10312557200 10716471296 1182590736 12.614 100 92 183 0 1407737648 205292240 19854672
11:53:24 5000 8192000000 10294245312 10705989632 1161734240 12.420 99 90 178 2 1388038624 206463936 19840448
11:54:36 4316 8192000000 10306528464 10713714688 1153280336 12.340 100 71 142 0 1409365680 239605760 16479584
11:55:46 3946 8192000000 10328327680 10751692800 1174175488 12.536 100 75 149 1 1437903136 246135280 17592368
11:56:56 1922 8192000000 10380586768 10776850432 1177739232 12.569 100 94 184 0 1474302880 276064896 20498752
11:58:04 1006 8192000000 10412145296 10807517184 1168598768 12.484 100 96 190 0 1501017808 309685424 22733616
11:59:13 3661 8192000000 10468204336 10887909376 1184047408 12.628 100 85 171 1 1574580256 369614720 20918128
12:00:22 883 8192000000 10514279920 10918141952 1193158304 12.713 100 92 182 0 1605287792 390615184 21514304
12:01:27 6734 8192000000 10508126480 10929631232 1196541456 12.744 100 65 128 1 1603519936 390854496 16123984
12:02:38 11900 8192000000 10562731952 11005558784 1195362672 12.733 100 45 90 0 1662397552 455081648 11953232
12:04:13 6724 8192000000 10594582000 11069587456 1176680016 12.559 99 48 96 0 1680929248 490865024 13384208
12:05:39 21834 8192000000 10608911360 11051692032 1169917824 12.496 100 42 84 1 1701746080 520276784 11551472
12:07:11 8522 8192000000 10588672480 11045687296 1166268896 12.462 100 52 103 0 1680389520 500698608 13422016
12:08:31 21601 8192000000 10526663584 11024658432 1137484080 12.192 99 27 54 1 1640437504 495118320 7835104
12:11:08 57190 8192000000 10089273120 10532794368 1113546336 11.966 99 6 12 0 1159530960 44877616 1107008
12:13:10 19505 8192000000 10134611600 10552385536 1125815056 12.082 100 56 113 1 1217144256 79066496 12262704
12:14:38 50686 8192000000 10091344736 10570567680 1117160448 12.000 99 22 42 3 1187203584 65338992 4704144
12:16:28 60212 8192000000 10055264512 10508898304 1117098928 12.000 99 12 24 0 1152484288 32942096 2443264
12:18:29 68516 8192000000 10077322688 10545057792 1137017504 12.187 100 48 96 0 1195390000 48250288 10122208
12:20:40 91193 8192000000 10095902752 10524291072 1121348944 12.040 98 31 60 1 1188582336 61486992 5746400
12:23:12 34271 8192000000 10145161264 10601267200 1156313216 12.369 100 34 68 2 1244617136 81076048 7227872
12:24:59 22974 8192000000 10143191792 10613415936 1147340960 12.285 99 35 70 0 1228456608 73207600 7908048
12:26:27 77201 8192000000 10185586384 10661380096 1140641296 12.222 99 15 30 1 1279320736 134780048 3899392
12:28:46 41980 8192000000 10140655136 10628239360 1143842096 12.252 99 15 30 0 1265925360 118116736 3966528
12:31:12 26550 8192000000 10094131168 10573135872 1148859936 12.299 100 7 13 0 1207367808 56638400 1869472
12:32:40 39583 8192000000 10081745136 10519883776 1089957392 11.742 95 29 58 0 1119399904 23534704 5907808
12:34:27 62073 8192000000 10138078640 10599870464 1125926640 12.083 98 26 51 1 1214746736 83561728 5258368
12:36:35 67104 8192000000 10108024912 10596909056 1143196112 12.246 100 6 12 0 1189529664 45095648 1237904
12:38:44 111766 8192000000 10102541344 10620084224 1136507888 12.183 99 18 35 0 1173450192 33398096 3544208
12:41:42 53736 8192000000 10069063008 10508804096 1125219040 12.076 98 12 24 0 1159968192 32723136 2026016
12:43:37 129333 8192000000 10066053536 10505121792 1100423184 11.842 97 26 49 1 1141869024 36721168 4724672
12:46:57 36700 8192000000 10058183600 10527535104 1108775040 11.921 98 12 23 2 1168361328 57195936 2390352
12:48:36 44273 8192000000 10053965648 10517901312 1111354736 11.945 98 10 20 0 1150351408 36647696 2348976
12:50:20 38061 8192000000 10118750672 10593693696 1129123216 12.113 99 15 30 1 1210037168 77893392 3020560
12:52:16 45976 8192000000 10132113936 10634006528 1130116848 12.122 99 22 44 1 1255012512 120375280 4520384
12:54:06 51521 8192000000 10102687728 10577854464 1111899648 11.950 98 25 49 2 1196792160 79505840 5386672
12:56:00 43168 8192000000 10121857264 10563375104 1121956240 12.045 98 21 42 0 1194252976 67542992 4753744
12:57:43 50090 8192000000 10104534304 10538766336 1118336272 12.011 98 31 62 0 1183595536 58393968 6865296
12:59:35 26331 8192000000 10117504976 10593492992 1128900336 12.111 100 38 76 0 1195251696 59001440 7349920
13:01:08 71539 8192000000 10082155680 10559598592 1083853680 11.684 95 17 34 0 1161481696 74276176 3351840
13:03:20 8469 8192000000 10073882592 10504560640 1134186592 12.161 100 38 78 0 1163940160 21453968 8299600
13:04:31 119351 8192000000 10106443024 10584162304 1138107408 12.198 99 17 34 0 1189224304 47790432 3326464
13:07:36 39450 8192000000 10124468656 10591297536 1126123856 12.085 98 28 56 0 1221267792 88758288 6385648
13:09:16 39333 8192000000 10054813024 10500263936 1115664416 11.986 98 19 38 0 1145350240 25695312 3990512
13:11:00 49423 8192000000 10069966032 10537889792 1105284656 11.888 98 8 16 0 1156258272 49213824 1759792
13:13:04 25356 8192000000 10079073504 10518069248 1139237408 12.208 100 34 66 0 1169463504 23676512 6549584
13:14:33 42698 8192000000 10127966080 10578702336 1148651552 12.297 100 31 62 1 1214844928 59324416 6868960
13:16:24 57642 8192000000 10124317696 10604216320 1092115760 11.763 95 27 54 0 1203695664 105704432 5875472
13:18:51 39785 8192000000 10086331168 10608431104 1126434432 12.088 99 11 22 0 1189870976 61251504 2185040
13:20:51 39075 8192000000 10126102192 10604531712 1135690192 12.175 99 43 85 1 1213454224 69543200 8220832
13:22:32 42343 8192000000 10110852608 10568617984 1126333888 12.087 99 33 66 0 1163084832 30002976 6747968
13:24:14 63184 8192000000 10119989728 10597908480 1115322384 11.983 98 20 40 0 1203660528 84327872 4010272
13:27:50 45562 8192000000 10096417504 10581417984 1135363792 12.172 100 27 53 1 1188132896 47651808 5117296
13:29:46 55927 8192000000 10087476240 10557775872 1107979296 11.913 97 6 12 0 1169951456 60609616 1362544
13:31:54 41236 8192000000 10043597744 10516238336 1120445328 12.031 99 5 10 0 1149165024 27657120 1062576
13:33:42 62938 8192000000 10065813680 10542391296 1113691536 11.967 98 18 36 2 1165240672 47859280 3689856
13:36:08 67143 8192000000 9281156256 9635446784 336966528 3.950 30 8 15 0 363800624 25245648 1588448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-09-2021 13:38:37 to 10-09-2021 13:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-09-2021 13:38:53 to 10-09-2021 13:38:55.

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          230313
MON$OLDEST_ACTIVE               232913
MON$OLDEST_SNAPSHOT             232913
MON$NEXT_TRANSACTION            232913
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                       2069536
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2587 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2587 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2587 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2587 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Sep 10 13:37:17 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Sep 10 13:37:17 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Sep 10 13:37:18 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

10.09.21 13:38:56. 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
2021-09-10 13:38:58 No severe PSQL-related problems occured
10.09.21 13:38:59. End of report.