Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 07:48
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 16207220 kB
MemAvailable 30927844 kB
Buffers 0 kB
Cached 15531232 kB
SwapCached 340 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-07 07:46:43
DTS_END                         2021-09-07 07:46:43.0920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55830
TRN_ID                          227445
ATT_ID                          604
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: 8736.33 28237 1048360
customer order: creation 155.56 442 18667
customer order: refuse 13.42 381 1610
order to supplier: creation 43.09 1431 5171
order to supplier: removal 3.49 129027 419
invoice (draft): creation 43.96 3768 5275
invoice (draft): removal 50.03 1153 6003
invoice accept: apply 39.06 21655 4687
invoice accept: cancel 45.54 947 5465
customer reserve: creation 3616.47 213 433976
customer reserve: removal 4443.90 79 533268
realization accept: apply 77.60 210 9312
realization accept: cancel 81.90 130 9828
payment from customer: creation 56.39 110 6767
payment from customer: removal 5.33 94 639
payment to supplier: creation 46.29 125 5555
payment to supplier: removal 3.29 112 395
service: total inventory turnovers 3.89 3656 467
service: total monetary turnovers 6.15 602 738
service: aggregate perf. data 0.94 16268 113
service: refresh index statistics 0.04 384335 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-09-2021 07:48:53 to 07-09-2021 07:48:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1502
TEST_TIME 2 1287
TEST_TIME 3 2279
TEST_TIME 4 3139
TEST_TIME 5 3272
TEST_TIME 6 3382
TEST_TIME 7 3631
TEST_TIME 8 3956
TEST_TIME 9 4482
TEST_TIME 10 4430
TEST_TIME 11 5056
TEST_TIME 12 5339
TEST_TIME 13 5774
TEST_TIME 14 6183
TEST_TIME 15 6209
TEST_TIME 16 6354
TEST_TIME 17 6545
TEST_TIME 18 6757
TEST_TIME 19 6844
TEST_TIME 20 6961
TEST_TIME 21 7534
TEST_TIME 22 7920
TEST_TIME 23 8096
TEST_TIME 24 8217
TEST_TIME 25 8102
TEST_TIME 26 8181
TEST_TIME 27 8158
TEST_TIME 28 8361
TEST_TIME 29 9019
TEST_TIME 30 10469
TEST_TIME 31 10322
TEST_TIME 32 10072
TEST_TIME 33 9844
TEST_TIME 34 9707
TEST_TIME 35 9574
TEST_TIME 36 9517
TEST_TIME 37 9562
TEST_TIME 38 9648
TEST_TIME 39 9600
TEST_TIME 40 9470
TEST_TIME 41 9394
TEST_TIME 42 9604
TEST_TIME 43 9516
TEST_TIME 44 9414
TEST_TIME 45 9545
TEST_TIME 46 9633
TEST_TIME 47 9608
TEST_TIME 48 9558
TEST_TIME 49 9515
TEST_TIME 50 9540
TEST_TIME 51 9612
TEST_TIME 52 9582
TEST_TIME 53 9627
TEST_TIME 54 9720
TEST_TIME 55 9748
TEST_TIME 56 9766
TEST_TIME 57 9731
TEST_TIME 58 9748
TEST_TIME 59 9611
TEST_TIME 60 9507
TEST_TIME 61 9406
TEST_TIME 62 9317
TEST_TIME 63 9389
TEST_TIME 64 9388
TEST_TIME 65 9298
TEST_TIME 66 9213
TEST_TIME 67 9203
TEST_TIME 68 9187
TEST_TIME 69 9394
TEST_TIME 70 9343
TEST_TIME 71 9375
TEST_TIME 72 9378
TEST_TIME 73 9403
TEST_TIME 74 9393
TEST_TIME 75 9392
TEST_TIME 76 9412
TEST_TIME 77 9454
TEST_TIME 78 9414
TEST_TIME 79 9331
TEST_TIME 80 9248
TEST_TIME 81 9254
TEST_TIME 82 9232
TEST_TIME 83 9177
TEST_TIME 84 9110
TEST_TIME 85 9084
TEST_TIME 86 9045
TEST_TIME 87 9088
TEST_TIME 88 9034
TEST_TIME 89 9069
TEST_TIME 90 9072
TEST_TIME 91 9071
TEST_TIME 92 9025
TEST_TIME 93 9054
TEST_TIME 94 9121
TEST_TIME 95 9086
TEST_TIME 96 9112
TEST_TIME 97 9103
TEST_TIME 98 9056
TEST_TIME 99 8995
TEST_TIME 100 8980
TEST_TIME 101 8974
TEST_TIME 102 8910
TEST_TIME 103 8905
TEST_TIME 104 8892
TEST_TIME 105 8897
TEST_TIME 106 8943
TEST_TIME 107 8907
TEST_TIME 108 8948
TEST_TIME 109 8917
TEST_TIME 110 8915
TEST_TIME 111 8911
TEST_TIME 112 8919
TEST_TIME 113 8937
TEST_TIME 114 8954
TEST_TIME 115 8943
TEST_TIME 116 8889
TEST_TIME 117 8858
TEST_TIME 118 8841
TEST_TIME 119 8784
TEST_TIME 120 8741

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-09-2021 07:48:57 to 07-09-2021 07:49:00.

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 1449107 1449107 0 0.00 0 31446 8 0 0
doc_list_biud 1449102 1449102 0 0.00 0 53598 37 0 0
sp_add_invoice_to_stock 4765 4687 78 1.63 33 241875 21655 0 78
sp_cancel_adding_invoice 5465 5465 0 0.00 5 61942 947 0 0
sp_cancel_client_order 1610 1610 0 0.00 4 13147 381 0 0
sp_cancel_customer_reserve 533268 533268 0 0.00 1 44706 79 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 6142 95 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 8189 113 0 0
sp_cancel_supplier_invoice 6009 6003 6 0.09 2 53610 1153 0 6
sp_cancel_supplier_order 419 419 0 0.00 15 1245788 129028 0 0
sp_cancel_write_off 9828 9828 0 0.00 1 8565 131 0 0
sp_client_order 18667 18667 0 0.00 4 27764 443 0 0
sp_customer_reserve 433976 433976 0 0.00 0 85975 214 0 0
sp_fill_shopping_cart 29087 29087 0 0.00 1 24591 49 0 0
sp_fill_shopping_cart_clo_res 433973 433973 0 0.00 0 17442 17 0 0
sp_get_clo_for_invoice 4680 4680 0 0.00 0 32823 424 0 0
sp_kill_cost_storno 16327 16327 0 0.00 0 6469 68 0 0
sp_kill_qstorno_ret_qs2qd 1082786 1082786 0 0.00 0 53596 43 0 0
sp_kill_qty_storno 570580 570580 0 0.00 0 53597 92 0 0
sp_lock_selected_doc 19373 19373 0 0.00 0 9706 6 0 0
sp_make_cost_storno 26314 26314 0 0.00 0 31433 66 0 0
sp_multiply_rows_for_pdistr 26310 26310 0 0.00 0 10931 34 0 0
sp_multiply_rows_for_qdistr 18667 18667 0 0.00 0 27756 237 0 0
sp_pay_from_customer 6767 6767 0 0.00 4 21855 111 0 0
sp_pay_to_supplier 5555 5555 0 0.00 5 22108 125 0 0
sp_payment_common 12322 12322 0 0.00 1 11163 22 0 0
sp_qd_handle_on_cancel_clo 1610 1610 0 0.00 0 13141 342 0 0
sp_qd_handle_on_invoice_upd_sts 10142 10142 0 0.00 0 34317 400 0 0
sp_qd_handle_on_reserve_upd_sts 9310 9310 0 0.00 0 19766 60 0 0
sp_reserve_write_off 9312 9312 0 0.00 6 22126 211 0 0
sp_supplier_invoice 5275 5275 0 0.00 8 106664 3769 0 0
sp_supplier_order 5171 5171 0 0.00 5 80661 1431 0 0
srv_aggregate_perf_data 113 113 0 0.00 2870 171411 16269 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1291 147798 45479 0 0
srv_find_qd_qs_mism 1003178 1003178 0 0.00 0 22875 7 0 0
srv_make_invnt_saldo 467 467 0 0.00 41 112646 3656 0 0
srv_make_money_saldo 738 738 0 0.00 1 13918 603 0 0
srv_recalc_idx_stat 5 5 0 0.00 169139 651186 384335 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4687 4687 0 0.00 0 24193 204 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 6804 113 0 0
t$perf-norm:sp_cancel_client_order 1610 1610 0 0.00 0 847 11 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 5729 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 995 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 377 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 340 340 0 0.00 0 1899 23 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 28586 1786 0 0
t$perf-norm:sp_cancel_write_off 904 904 0 0.00 0 1554 8 0 0
t$perf-norm:sp_client_order 18667 18667 0 0.00 0 24387 23 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 12346 22 0 0
t$perf-norm:sp_pay_from_customer 6767 6767 0 0.00 0 8913 12 0 0
t$perf-norm:sp_pay_to_supplier 5555 5555 0 0.00 0 10413 14 0 0
t$perf-norm:sp_reserve_write_off 9312 9312 0 0.00 0 9270 13 0 0
t$perf-norm:sp_supplier_invoice 5275 5275 0 0.00 0 11626 24 0 0
t$perf-norm:sp_supplier_order 5171 5171 0 0.00 0 8197 25 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 1170 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 520 12 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 2126 13 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 15327 26 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 9508 9352 156 1.64 0 9756 15 0 156
v_all_customers 34588 34588 0 0.00 0 39 1 0 0
v_all_suppliers 28972 28972 0 0.00 0 36 1 0 0
v_all_wares 37334 37334 0 0.00 0 86 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2189 9 0 0
v_cancel_client_order 3220 3220 0 0.00 0 2232 6 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 3027 6 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 2495 9 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 3524 15 0 0
v_cancel_supplier_invoice 692 680 12 1.73 0 3046 21 0 12
v_cancel_supplier_order 838 838 0 0.00 0 2442 13 0 0
v_cancel_write_off 1808 1808 0 0.00 0 4931 23 0 0
v_max_id_clo_ord 5171 5171 0 0.00 0 4687 3 0 0
v_max_id_clo_res 972 972 0 0.00 0 8816 32 0 0
v_max_id_ord_sup 5249 5249 0 0.00 0 8736 8 0 0
v_max_non_paid_invoice 1489 1489 0 0.00 0 310 2 0 0
v_max_non_paid_realizn 3484 3484 0 0.00 0 1038 3 0 0
v_min_id_clo_ord 5171 5171 0 0.00 0 4535 9 0 0
v_min_id_clo_res 972 972 0 0.00 0 5510 21 0 0
v_min_id_ord_sup 5249 5249 0 0.00 0 7299 10 0 0
v_min_non_paid_invoice 5555 5555 0 0.00 0 11145 14 0 0
v_min_non_paid_realizn 6767 6767 0 0.00 0 10897 16 0 0
v_our_firm 9312 9312 0 0.00 0 16 1 0 0
v_reserve_write_off 18620 18620 0 0.00 0 3268 5 0 0
x_lock_depdocs_on_canc_invoice 11128 11128 0 0.00 0 12533 136 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 7155 388 0 0
x_make_qstorno_1000_1200 5171 5171 0 0.00 2 78504 1336 0 0
x_make_qstorno_1200_2000 5275 5275 0 0.00 4 106593 3595 0 0
x_make_qstorno_2100_3300 433976 433976 0 0.00 0 85973 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 07:49:01 to 07-09-2021 07:49:05.

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
05:16:46 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:47 20727 8192000000 9719577120 10018791424 794888832 8.844 100 84 168 0 823311824 7633312 20789680
05:19:15 10195 8192000000 9757517648 10065117184 833707408 9.237 100 87 174 0 865573568 10932272 20933888
05:20:35 20613 8192000000 9806105184 10119806976 866730000 9.567 100 87 174 0 905749088 18887712 20131376
05:22:04 10319 8192000000 9855719200 10185814016 911441920 10.012 100 82 164 0 967043600 37275184 18326496
05:23:24 10686 8192000000 9910186128 10252734464 947306896 10.365 100 84 167 0 1021228240 56214704 17706640
05:24:38 5996 8192000000 9979485472 10329387008 985953952 10.742 100 92 183 2 1086680192 82156992 18569248
05:25:46 1988 8192000000 9992859040 10350682112 1015568624 11.029 100 87 174 0 1120649344 87038464 18042256
05:26:49 5156 8192000000 10016274784 10383593472 1032230096 11.190 100 83 167 0 1131123488 82456848 16436544
05:27:56 1341 8192000000 10031966064 10402766848 1057086192 11.429 100 93 187 0 1142376240 65931792 19358256
05:28:59 20320 8192000000 9987968544 10380767232 1064655552 11.501 100 36 72 0 1120053088 47842928 7554608
05:30:24 4234 8192000000 9989061552 10377768960 1071470512 11.566 100 42 80 0 1106970992 26525168 8975312
05:31:30 5590 8192000000 10001452576 10393194496 1084610224 11.691 100 43 85 2 1126317376 32328944 9378208
05:32:37 68973 8192000000 9995265312 10392121344 1084131072 11.687 100 43 86 2 1105439312 11999904 9308336
05:34:46 3011 8192000000 10021821456 10431963136 1091612352 11.758 100 29 58 0 1135910720 38667616 5630752
05:35:51 3436 8192000000 10011524128 10403655680 1094043568 11.781 100 48 94 2 1118275008 13512064 10719376
05:36:56 15083 8192000000 10000004624 10404978688 1068654304 11.539 98 21 42 0 1096876416 23688320 4533792
05:38:14 36492 8192000000 9987541360 10399690752 1037591072 11.242 95 31 62 1 1068840544 24755600 6493872
05:39:52 7007 8192000000 9965915136 10356203520 1019174224 11.064 98 45 90 0 1054026800 24716560 10136016
05:40:59 10183 8192000000 9914027648 10308820992 961394416 10.503 94 25 48 0 1000014016 33587760 5031840
05:42:10 7703 8192000000 9953856928 10333859840 1023090592 11.102 99 24 49 3 1056872176 28290496 5491088
05:43:17 20145 8192000000 9952507776 10347544576 1022827184 11.099 98 32 64 0 1056455040 26916256 6711600
05:44:40 6612 8192000000 9979294688 10370547712 1055082768 11.409 100 52 104 0 1084694656 17966672 11645216
05:45:46 8875 8192000000 9978974320 10364952576 1064727856 11.502 100 43 84 3 1088385632 14787632 8870144
05:46:58 104185 8192000000 10025043136 10430103552 1079200656 11.640 100 37 74 0 1106656848 19949808 7506384
05:49:57 31296 8192000000 10067221280 10476732416 1105778032 11.892 100 49 97 1 1187733648 71720272 10235344
05:51:33 26984 8192000000 10145970128 10552459264 1124981824 12.074 100 40 79 1 1240052368 105681760 9388784
05:53:10 32447 8192000000 10196500432 10610671616 1136682576 12.184 100 46 92 2 1306576624 159720832 10173216
05:55:10 11652 8192000000 10235161792 10653388800 1142537904 12.239 100 59 118 2 1335099712 179914496 12647312
05:56:29 5231 8192000000 10251711936 10654126080 1157182160 12.377 100 67 132 0 1352927440 181205920 14539360
05:57:43 5269 8192000000 10279064256 10701729792 1165016336 12.450 100 64 125 1 1387912640 208400304 14496000
05:58:55 6125 8192000000 10285824832 10715451392 1162619360 12.428 100 56 112 0 1393097600 217653536 12824704
06:00:08 15597 8192000000 10292173680 10741170176 1188266976 12.667 100 60 120 2 1404225744 202840464 13118304
06:01:29 3016 8192000000 10326281392 10732032000 1194931168 12.729 100 82 162 0 1421713792 209194464 17588160
06:02:40 6026 8192000000 10345593872 10758045696 1195450016 12.734 100 75 148 1 1444165200 231784144 16931040
06:03:52 2230 8192000000 10392137616 10799816704 1197684112 12.755 100 93 184 1 1488333984 269366288 21283584
06:05:05 2214 8192000000 10399528592 10812203008 1190179216 12.685 100 76 150 1 1496097792 288314848 17603728
06:06:13 1290 8192000000 10460571184 10866024448 1185363328 12.640 100 90 179 1 1548360832 342460000 20537504
06:07:19 4217 8192000000 10415834288 10867515392 1184771840 12.635 100 76 152 0 1510987984 308925840 17290304
06:08:29 4734 8192000000 10452705840 10874810368 1179848464 12.589 99 65 130 1 1535898160 340338912 15710784
06:09:58 21456 8192000000 10381611344 10822434816 1175687248 12.550 99 44 87 0 1455977712 269477184 10813280
06:11:31 16826 8192000000 10420629056 10856566784 1173272368 12.527 100 37 74 2 1516050896 333278864 9499664
06:13:04 10078 8192000000 10444434256 10881024000 1143199216 12.246 100 33 66 2 1544962256 392362704 9400336
06:14:25 13159 8192000000 10474774624 10930835456 1136043680 12.178 100 29 58 1 1573810352 428970832 8795840
06:15:43 26254 8192000000 10284662256 10792230912 1128251680 12.105 100 20 40 1 1454255808 320301296 5702832
06:17:14 19540 8192000000 10075061584 10514051072 1134088560 12.160 100 37 70 2 1163189056 20653136 8447360
06:18:39 147796 8192000000 10081661248 10513657856 1068133696 11.534 94 40 80 1 1138960560 61661344 9165520
06:22:09 31451 8192000000 10160410496 10605903872 1141786208 12.232 100 39 77 0 1267929424 117732288 8410928
06:23:46 21264 8192000000 10168213328 10622644224 1124280640 12.067 98 46 91 0 1240752688 106543440 9928608
06:25:11 71903 8192000000 10123446144 10578141184 1115882112 11.988 98 8 16 1 1205682480 88029952 1770416
06:27:32 43504 8192000000 10154715136 10650746880 1144081264 12.254 100 23 46 0 1266561072 116732304 5747504
06:29:21 51197 8192000000 10161535168 10620928000 1144430896 12.257 99 28 56 3 1239363200 88875104 6057200
06:31:12 62859 8192000000 10123942992 10631565312 1147007200 12.281 100 9 18 0 1224263760 75089552 2167008
06:33:16 40851 8192000000 10076058160 10536448000 1130310704 12.124 99 32 64 0 1194262592 56722000 7229888
06:35:03 48185 8192000000 10108888224 10549473280 1103969184 11.875 97 37 74 1 1177768960 65857920 7941856
06:36:55 55457 8192000000 10064931136 10536013824 1119234784 12.020 99 13 26 0 1174266960 52617648 2414528
06:38:51 53563 8192000000 10121169584 10569736192 1143656496 12.250 100 28 54 2 1203358832 54181072 5521264
06:40:55 86894 8192000000 10108616688 10602733568 1142707024 12.241 99 10 20 0 1185950160 41480080 1763056
06:43:23 16825 8192000000 10106581424 10539073536 1146676128 12.278 100 54 107 0 1179864592 21731168 11457296
06:44:53 55465 8192000000 10068686144 10541727744 1145950880 12.271 100 9 18 0 1174903648 27103840 1848928
06:47:05 39994 8192000000 10101963440 10552918016 1134621776 12.165 98 51 102 2 1185677504 40309696 10746032
06:48:47 71592 8192000000 10138196544 10653822976 1156013024 12.366 100 25 50 0 1263050288 101676688 5360576
06:50:59 73099 8192000000 10122951824 10581123072 1145986144 12.272 99 17 32 0 1197998000 48180352 3831504
06:53:15 53072 8192000000 10136435264 10627149824 1109461296 11.927 97 27 54 2 1239064960 122956560 6647104
06:56:24 145909 8192000000 10065971712 10532417536 1072355568 11.575 95 15 30 2 1146068080 70206400 3506112
06:59:56 20748 8192000000 10072041760 10509127680 1113191184 11.963 99 51 102 2 1155491440 31580896 10719360
07:01:18 82294 8192000000 10086023056 10554617856 1107638160 11.910 98 33 66 1 1164289056 49886528 6764368
07:03:44 78132 8192000000 10066128080 10501652480 1045458416 11.317 93 22 44 1 1094124432 43983568 4682448
07:06:14 81567 8192000000 10071602096 10546098176 1121335840 12.040 100 20 40 1 1164384240 39125984 3922416
07:08:40 57506 8192000000 10040650496 10522599424 1111900528 11.950 98 12 24 0 1171298176 57114768 2282880
07:10:41 27310 8192000000 10074324240 10568273920 1129873392 12.120 100 14 28 1 1171364208 38936464 2554352
07:12:36 45184 8192000000 10067631392 10544238592 1129429744 12.116 100 21 42 0 1189608880 55722880 4456256
07:14:24 54583 8192000000 10088654176 10578452480 1140913760 12.224 100 25 51 0 1224982016 78476048 5592208
07:16:25 29936 8192000000 10090383424 10535559168 1134739120 12.166 99 29 58 2 1182879792 42021040 6119632
07:18:02 121720 8192000000 10105875744 10626035712 1108240304 11.916 96 16 32 0 1200462624 88766064 3456256
07:21:05 49342 8192000000 10075659984 10515886080 1097364064 11.813 96 25 48 1 1146893408 44343440 5185904
07:22:55 38251 8192000000 10081523072 10513268736 1083899760 11.685 96 36 72 0 1129258784 38011344 7347680
07:24:36 147644 8192000000 10110111680 10579873792 1120622464 12.033 99 20 39 1 1190755040 65845216 4287360
07:28:04 52004 8192000000 10096809664 10557456384 1120548048 12.032 100 8 16 0 1207136800 84583824 2004928
07:29:56 88069 8192000000 10093395648 10535010304 1084321616 11.689 96 22 43 2 1165738848 76489744 4927488
07:32:30 58026 8192000000 10080782032 10530021376 1129464128 12.116 100 11 22 0 1165200288 33341808 2394352
07:34:34 47680 8192000000 10051979152 10492051456 1108276560 11.916 98 16 32 0 1144880400 33510096 3093744
07:36:23 52940 8192000000 10082879008 10525286400 1101686176 11.854 97 36 72 0 1142320576 33920144 6714256
07:38:18 26978 8192000000 10090180880 10531246080 1131453152 12.135 100 38 76 0 1187741200 49219520 7068528
07:39:54 19829 8192000000 10105034976 10547777536 1108551920 11.919 98 44 88 0 1168647456 51108560 8986976
07:41:15 109711 8192000000 10069115040 10516783104 1107518112 11.909 98 21 42 0 1151040880 39234016 4288752
07:44:24 29809 8192000000 10048212768 10534293504 1115795856 11.987 99 5 9 1 1146294736 29526368 972512
07:46:02 50619 8192000000 9316006288 9661063168 437849456 5.073 39 5 10 1 458879520 19908976 1121088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-09-2021 07:49:07 to 07-09-2021 07:49:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 78 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-09-2021 07:49:23 to 07-09-2021 07:49:25.

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          227683
MON$OLDEST_ACTIVE               227684
MON$OLDEST_SNAPSHOT             227684
MON$NEXT_TRANSACTION            227684
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                       2035168
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2584 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2584 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2584 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2584 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Sep  7 07:47:08 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

07.09.21 07:49:26. 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-07 07:49:28 No severe PSQL-related problems occured
07.09.21 07:49:29. End of report.