Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2021 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15759188 kB
MemAvailable 30723312 kB
Buffers 0 kB
Cached 15944856 kB
SwapCached 4796 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-10-07 10:37:10
DTS_END                         2021-10-07 10:37:10.0660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46530
TRN_ID                          231805
ATT_ID                          684
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: 9274.91 20765 1112989
customer order: creation 169.30 372 20316
customer order: refuse 14.01 380 1681
order to supplier: creation 43.94 1531 5273
order to supplier: removal 3.44 124617 413
invoice (draft): creation 45.61 3748 5473
invoice (draft): removal 52.95 1034 6354
invoice accept: apply 41.99 20801 5039
invoice accept: cancel 47.35 1009 5682
customer reserve: creation 3892.73 204 467128
customer reserve: removal 4679.14 71 561497
realization accept: apply 83.39 243 10007
realization accept: cancel 77.73 127 9328
payment from customer: creation 56.33 164 6759
payment from customer: removal 5.01 115 601
payment to supplier: creation 47.73 187 5727
payment to supplier: removal 3.45 76 414
service: total inventory turnovers 3.48 4817 418
service: total monetary turnovers 6.38 606 765
service: aggregate perf. data 0.92 16185 110
service: refresh index statistics 0.03 239004 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2021 10:39:05 to 07-10-2021 10:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5938
TEST_TIME 2 5305
TEST_TIME 3 4884
TEST_TIME 4 4767
TEST_TIME 5 5056
TEST_TIME 6 5030
TEST_TIME 7 5146
TEST_TIME 8 5157
TEST_TIME 9 5367
TEST_TIME 10 5722
TEST_TIME 11 5861
TEST_TIME 12 5987
TEST_TIME 13 6131
TEST_TIME 14 6480
TEST_TIME 15 6840
TEST_TIME 16 6731
TEST_TIME 17 6985
TEST_TIME 18 7257
TEST_TIME 19 7319
TEST_TIME 20 7417
TEST_TIME 21 7714
TEST_TIME 22 8070
TEST_TIME 23 8429
TEST_TIME 24 8624
TEST_TIME 25 8572
TEST_TIME 26 8709
TEST_TIME 27 8654
TEST_TIME 28 8596
TEST_TIME 29 8423
TEST_TIME 30 8676
TEST_TIME 31 8466
TEST_TIME 32 8830
TEST_TIME 33 9291
TEST_TIME 34 9568
TEST_TIME 35 10149
TEST_TIME 36 10148
TEST_TIME 37 10364
TEST_TIME 38 10305
TEST_TIME 39 10138
TEST_TIME 40 10028
TEST_TIME 41 10016
TEST_TIME 42 9885
TEST_TIME 43 9914
TEST_TIME 44 9861
TEST_TIME 45 9720
TEST_TIME 46 9711
TEST_TIME 47 9686
TEST_TIME 48 9694
TEST_TIME 49 9761
TEST_TIME 50 9798
TEST_TIME 51 9755
TEST_TIME 52 9690
TEST_TIME 53 9589
TEST_TIME 54 9508
TEST_TIME 55 9589
TEST_TIME 56 9553
TEST_TIME 57 9481
TEST_TIME 58 9557
TEST_TIME 59 9576
TEST_TIME 60 9513
TEST_TIME 61 9543
TEST_TIME 62 9572
TEST_TIME 63 9636
TEST_TIME 64 9633
TEST_TIME 65 9726
TEST_TIME 66 9680
TEST_TIME 67 9704
TEST_TIME 68 9709
TEST_TIME 69 9666
TEST_TIME 70 9691
TEST_TIME 71 9794
TEST_TIME 72 9795
TEST_TIME 73 9705
TEST_TIME 74 9660
TEST_TIME 75 9600
TEST_TIME 76 9566
TEST_TIME 77 9549
TEST_TIME 78 9535
TEST_TIME 79 9452
TEST_TIME 80 9475
TEST_TIME 81 9410
TEST_TIME 82 9364
TEST_TIME 83 9312
TEST_TIME 84 9293
TEST_TIME 85 9345
TEST_TIME 86 9330
TEST_TIME 87 9331
TEST_TIME 88 9371
TEST_TIME 89 9348
TEST_TIME 90 9453
TEST_TIME 91 9438
TEST_TIME 92 9437
TEST_TIME 93 9394
TEST_TIME 94 9419
TEST_TIME 95 9511
TEST_TIME 96 9487
TEST_TIME 97 9485
TEST_TIME 98 9521
TEST_TIME 99 9441
TEST_TIME 100 9459
TEST_TIME 101 9411
TEST_TIME 102 9381
TEST_TIME 103 9408
TEST_TIME 104 9358
TEST_TIME 105 9355
TEST_TIME 106 9292
TEST_TIME 107 9249
TEST_TIME 108 9201
TEST_TIME 109 9202
TEST_TIME 110 9254
TEST_TIME 111 9252
TEST_TIME 112 9251
TEST_TIME 113 9304
TEST_TIME 114 9268
TEST_TIME 115 9262
TEST_TIME 116 9352
TEST_TIME 117 9347
TEST_TIME 118 9336
TEST_TIME 119 9351
TEST_TIME 120 9277

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2021 10:39:09 to 07-10-2021 10:39:12.

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 1544377 1544377 0 0.00 0 36381 8 0 0
doc_list_biud 1544376 1544376 0 0.00 0 62474 33 0 0
sp_add_invoice_to_stock 5136 5039 97 1.88 52 220654 20801 0 97
sp_cancel_adding_invoice 5682 5682 0 0.00 6 61001 1009 0 0
sp_cancel_client_order 1681 1681 0 0.00 4 10075 381 0 0
sp_cancel_customer_reserve 561497 561497 0 0.00 1 62476 72 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 3 11974 116 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 2154 77 0 0
sp_cancel_supplier_invoice 6361 6354 7 0.11 3 38482 1034 0 7
sp_cancel_supplier_order 413 413 0 0.00 6 1081754 124617 0 0
sp_cancel_write_off 9328 9328 0 0.00 1 10914 127 0 0
sp_client_order 20316 20316 0 0.00 4 21395 373 0 0
sp_customer_reserve 467128 467128 0 0.00 0 174857 204 0 0
sp_fill_shopping_cart 31041 31041 0 0.00 1 15644 44 0 0
sp_fill_shopping_cart_clo_res 467126 467126 0 0.00 0 18345 14 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 28504 347 0 0
sp_kill_cost_storno 16025 16025 0 0.00 0 20386 74 0 0
sp_kill_qstorno_ret_qs2qd 1139089 1139089 0 0.00 0 62466 39 0 0
sp_kill_qty_storno 599999 599999 0 0.00 0 62471 85 0 0
sp_lock_selected_doc 20586 20586 0 0.00 0 12934 6 0 0
sp_make_cost_storno 27530 27530 0 0.00 0 36302 89 0 0
sp_multiply_rows_for_pdistr 27530 27530 0 0.00 0 9361 42 0 0
sp_multiply_rows_for_qdistr 20316 20316 0 0.00 0 18433 181 0 0
sp_pay_from_customer 6759 6759 0 0.00 4 34064 165 0 0
sp_pay_to_supplier 5727 5727 0 0.00 5 29705 188 0 0
sp_payment_common 12486 12486 0 0.00 1 25205 39 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 10064 355 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 48342 416 0 0
sp_qd_handle_on_reserve_upd_sts 10007 10007 0 0.00 0 26113 66 0 0
sp_reserve_write_off 10007 10007 0 0.00 7 26165 244 0 0
sp_supplier_invoice 5473 5473 0 0.00 9 80751 3748 0 0
sp_supplier_order 5273 5273 0 0.00 6 32148 1531 0 0
srv_aggregate_perf_data 110 110 0 0.00 2632 106318 16186 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 947 143965 44576 0 0
srv_find_qd_qs_mism 1065448 1065448 0 0.00 0 14648 6 0 0
srv_make_invnt_saldo 418 418 0 0.00 56 89633 4817 0 0
srv_make_money_saldo 765 765 0 0.00 1 12716 606 0 0
srv_recalc_idx_stat 4 4 0 0.00 129558 383715 239005 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 14765 181 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 9687 120 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 5430 14 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 1800 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 6967 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 3295 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 522 12 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 24897 1719 0 0
t$perf-norm:sp_cancel_write_off 916 916 0 0.00 0 7127 24 0 0
t$perf-norm:sp_client_order 20316 20316 0 0.00 0 6505 22 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2792 11 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 5583 13 0 0
t$perf-norm:sp_pay_to_supplier 5727 5727 0 0.00 0 9581 13 0 0
t$perf-norm:sp_reserve_write_off 10007 10007 0 0.00 0 7427 13 0 0
t$perf-norm:sp_supplier_invoice 5473 5473 0 0.00 0 5077 20 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 6204 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 195 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 199 7 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 1941 16 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 2043 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 648 166 0 0
v_add_invoice_to_stock 10268 10074 194 1.88 0 8543 20 0 194
v_all_customers 36192 36192 0 0.00 0 164 1 0 0
v_all_suppliers 29662 29662 0 0.00 0 23 1 0 0
v_all_wares 40630 40630 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 3325 13 0 0
v_cancel_client_order 3362 3362 0 0.00 0 5599 6 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 5977 10 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 1069 5 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1144 5 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 1943 16 0 14
v_cancel_supplier_order 826 826 0 0.00 0 1973 6 0 0
v_cancel_write_off 1832 1832 0 0.00 0 6760 19 0 0
v_max_id_clo_ord 5268 5268 0 0.00 0 1693 2 0 0
v_max_id_clo_res 947 947 0 0.00 0 4267 18 0 0
v_max_id_ord_sup 5456 5456 0 0.00 0 10257 6 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 6341 7 0 0
v_max_non_paid_realizn 3829 3829 0 0.00 0 2001 4 0 0
v_min_id_clo_ord 5268 5268 0 0.00 0 9038 10 0 0
v_min_id_clo_res 947 947 0 0.00 0 8138 33 0 0
v_min_id_ord_sup 5456 5456 0 0.00 0 10356 13 0 0
v_min_non_paid_invoice 5727 5727 0 0.00 0 14834 30 0 0
v_min_non_paid_realizn 6759 6759 0 0.00 0 17579 27 0 0
v_our_firm 10298 10298 0 0.00 0 53 1 0 0
v_reserve_write_off 20014 20014 0 0.00 0 3577 6 0 0
x_lock_depdocs_on_canc_invoice 11643 11643 0 0.00 0 18560 121 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 5908 373 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 2 32138 1439 0 0
x_make_qstorno_1200_2000 5473 5473 0 0.00 4 80738 3586 0 0
x_make_qstorno_2100_3300 467128 467128 0 0.00 0 174855 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2021 10:39:13 to 07-10-2021 10:39:17.

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:11 3 8192000000 8799770832 8811630592 5568240 0.067 2 2 3 0 5702848 70688 63920
08:08:13 41890 8192000000 9727111392 10029715456 795774736 8.853 100 85 170 0 824925520 7470064 21680720
08:10:00 18865 8192000000 9765568624 10074669056 837583296 9.275 100 87 174 0 869573360 11244288 20745776
08:11:31 25286 8192000000 9814302496 10134241280 872085392 9.621 100 86 172 0 914527008 22617568 19824048
08:12:59 18501 8192000000 9866305696 10201747456 908432544 9.982 100 75 150 0 967909792 42911424 16565824
08:14:27 11397 8192000000 9910316976 10257965056 959712880 10.486 100 73 146 3 1030028192 54931904 15383408
08:15:43 2845 8192000000 9950872272 10310361088 996703584 10.847 100 73 146 0 1063828496 51895328 15229584
08:16:49 3348 8192000000 10008638576 10372927488 1027674144 11.146 100 85 170 0 1122148672 77241760 17232768
08:17:53 1874 8192000000 10023270672 10390675456 1046363488 11.326 100 92 184 1 1134599984 69041680 19194816
08:18:57 2906 8192000000 10041364832 10421587968 1063932320 11.494 100 74 147 1 1160337552 81296848 15108384
08:20:02 5647 8192000000 9980362816 10382262272 1069052064 11.543 100 35 70 2 1116568208 40426752 7089392
08:21:12 5669 8192000000 9988315264 10380447744 1078890112 11.637 100 45 91 3 1115450912 26047232 10513568
08:22:18 8182 8192000000 9995413360 10393337856 1090500224 11.747 100 38 77 1 1118327856 19342512 8485120
08:23:29 30606 8192000000 9995109312 10405814272 1090461824 11.747 100 52 104 1 1113276816 11274624 11540368
08:25:02 9752 8192000000 10016598240 10425962496 1094933824 11.790 100 37 75 0 1137261280 34693552 7633904
08:26:12 4457 8192000000 10021397328 10414182400 1103162944 11.868 100 39 77 0 1135977152 23812624 9001584
08:27:17 3509 8192000000 9991432080 10386845696 1073923936 11.590 98 51 100 1 1100524512 15215248 11385328
08:28:21 36970 8192000000 10006408640 10413801472 1087473120 11.719 100 51 102 0 1112171952 13598768 11100064
08:30:00 5053 8192000000 9963746800 10353745920 1040145696 11.266 98 53 104 0 1069912624 18493984 11272944
08:31:09 5355 8192000000 9927366928 10306756608 982046480 10.704 96 44 88 2 1015072544 24134736 8891328
08:32:15 8229 8192000000 9946451200 10330603520 1000325728 10.882 97 37 77 1 1034169344 26176368 7667248
08:33:25 51045 8192000000 9946019632 10347962368 1015396960 11.028 97 25 51 0 1039171760 18121840 5652960
08:35:17 6250 8192000000 9975525472 10372198400 1058245632 11.440 100 40 80 1 1099280816 31778288 9256896
08:36:28 14538 8192000000 9976663808 10382241792 1067508560 11.528 100 27 54 2 1094417424 21071600 5837264
08:37:45 98900 8192000000 10012528576 10420920320 1097914768 11.818 100 40 80 3 1143076304 37016448 8145088
08:40:47 9256 8192000000 10066373952 10460151808 1113304880 11.964 100 54 109 2 1171753312 46181760 12266672
08:41:59 11521 8192000000 10089415040 10499919872 1121659280 12.043 100 51 102 0 1198998240 66671936 10667024
08:43:14 7587 8192000000 10117338480 10524000256 1132337648 12.143 100 51 102 0 1222222336 79235120 10649568
08:44:36 5660 8192000000 10159082064 10563825664 1149248160 12.302 100 76 148 1 1264581776 100133344 15200272
08:45:50 27965 8192000000 10175637984 10599948288 1151010000 12.319 100 40 80 0 1303805808 144532144 8263664
08:47:22 9050 8192000000 10192104800 10608230400 1161795344 12.420 100 55 110 0 1296963872 123165376 12003152
08:48:42 15594 8192000000 10237855008 10657513472 1176953456 12.562 100 61 123 1 1346519440 156009904 13556080
08:50:13 14162 8192000000 10224278688 10644992000 1174437904 12.538 100 48 95 2 1334604512 149590816 10575792
08:51:34 12199 8192000000 10250146800 10668507136 1154125136 12.348 100 62 122 1 1347650576 179926096 13599344
08:53:01 1677 8192000000 10252331680 10660761600 1169486848 12.492 100 77 155 1 1349700704 163632224 16581632
08:54:08 4869 8192000000 10272273104 10696187904 1162665360 12.428 100 62 123 1 1372365648 195292544 14407744
08:55:19 3698 8192000000 10299822144 10710491136 1163302256 12.434 100 75 144 1 1402571216 222786288 16482672
08:56:27 945 8192000000 10285713888 10682494976 1129223712 12.114 99 83 164 1 1378312304 230534112 18554480
08:57:34 5018 8192000000 10312958624 10712956928 1129631744 12.118 99 82 165 1 1404775024 255940720 19202560
08:58:43 8358 8192000000 10313557392 10751754240 1143651200 12.250 100 63 124 0 1436536512 277702944 15182368
09:00:01 3263 8192000000 10321604240 10726027264 1123134576 12.057 98 64 127 0 1408757696 270347680 15275440
09:01:28 8626 8192000000 10277763824 10717122560 1128285184 12.105 99 40 80 0 1387697008 249841264 9570560
09:02:42 4794 8192000000 10328493120 10736730112 1148752032 12.298 100 70 138 1 1424128240 259280096 16096112
09:03:53 6355 8192000000 10302776448 10745745408 1139714608 12.213 100 40 80 1 1401404208 251704768 9984832
09:05:08 11284 8192000000 10373799648 10820063232 1162025840 12.422 100 36 72 0 1480571408 309463248 9082320
09:06:43 5695 8192000000 10432663072 10859503616 1160110752 12.404 100 60 121 0 1527353424 352415312 14827360
09:07:53 25193 8192000000 10444151808 10915155968 1149060224 12.301 100 28 56 0 1538372928 381871280 7441424
09:10:21 31614 8192000000 10327022560 10867998720 1127390832 12.097 99 16 33 1 1469490160 337737808 4361520
09:11:56 28037 8192000000 10074278624 10516152320 1111690096 11.948 98 12 25 0 1183474416 68787216 2997104
09:13:24 13849 8192000000 10113806720 10541531136 1137483344 12.192 100 49 97 1 1202363232 54957472 9922416
09:14:43 87279 8192000000 10073196944 10497069056 1098001840 11.819 96 18 36 0 1151864128 50176496 3685792
09:17:18 53178 8192000000 10112719792 10546061312 1142006304 12.234 99 49 96 1 1209630000 56009328 11614368
09:19:13 100444 8192000000 10085315488 10538622976 1120453840 12.031 98 28 56 1 1178261408 51370336 6437232
09:22:00 143965 8192000000 10143967488 10586484736 1114614896 11.976 97 18 37 0 1175770416 57446048 3709472
09:25:30 32909 8192000000 10165462720 10619592704 1150642112 12.316 100 50 100 0 1264369264 102606704 11120448
09:27:29 41505 8192000000 10085062080 10562854912 1149228960 12.302 100 7 14 0 1202348960 51886336 1233664
09:29:10 113630 8192000000 10138193680 10602373120 1149576560 12.306 99 15 29 1 1200906160 47825472 3504128
09:32:04 68212 8192000000 10129164784 10630488064 1163637632 12.437 100 18 36 0 1258847168 91342272 3867264
09:35:12 50161 8192000000 10112748272 10636529664 1147302416 12.284 99 11 22 0 1222207072 72579600 2325056
09:37:41 44461 8192000000 10102458368 10542456832 1131826720 12.139 98 27 53 0 1183630368 45456944 6346704
09:39:27 61177 8192000000 10139726576 10602987520 1167034416 12.469 100 19 38 0 1244070224 73436592 3599216
09:41:44 58084 8192000000 10101678896 10556301312 1149565392 12.305 99 19 38 0 1217859456 63858928 4435136
09:43:42 12614 8192000000 10120946016 10547478528 1156443392 12.370 100 62 124 0 1207714864 38614944 12656528
09:45:00 49827 8192000000 10044127040 10490507264 1058701296 11.444 93 27 54 0 1105924208 42038192 5184720
09:46:52 53872 8192000000 10107319104 10586595328 1100025360 11.838 98 26 52 0 1200833616 95372912 5435344
09:48:47 39136 8192000000 10084045152 10510016512 1089901280 11.742 98 38 75 0 1132311120 35236224 7173616
09:50:27 97328 8192000000 10045936608 10504904704 1077595248 11.625 97 32 64 0 1132784976 47974368 7215360
09:53:08 45764 8192000000 10058137440 10527084544 1103823136 11.874 100 22 44 0 1189881872 81316512 4742224
09:54:56 74211 8192000000 10059938752 10525790208 1090973328 11.752 98 14 28 0 1156620080 62755136 2891616
09:57:14 60806 8192000000 10054574928 10508070912 1098063568 11.819 98 21 42 1 1162739280 59727264 4948448
09:59:24 60512 8192000000 10054394736 10501091328 1068623296 11.539 96 15 30 0 1132890160 61133824 3133040
10:01:26 90741 8192000000 10057964400 10534690816 1111298960 11.945 100 10 20 1 1184459888 71081408 2079520
10:04:05 49738 8192000000 10110802752 10568888320 1117853520 12.007 99 24 48 1 1189622352 66279040 5489792
10:06:00 102333 8192000000 10089248336 10614767616 1086429936 11.709 96 11 22 1 1202578656 113533888 2614832
10:08:45 77113 8192000000 10082073744 10544652288 1125650896 12.080 99 20 40 0 1154991120 25308368 4031856
10:11:03 66287 8192000000 10152253744 10628890624 1110418560 11.936 97 29 58 1 1177842592 61668336 5755696
10:13:10 26294 8192000000 10097913744 10535157760 1125493392 12.079 98 35 70 1 1169082928 35857376 7732160
10:15:05 79498 8192000000 10085139008 10585399296 1137707616 12.194 100 18 36 1 1181073520 39695440 3670464
10:17:35 70841 8192000000 10086781616 10566766592 1133975280 12.159 99 19 38 0 1182434528 44914528 3544720
10:19:46 95612 8192000000 10072983248 10573389824 1130968960 12.130 99 7 14 0 1178863072 46420064 1474048
10:22:22 53791 8192000000 10075046896 10554699776 1148670240 12.297 100 18 36 1 1183997408 31556336 3770832
10:24:16 71540 8192000000 10112180576 10568318976 1108397904 11.917 96 24 48 0 1157647824 44039136 5210784
10:26:39 73085 8192000000 10060166816 10532577280 1083559920 11.681 95 20 40 0 1170341008 81810256 4970832
10:28:58 62337 8192000000 10106234560 10603659264 1119987312 12.027 99 37 73 0 1235567984 107674304 7906368
10:31:08 65654 8192000000 10124388608 10605023232 1103354640 11.869 97 23 46 0 1178807728 70624464 4828624
10:33:17 46701 8192000000 10072251024 10526658560 1076352784 11.613 95 30 60 0 1143033312 60669536 6010992
10:35:06 40059 8192000000 10044404800 10492252160 1099454576 11.832 99 21 42 1 1136025360 31384384 5186400
10:36:46 56923 8192000000 9666988224 10062344192 774392192 8.636 70 12 24 0 800319296 23684464 2242640

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-10-2021 10:39:19 to 07-10-2021 10:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-10-2021 10:39:35 to 07-10-2021 10:39:37.

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          231766
MON$OLDEST_ACTIVE               232021
MON$OLDEST_SNAPSHOT             232021
MON$NEXT_TRANSACTION            232021
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                       2063264
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2624 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2624 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2624 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2624 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Oct  7 10:38:01 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'

07.10.21 10:39:38. 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-10-07 10:39:40 No severe PSQL-related problems occured
07.10.21 10:39:41. End of report.