Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.08.2021 10: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 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 15931296 kB
MemAvailable 30903576 kB
Buffers 0 kB
Cached 15742468 kB
SwapCached 324 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-08-31 10:37:06
DTS_END                         2021-08-31 10:37:06.0170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59516
TRN_ID                          233010
ATT_ID                          597
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: 9167.90 22925 1100148
customer order: creation 170.51 394 20461
customer order: refuse 14.39 363 1727
order to supplier: creation 45.00 1444 5400
order to supplier: removal 3.44 124028 413
invoice (draft): creation 45.30 4061 5436
invoice (draft): removal 52.58 999 6309
invoice accept: apply 41.18 19628 4941
invoice accept: cancel 46.93 919 5631
customer reserve: creation 3805.11 192 456613
customer reserve: removal 4658.91 72 559069
realization accept: apply 82.71 206 9925
realization accept: cancel 74.38 140 8926
payment from customer: creation 58.05 132 6966
payment from customer: removal 5.48 93 657
payment to supplier: creation 49.56 140 5947
payment to supplier: removal 3.48 154 417
service: total inventory turnovers 3.79 3976 455
service: total monetary turnovers 6.16 550 739
service: aggregate perf. data 0.93 15803 112
service: refresh index statistics 0.03 285215 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-08-2021 10:38:13 to 31-08-2021 10:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7129
TEST_TIME 2 4531
TEST_TIME 3 4630
TEST_TIME 4 4210
TEST_TIME 5 4257
TEST_TIME 6 5769
TEST_TIME 7 5443
TEST_TIME 8 5433
TEST_TIME 9 5399
TEST_TIME 10 5823
TEST_TIME 11 6127
TEST_TIME 12 6531
TEST_TIME 13 6715
TEST_TIME 14 6879
TEST_TIME 15 7093
TEST_TIME 16 7336
TEST_TIME 17 7420
TEST_TIME 18 7787
TEST_TIME 19 7977
TEST_TIME 20 7848
TEST_TIME 21 8200
TEST_TIME 22 8408
TEST_TIME 23 8499
TEST_TIME 24 8769
TEST_TIME 25 8798
TEST_TIME 26 9034
TEST_TIME 27 9022
TEST_TIME 28 9369
TEST_TIME 29 9906
TEST_TIME 30 10328
TEST_TIME 31 10584
TEST_TIME 32 10464
TEST_TIME 33 10404
TEST_TIME 34 10422
TEST_TIME 35 10466
TEST_TIME 36 10451
TEST_TIME 37 10385
TEST_TIME 38 10332
TEST_TIME 39 10159
TEST_TIME 40 10043
TEST_TIME 41 9958
TEST_TIME 42 9979
TEST_TIME 43 9835
TEST_TIME 44 9745
TEST_TIME 45 9760
TEST_TIME 46 9795
TEST_TIME 47 9775
TEST_TIME 48 9829
TEST_TIME 49 9822
TEST_TIME 50 9809
TEST_TIME 51 9671
TEST_TIME 52 9663
TEST_TIME 53 9562
TEST_TIME 54 9631
TEST_TIME 55 9602
TEST_TIME 56 9755
TEST_TIME 57 9682
TEST_TIME 58 9728
TEST_TIME 59 9705
TEST_TIME 60 9743
TEST_TIME 61 9801
TEST_TIME 62 9858
TEST_TIME 63 9824
TEST_TIME 64 9887
TEST_TIME 65 9969
TEST_TIME 66 9971
TEST_TIME 67 9884
TEST_TIME 68 9790
TEST_TIME 69 9724
TEST_TIME 70 9655
TEST_TIME 71 9660
TEST_TIME 72 9607
TEST_TIME 73 9575
TEST_TIME 74 9554
TEST_TIME 75 9504
TEST_TIME 76 9503
TEST_TIME 77 9487
TEST_TIME 78 9501
TEST_TIME 79 9549
TEST_TIME 80 9570
TEST_TIME 81 9579
TEST_TIME 82 9579
TEST_TIME 83 9534
TEST_TIME 84 9592
TEST_TIME 85 9619
TEST_TIME 86 9707
TEST_TIME 87 9661
TEST_TIME 88 9727
TEST_TIME 89 9671
TEST_TIME 90 9605
TEST_TIME 91 9584
TEST_TIME 92 9544
TEST_TIME 93 9537
TEST_TIME 94 9463
TEST_TIME 95 9398
TEST_TIME 96 9367
TEST_TIME 97 9430
TEST_TIME 98 9460
TEST_TIME 99 9466
TEST_TIME 100 9437
TEST_TIME 101 9439
TEST_TIME 102 9434
TEST_TIME 103 9432
TEST_TIME 104 9460
TEST_TIME 105 9428
TEST_TIME 106 9483
TEST_TIME 107 9494
TEST_TIME 108 9531
TEST_TIME 109 9449
TEST_TIME 110 9415
TEST_TIME 111 9388
TEST_TIME 112 9316
TEST_TIME 113 9293
TEST_TIME 114 9251
TEST_TIME 115 9297
TEST_TIME 116 9303
TEST_TIME 117 9297
TEST_TIME 118 9278
TEST_TIME 119 9243
TEST_TIME 120 9174

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-08-2021 10:38:16 to 31-08-2021 10:38:20.

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 1522573 1522573 0 0.00 0 17116 7 0 0
doc_list_biud 1522573 1522573 0 0.00 0 56778 34 0 0
sp_add_invoice_to_stock 5048 4941 107 2.11 47 209150 19628 0 107
sp_cancel_adding_invoice 5631 5631 0 0.00 5 85624 920 0 0
sp_cancel_client_order 1727 1727 0 0.00 4 8829 363 0 0
sp_cancel_customer_reserve 559069 559069 0 0.00 1 56780 73 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 6164 93 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 11952 155 0 0
sp_cancel_supplier_invoice 6313 6309 4 0.06 2 16930 1000 0 4
sp_cancel_supplier_order 413 413 0 0.00 6 937154 124028 0 0
sp_cancel_write_off 8926 8926 0 0.00 1 12598 140 0 0
sp_client_order 20461 20461 0 0.00 4 28843 394 0 0
sp_customer_reserve 456613 456613 0 0.00 0 105135 192 0 0
sp_fill_shopping_cart 31271 31271 0 0.00 1 18369 49 0 0
sp_fill_shopping_cart_clo_res 456607 456607 0 0.00 0 15220 17 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 29735 340 0 0
sp_kill_cost_storno 15631 15631 0 0.00 0 7434 70 0 0
sp_kill_qstorno_ret_qs2qd 1133786 1133786 0 0.00 0 56772 39 0 0
sp_kill_qty_storno 596934 596934 0 0.00 0 56777 85 0 0
sp_lock_selected_doc 20421 20421 0 0.00 0 9170 6 0 0
sp_make_cost_storno 27772 27772 0 0.00 0 16904 66 0 0
sp_multiply_rows_for_pdistr 27772 27772 0 0.00 0 7939 35 0 0
sp_multiply_rows_for_qdistr 20461 20461 0 0.00 0 21212 198 0 0
sp_pay_from_customer 6966 6966 0 0.00 4 13411 132 0 0
sp_pay_to_supplier 5947 5947 0 0.00 5 10783 140 0 0
sp_payment_common 12913 12913 0 0.00 1 10871 28 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 8821 331 0 0
sp_qd_handle_on_invoice_upd_sts 10565 10565 0 0.00 0 22068 398 0 0
sp_qd_handle_on_reserve_upd_sts 9925 9925 0 0.00 0 19059 65 0 0
sp_reserve_write_off 9925 9925 0 0.00 7 19079 207 0 0
sp_supplier_invoice 5436 5436 0 0.00 6 81181 4061 0 0
sp_supplier_order 5400 5400 0 0.00 6 35948 1445 0 0
srv_aggregate_perf_data 112 112 0 0.00 2649 173892 15804 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2032 118983 43600 0 0
srv_find_qd_qs_mism 1052773 1052773 0 0.00 0 16682 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 56 91726 3977 0 0
srv_make_money_saldo 739 739 0 0.00 1 19830 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 179545 417328 285216 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4941 4941 0 0.00 0 13388 183 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 12801 162 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 3653 15 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 11515 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 10658 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 83 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 4126 25 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 29443 1896 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 12283 16 0 0
t$perf-norm:sp_client_order 20461 20461 0 0.00 0 6539 20 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 7539 16 0 0
t$perf-norm:sp_pay_from_customer 6966 6966 0 0.00 0 6087 10 0 0
t$perf-norm:sp_pay_to_supplier 5947 5947 0 0.00 0 6095 13 0 0
t$perf-norm:sp_reserve_write_off 9925 9925 0 0.00 0 5728 13 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 11037 23 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 6872 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 138 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4081 63 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 1993 13 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 3119 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10080 9866 214 2.12 0 14133 18 0 214
v_all_customers 37174 37174 0 0.00 0 24 1 0 0
v_all_suppliers 30074 30074 0 0.00 0 24 1 0 0
v_all_wares 40922 40922 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 739 9 0 0
v_cancel_client_order 3454 3454 0 0.00 0 6605 8 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 579 6 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 6148 13 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 2633 13 0 0
v_cancel_supplier_invoice 796 788 8 1.00 0 3374 19 0 8
v_cancel_supplier_order 826 826 0 0.00 0 1186 5 0 0
v_cancel_write_off 1876 1876 0 0.00 0 6292 20 0 0
v_max_id_clo_ord 5395 5395 0 0.00 0 1835 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 4386 23 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 5509 8 0 0
v_max_non_paid_invoice 1720 1720 0 0.00 0 506 2 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 1357 3 0 0
v_min_id_clo_ord 5395 5395 0 0.00 0 5199 10 0 0
v_min_id_clo_res 989 989 0 0.00 0 4956 21 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 7125 8 0 0
v_min_non_paid_invoice 5947 5947 0 0.00 0 5719 20 0 0
v_min_non_paid_realizn 6966 6966 0 0.00 0 10867 22 0 0
v_our_firm 10104 10104 0 0.00 0 18 1 0 0
v_reserve_write_off 19850 19850 0 0.00 0 9781 6 0 0
x_lock_depdocs_on_canc_invoice 11546 11546 0 0.00 0 11579 114 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 7883 381 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 2 35943 1358 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 3 81058 3867 0 0
x_make_qstorno_2100_3300 456613 456613 0 0.00 0 105132 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-08-2021 10:38:21 to 31-08-2021 10:38:25.

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:08 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 17192 8192000000 9706543488 10001117184 787170464 8.766 100 88 176 0 816474736 7954672 21349600
08:09:35 2305 8192000000 9742610336 10044104704 820882528 9.107 100 87 174 0 853818528 12212208 20723792
08:10:47 24747 8192000000 9797696592 10108436480 857626976 9.476 100 89 178 0 897142256 18869664 20645616
08:12:17 18452 8192000000 9858889152 10185113600 898280176 9.881 100 89 174 2 964031056 46715712 19035168
08:13:40 8130 8192000000 9898845744 10240733184 934192832 10.236 100 79 156 1 1011679888 60295792 17191264
08:14:52 5431 8192000000 9926328112 10276093952 976276512 10.648 100 75 150 0 1044984352 53122400 15585440
08:15:59 4719 8192000000 9994630944 10349461504 1008980000 10.966 100 84 168 0 1101694624 75533728 17180896
08:17:07 8776 8192000000 10002666112 10366312448 1036229856 11.228 100 82 163 0 1114772672 62365936 16176880
08:18:17 2238 8192000000 10058443904 10429050880 1063683568 11.492 100 97 193 2 1173839040 91265632 18889840
08:19:21 15653 8192000000 9992042720 10372775936 1065248400 11.507 100 51 103 1 1121616240 45767664 10600176
08:20:38 5095 8192000000 9996428304 10389291008 1070028176 11.552 100 40 82 1 1111383040 33634736 7720128
08:21:44 5127 8192000000 10004032144 10393329664 1081535136 11.662 100 62 124 0 1112365664 17322656 13507872
08:22:52 6800 8192000000 9997105232 10397650944 1085887008 11.704 100 33 66 0 1109968752 16545872 7535872
08:24:04 51984 8192000000 10000225936 10407776256 1083976960 11.685 99 27 54 2 1100734080 10163600 6593520
08:25:57 8559 8192000000 10029965664 10440437760 1103206000 11.868 100 35 67 2 1131026160 20621104 7199056
08:27:07 5178 8192000000 10030914640 10443796480 1104920656 11.884 100 49 94 6 1154193376 39199552 10073168
08:28:13 7254 8192000000 10000031472 10403172352 1079313328 11.641 98 24 47 1 1115170784 30123312 5734144
08:29:21 21851 8192000000 9985124144 10392264704 1042694480 11.291 96 36 72 1 1075703680 24765184 8244016
08:30:43 5294 8192000000 9966672592 10340511744 1023506048 11.106 98 55 107 1 1071857856 36680512 11671296
08:31:49 4461 8192000000 9909006144 10284396544 981749712 10.701 97 45 92 2 1009581952 17211488 10620752
08:32:57 57938 8192000000 9946613008 10343432192 1032018112 11.188 100 31 62 0 1062098016 23884288 6195616
08:34:55 4671 8192000000 9965272704 10362916864 1039628816 11.261 100 45 91 2 1083890688 34895104 9366768
08:36:03 5481 8192000000 9987115344 10384691200 1056481104 11.423 100 58 116 0 1100394704 31798624 12114976
08:37:10 34071 8192000000 9993894384 10386558976 1077266768 11.621 100 43 86 1 1099839168 13438592 9133808
08:38:50 118983 8192000000 10082454640 10496991232 1100833360 11.846 100 50 100 0 1169913600 58063728 11016512
08:41:53 17535 8192000000 10118920544 10535772160 1117804800 12.006 100 36 71 0 1226247088 99924496 8517792
08:43:14 20774 8192000000 10112953984 10561728512 1115311184 11.983 100 57 114 1 1237094656 108476944 13306528
08:44:42 25309 8192000000 10157924640 10569990144 1134956960 12.168 100 45 90 0 1248659872 103770912 9932000
08:46:16 24334 8192000000 10207250912 10626854912 1148860608 12.299 100 52 103 1 1305430400 144901472 11668320
08:47:46 6171 8192000000 10232927200 10647224320 1154182448 12.349 100 60 114 0 1337023040 169614896 13225696
08:48:58 5844 8192000000 10251058320 10661330944 1162255792 12.424 100 72 141 1 1354346944 176362816 15728336
08:50:07 4069 8192000000 10263140560 10686611456 1173200240 12.527 100 67 131 0 1368711360 180419008 15092112
08:51:16 3610 8192000000 10268891584 10700394496 1179604656 12.587 100 64 125 0 1364200160 170149568 14445936
08:52:24 4590 8192000000 10285054992 10697678848 1179692784 12.587 100 67 134 1 1385680880 190605952 15382144
08:53:35 3300 8192000000 10310443392 10721964032 1176228976 12.555 100 87 174 0 1409147312 212575552 20342784
08:54:45 2032 8192000000 10324859392 10729684992 1167322880 12.472 100 89 178 1 1416770400 227241168 22206352
08:55:51 4033 8192000000 10336421216 10756587520 1162453504 12.426 100 54 108 0 1437966976 262426624 13086848
08:57:00 5021 8192000000 10315101616 10730229760 1130647840 12.127 98 68 132 2 1407902160 261421008 15833312
08:58:10 4810 8192000000 10406618432 10807578624 1149609072 12.306 100 90 180 0 1512119728 341553424 20957232
08:59:22 5635 8192000000 10311086592 10734653440 1146152368 12.273 100 56 112 1 1413144480 253905856 13086256
09:00:49 4415 8192000000 10365174592 10773831680 1142594464 12.240 99 74 148 1 1457390416 297901840 16894112
09:02:02 17166 8192000000 10352426144 10792292352 1147280416 12.284 100 36 70 0 1450199728 294181520 8737792
09:03:21 20556 8192000000 10355651888 10829012992 1148188992 12.292 100 36 70 1 1516803168 359353984 9260192
09:04:48 20315 8192000000 10357184256 10814754816 1136650912 12.184 100 27 54 1 1468766576 324469248 7646416
09:06:42 12129 8192000000 10217680720 10734800896 1136618736 12.184 100 16 32 0 1333362032 192789680 3953616
09:08:01 20164 8192000000 10118654544 10574151680 1126768432 12.091 99 27 51 1 1210385344 78700528 4916384
09:09:22 28283 8192000000 10083818832 10524758016 1125519888 12.079 100 27 53 4 1166733984 35556272 5657824
09:10:57 77571 8192000000 10057472176 10499579904 1102364240 11.860 97 10 20 0 1135704352 31614368 1725744
09:13:18 98787 8192000000 10100619200 10549518336 1125374896 12.078 99 21 38 2 1191556384 62136016 4045472
09:16:00 103402 8192000000 10097080816 10546962432 1135986064 12.178 99 46 92 1 1193196368 47272608 9937696
09:18:48 104885 8192000000 10089715728 10532429824 1142695536 12.241 99 22 44 0 1191015936 43465392 4855008
09:21:35 63103 8192000000 10170657136 10598825984 1151062800 12.319 99 37 74 0 1273292720 113177568 9052352
09:23:39 39215 8192000000 10195608368 10633134080 1171111744 12.507 100 54 108 1 1301406448 117667280 12627424
09:25:32 32566 8192000000 10132085744 10625785856 1153652992 12.344 100 14 27 0 1255879040 98893984 3332064
09:27:05 12698 8192000000 10113080432 10587099136 1149521392 12.305 100 18 36 2 1221225760 67376304 4328064
09:28:19 66798 8192000000 10133192272 10582544384 1142809760 12.242 99 18 36 0 1214225424 67608128 3807536
09:30:34 56820 8192000000 10080592960 10596589568 1128314096 12.105 98 8 16 1 1214717808 84415200 1988512
09:32:36 54855 8192000000 10098680384 10606534656 1134866368 12.167 99 17 34 2 1221172560 82628016 3678176
09:34:41 59055 8192000000 10162780432 10648764416 1150906592 12.318 100 36 71 1 1266344640 108217072 7220976
09:36:44 42548 8192000000 10136493072 10597462016 1130145008 12.123 98 38 75 1 1207983120 70087792 7750320
09:38:29 55133 8192000000 10075740704 10593677312 1110606272 11.938 97 20 38 2 1163221920 48807136 3808512
09:40:39 63374 8192000000 10129090624 10591297536 1094410432 11.785 97 12 24 0 1177896640 80571488 2914720
09:42:46 42965 8192000000 10089990016 10516934656 1094804448 11.788 98 44 88 1 1159982000 56242304 8935248
09:44:33 91090 8192000000 10062063728 10492788736 1113532160 11.966 100 45 89 1 1170919744 47964640 9422944
09:47:16 4597 8192000000 10075774992 10506084352 1113994480 11.970 99 42 84 1 1168000128 45807872 8197776
09:48:24 88558 8192000000 10087773536 10540515328 1112452752 11.956 99 15 30 1 1192646928 76767360 3426816
09:50:53 51328 8192000000 10123835216 10596233216 1128131808 12.104 99 29 58 0 1230101360 95997296 5972256
09:53:01 42674 8192000000 10100565456 10552909824 1121846000 12.044 99 18 36 0 1197946640 71998848 4101792
09:55:12 100953 8192000000 10097746688 10589642752 1102894640 11.865 97 18 35 1 1212487024 105431504 4160880
09:57:56 60086 8192000000 10059093792 10521149440 1107585744 11.910 98 12 24 1 1151324752 41147504 2591504
09:59:57 56426 8192000000 10108031904 10557796352 1127109472 12.094 100 27 54 1 1202759152 70062288 5587392
10:01:59 55740 8192000000 10087032352 10568294400 1109039472 11.923 98 23 46 0 1165175872 51522240 4614160
10:03:54 36810 8192000000 10078153808 10502463488 1111769904 11.949 98 36 72 0 1155600608 35725440 8105264
10:05:41 66071 8192000000 10082984832 10540843008 1097594400 11.815 97 28 56 0 1152580464 48822928 6163136
10:08:00 80689 8192000000 10075859216 10501668864 1094244784 11.783 96 26 52 0 1141528704 41370576 5913344
10:10:24 39298 8192000000 10060461312 10523557888 1120253232 12.029 98 11 22 0 1161549904 39397360 1899312
10:12:03 62784 8192000000 10126659568 10582556672 1148719312 12.297 100 15 30 0 1221467440 69672592 3075536
10:14:07 80710 8192000000 10095853888 10577383424 1143372112 12.247 99 19 38 1 1212387504 65095216 3920176
10:16:37 30907 8192000000 10074106064 10534223872 1135015296 12.169 98 43 86 0 1178960224 35194288 8750640
10:18:19 74566 8192000000 10100541072 10592247808 1120261552 12.029 97 16 32 0 1198025584 74444336 3319696
10:20:35 16356 8192000000 10116482160 10549649408 1149650512 12.306 100 42 84 0 1190210528 32404944 8155072
10:21:52 38063 8192000000 10130398320 10561511424 1117375744 12.002 97 31 62 0 1183242784 59522400 6344640
10:23:30 49420 8192000000 10110293664 10549157888 1103870272 11.874 96 24 47 4 1178700736 69763504 5066960
10:25:21 98711 8192000000 10066119008 10544513024 1126273024 12.086 99 16 32 0 1168459600 38643008 3543568
10:28:05 64356 8192000000 10067554896 10542493696 1112451936 11.956 99 9 18 0 1155117568 40654000 2011632
10:30:27 48158 8192000000 10097903360 10553544704 1110173984 11.934 98 9 18 0 1170867968 58621888 2072096
10:32:40 83972 8192000000 10048999360 10494013440 1065550336 11.510 95 13 26 0 1127552192 59399824 2602032
10:35:09 39660 8192000000 10096080816 10531348480 1098896544 11.827 97 38 76 1 1167588608 60603456 8088608
10:36:49 28599 8192000000 9159441648 9465028608 295935328 3.486 26 15 30 0 335535136 36525312 3074496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-08-2021 10:38:27 to 31-08-2021 10:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-08-2021 10:38:43 to 31-08-2021 10:38:45.

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          233299
MON$OLDEST_ACTIVE               233300
MON$OLDEST_SNAPSHOT             233300
MON$NEXT_TRANSACTION            233300
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                       2062560
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2577 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2577 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2577 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2577 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Aug 31 10:37:34 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Aug 31 10:37:34 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

31.08.21 10:38:46. 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-08-31 10:38:48 No severe PSQL-related problems occured
31.08.21 10:38:50. End of report.