Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.09.2021 04:39
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 15872152 kB
MemAvailable 30864744 kB
Buffers 20 kB
Cached 15797040 kB
SwapCached 388 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-12 04:37:06
DTS_END                         2021-09-12 04:37:06.2540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47944
TRN_ID                          240537
ATT_ID                          618
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: 9288.45 22286 1114614
customer order: creation 171.48 393 20578
customer order: refuse 14.54 347 1745
order to supplier: creation 45.53 1517 5464
order to supplier: removal 3.55 108542 426
invoice (draft): creation 46.31 3527 5557
invoice (draft): removal 52.73 901 6327
invoice accept: apply 41.12 19100 4934
invoice accept: cancel 47.37 886 5684
customer reserve: creation 3834.75 188 460170
customer reserve: removal 4741.79 64 569015
realization accept: apply 84.53 185 10144
realization accept: cancel 76.93 130 9232
payment from customer: creation 59.18 107 7102
payment from customer: removal 5.51 64 661
payment to supplier: creation 48.83 135 5860
payment to supplier: removal 3.34 62 401
service: total inventory turnovers 4.13 3429 496
service: total monetary turnovers 5.81 528 697
service: aggregate perf. data 0.97 15778 116
service: refresh index statistics 0.04 289844 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-09-2021 04:39:38 to 12-09-2021 04:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3780
TEST_TIME 2 4704
TEST_TIME 3 4377
TEST_TIME 4 4332
TEST_TIME 5 4370
TEST_TIME 6 4120
TEST_TIME 7 5134
TEST_TIME 8 5330
TEST_TIME 9 5236
TEST_TIME 10 5614
TEST_TIME 11 5871
TEST_TIME 12 6049
TEST_TIME 13 6394
TEST_TIME 14 6688
TEST_TIME 15 7216
TEST_TIME 16 7502
TEST_TIME 17 7841
TEST_TIME 18 8214
TEST_TIME 19 8474
TEST_TIME 20 8635
TEST_TIME 21 9066
TEST_TIME 22 9008
TEST_TIME 23 9743
TEST_TIME 24 9692
TEST_TIME 25 10134
TEST_TIME 26 10780
TEST_TIME 27 10928
TEST_TIME 28 11159
TEST_TIME 29 10991
TEST_TIME 30 10952
TEST_TIME 31 10948
TEST_TIME 32 10804
TEST_TIME 33 10685
TEST_TIME 34 10384
TEST_TIME 35 10312
TEST_TIME 36 10237
TEST_TIME 37 10155
TEST_TIME 38 10060
TEST_TIME 39 10047
TEST_TIME 40 9950
TEST_TIME 41 9936
TEST_TIME 42 9892
TEST_TIME 43 9990
TEST_TIME 44 9985
TEST_TIME 45 9961
TEST_TIME 46 9960
TEST_TIME 47 9884
TEST_TIME 48 9855
TEST_TIME 49 9893
TEST_TIME 50 9909
TEST_TIME 51 9872
TEST_TIME 52 9797
TEST_TIME 53 9809
TEST_TIME 54 9721
TEST_TIME 55 9854
TEST_TIME 56 9813
TEST_TIME 57 9901
TEST_TIME 58 9808
TEST_TIME 59 9813
TEST_TIME 60 9785
TEST_TIME 61 9686
TEST_TIME 62 9626
TEST_TIME 63 9671
TEST_TIME 64 9581
TEST_TIME 65 9537
TEST_TIME 66 9437
TEST_TIME 67 9376
TEST_TIME 68 9435
TEST_TIME 69 9367
TEST_TIME 70 9458
TEST_TIME 71 9525
TEST_TIME 72 9578
TEST_TIME 73 9657
TEST_TIME 74 9646
TEST_TIME 75 9637
TEST_TIME 76 9716
TEST_TIME 77 9720
TEST_TIME 78 9693
TEST_TIME 79 9827
TEST_TIME 80 9706
TEST_TIME 81 9648
TEST_TIME 82 9614
TEST_TIME 83 9602
TEST_TIME 84 9520
TEST_TIME 85 9483
TEST_TIME 86 9500
TEST_TIME 87 9513
TEST_TIME 88 9500
TEST_TIME 89 9485
TEST_TIME 90 9471
TEST_TIME 91 9491
TEST_TIME 92 9489
TEST_TIME 93 9471
TEST_TIME 94 9527
TEST_TIME 95 9527
TEST_TIME 96 9566
TEST_TIME 97 9598
TEST_TIME 98 9570
TEST_TIME 99 9565
TEST_TIME 100 9503
TEST_TIME 101 9456
TEST_TIME 102 9428
TEST_TIME 103 9381
TEST_TIME 104 9340
TEST_TIME 105 9302
TEST_TIME 106 9293
TEST_TIME 107 9278
TEST_TIME 108 9285
TEST_TIME 109 9251
TEST_TIME 110 9226
TEST_TIME 111 9240
TEST_TIME 112 9204
TEST_TIME 113 9294
TEST_TIME 114 9435
TEST_TIME 115 9433
TEST_TIME 116 9457
TEST_TIME 117 9432
TEST_TIME 118 9425
TEST_TIME 119 9364
TEST_TIME 120 9288

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-09-2021 04:39:42 to 12-09-2021 04:39:45.

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 1540805 1540805 0 0.00 0 30355 7 0 0
doc_list_biud 1540781 1540781 0 0.00 0 23981 30 0 0
sp_add_invoice_to_stock 5067 4934 133 2.62 43 177900 19100 0 133
sp_cancel_adding_invoice 5684 5684 0 0.00 4 71945 887 0 0
sp_cancel_client_order 1745 1745 0 0.00 5 24859 347 0 0
sp_cancel_customer_reserve 569015 569015 0 0.00 1 22910 65 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 1967 65 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 1830 63 0 0
sp_cancel_supplier_invoice 6338 6327 11 0.17 2 21079 902 0 11
sp_cancel_supplier_order 426 426 0 0.00 7 1040956 108542 0 0
sp_cancel_write_off 9232 9232 0 0.00 1 17475 130 0 0
sp_client_order 20578 20578 0 0.00 4 24578 394 0 0
sp_customer_reserve 460170 460170 0 0.00 0 67634 188 0 0
sp_fill_shopping_cart 31595 31595 0 0.00 1 23726 46 0 0
sp_fill_shopping_cart_clo_res 460170 460170 0 0.00 0 18171 17 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 25148 378 0 0
sp_kill_cost_storno 15978 15978 0 0.00 0 6370 57 0 0
sp_kill_qstorno_ret_qs2qd 1154015 1154015 0 0.00 0 21597 35 0 0
sp_kill_qty_storno 607488 607488 0 0.00 0 23981 75 0 0
sp_lock_selected_doc 20763 20763 0 0.00 0 9181 5 0 0
sp_make_cost_storno 28040 28040 0 0.00 0 30341 56 0 0
sp_multiply_rows_for_pdistr 28016 28016 0 0.00 0 10852 29 0 0
sp_multiply_rows_for_qdistr 20578 20578 0 0.00 0 17001 193 0 0
sp_pay_from_customer 7102 7102 0 0.00 4 10879 108 0 0
sp_pay_to_supplier 5860 5860 0 0.00 5 26185 135 0 0
sp_payment_common 12957 12957 0 0.00 1 9589 18 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 10454 289 0 0
sp_qd_handle_on_invoice_upd_sts 10609 10609 0 0.00 0 23979 339 0 0
sp_qd_handle_on_reserve_upd_sts 10134 10134 0 0.00 0 14040 53 0 0
sp_reserve_write_off 10144 10144 0 0.00 7 17654 185 0 0
sp_supplier_invoice 5557 5557 0 0.00 7 91355 3527 0 0
sp_supplier_order 5464 5464 0 0.00 5 42361 1518 0 0
srv_aggregate_perf_data 116 116 0 0.00 3129 310225 15779 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1435 111764 43688 0 0
srv_find_qd_qs_mism 1067127 1067127 0 0.00 0 22107 6 0 0
srv_make_invnt_saldo 496 496 0 0.00 39 100434 3429 0 0
srv_make_money_saldo 697 697 0 0.00 1 11884 528 0 0
srv_recalc_idx_stat 5 5 0 0.00 169581 366951 289844 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4934 4934 0 0.00 0 45189 208 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 1 18022 141 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 4275 16 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 5724 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 926 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 5224 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 5403 32 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 15383 1391 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 3025 6 0 0
t$perf-norm:sp_client_order 20578 20578 0 0.00 0 11301 18 0 0
t$perf-norm:sp_customer_reserve 1109 1109 0 0.00 0 6263 17 0 0
t$perf-norm:sp_pay_from_customer 7102 7102 0 0.00 0 11472 14 0 0
t$perf-norm:sp_pay_to_supplier 5860 5860 0 0.00 0 9289 11 0 0
t$perf-norm:sp_reserve_write_off 10144 10144 0 0.00 0 11474 11 0 0
t$perf-norm:sp_supplier_invoice 5557 5557 0 0.00 0 7825 15 0 0
t$perf-norm:sp_supplier_order 5464 5464 0 0.00 0 10799 19 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 596 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 2549 12 0 0
t$perf-norm:srv_make_money_saldo 697 697 0 0.00 0 2496 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10116 9850 266 2.62 0 26110 27 0 266
v_all_customers 37380 37380 0 0.00 0 19 1 0 0
v_all_suppliers 30652 30652 0 0.00 0 19 1 0 0
v_all_wares 41154 41154 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 813 6 0 0
v_cancel_client_order 3490 3490 0 0.00 0 24538 19 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 561 4 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2324 8 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1801 7 0 0
v_cancel_supplier_invoice 808 786 22 2.72 0 2972 19 0 22
v_cancel_supplier_order 852 852 0 0.00 0 8491 14 0 0
v_cancel_write_off 2044 2044 0 0.00 0 4035 17 0 0
v_max_id_clo_ord 5463 5463 0 0.00 0 7157 3 0 0
v_max_id_clo_res 1109 1109 0 0.00 0 1804 14 0 0
v_max_id_ord_sup 5555 5555 0 0.00 0 5380 3 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 474 2 0 0
v_max_non_paid_realizn 3902 3902 0 0.00 0 1286 3 0 0
v_min_id_clo_ord 5463 5463 0 0.00 0 2900 5 0 0
v_min_id_clo_res 1109 1109 0 0.00 0 10074 17 0 0
v_min_id_ord_sup 5555 5555 0 0.00 0 15145 32 0 0
v_min_non_paid_invoice 5855 5855 0 0.00 0 3921 13 0 0
v_min_non_paid_realizn 7102 7102 0 0.00 0 9586 11 0 0
v_our_firm 10174 10174 0 0.00 0 210 1 0 0
v_reserve_write_off 20268 20268 0 0.00 0 17278 9 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 28657 118 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 29317 414 0 0
x_make_qstorno_1000_1200 5464 5464 0 0.00 2 42338 1441 0 0
x_make_qstorno_1200_2000 5557 5557 0 0.00 3 91317 3344 0 0
x_make_qstorno_2100_3300 460170 460170 0 0.00 0 67632 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 04:39:46 to 12-09-2021 04:39:50.

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
02:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 43824 8192000000 9730624144 10030014464 803388016 8.931 100 81 162 0 830938048 8103504 19446528
02:10:07 16314 8192000000 9765609792 10073133056 839413584 9.294 100 88 176 0 875048464 14796000 20838880
02:11:28 16649 8192000000 9817811696 10140332032 870831104 9.608 100 78 156 0 920022064 31376384 17814576
02:12:51 8436 8192000000 9882813824 10219122688 926903456 10.164 100 77 154 3 993789952 50383648 16502848
02:14:02 5291 8192000000 9925194144 10274529280 969508704 10.582 100 79 157 1 1042212336 56784352 15919280
02:15:08 4049 8192000000 9977674384 10333122560 1012156064 10.996 100 81 162 0 1096236224 67937504 16142656
02:16:15 4449 8192000000 10006611280 10373263360 1035284672 11.219 100 82 164 0 1117502672 66408784 15809216
02:17:22 1905 8192000000 10044370352 10413625344 1058435472 11.442 100 91 182 2 1157916976 80781680 18699824
02:18:26 4948 8192000000 9994515280 10387423232 1069953424 11.552 100 44 87 2 1114216528 35089472 9173632
02:19:33 47728 8192000000 10000068256 10388344832 1077001056 11.619 100 43 87 0 1121711648 35440208 9270384
02:21:21 4482 8192000000 10011736784 10410942464 1087990928 11.724 100 64 130 3 1133368496 31338944 14038624
02:22:28 10269 8192000000 10010676128 10420834304 1092663600 11.768 100 30 61 0 1118662864 19978976 6020288
02:23:41 59361 8192000000 10005318640 10406461440 1098086656 11.819 100 33 65 0 1131991072 26915760 6988656
02:25:56 4216 8192000000 10027259184 10436648960 1105675408 11.891 100 53 109 0 1154163216 37030336 11457472
02:27:01 5583 8192000000 10027098944 10426064896 1086553152 11.710 100 49 98 0 1137042640 39491360 10998128
02:28:06 61994 8192000000 9980534464 10363027456 1012952608 11.004 94 43 86 1 1045760928 23006224 9802096
02:30:09 6590 8192000000 9937154096 10319069184 1000402320 10.882 97 35 70 0 1019862640 11688976 7771344
02:31:19 8851 8192000000 9956783776 10350419968 1012620016 11.001 99 48 96 1 1043928096 21448768 9859312
02:32:28 50114 8192000000 9947034912 10346520576 1028738176 11.156 99 33 66 1 1064221616 29079488 6403952
02:34:20 6202 8192000000 9986637632 10384293888 1062321184 11.479 100 49 95 2 1093647968 20994128 10332656
02:35:27 6927 8192000000 9992486048 10389549056 1065315152 11.507 100 42 85 1 1098638512 24224128 9099232
02:36:35 7835 8192000000 9983943248 10381008896 1078291408 11.631 100 40 80 2 1105874096 19287568 8295120
02:37:47 76372 8192000000 10043299616 10441846784 1103360240 11.870 100 40 80 1 1148482048 37231664 7890144
02:40:09 31949 8192000000 10068815920 10483625984 1119981968 12.027 100 31 61 1 1178238848 51892352 6364528
02:42:15 19834 8192000000 10142475536 10554781696 1146488544 12.277 100 40 80 0 1258438144 102758544 9191056
02:43:47 22757 8192000000 10220611296 10649862144 1160265216 12.406 100 49 98 1 1329405936 157322640 11818080
02:45:12 11259 8192000000 10217883760 10636267520 1167562784 12.474 100 59 117 0 1328054768 147299568 13192416
02:46:34 9360 8192000000 10253357728 10675363840 1170255936 12.499 100 46 92 0 1363331616 182782688 10292992
02:47:51 5909 8192000000 10266530176 10705412096 1154403472 12.351 99 58 116 0 1366475152 199178864 12892816
02:49:06 2205 8192000000 10277622576 10687401984 1159447440 12.398 99 70 138 0 1377797440 203173552 15176448
02:50:12 2377 8192000000 10320546512 10733682688 1177758736 12.569 100 77 151 2 1430172688 234735232 17678720
02:51:18 2832 8192000000 10335655072 10743156736 1163100272 12.432 100 78 155 3 1432677760 251507584 18069904
02:52:26 8592 8192000000 10346678768 10763374592 1157869424 12.383 100 73 147 1 1455420240 280887072 16663744
02:53:40 1430 8192000000 10376425072 10777853952 1165159728 12.452 100 95 190 2 1468196208 281437040 21599440
02:54:47 11007 8192000000 10345980400 10764259328 1155147168 12.358 100 41 82 0 1451745168 285812176 10785824
02:56:02 6481 8192000000 10336222928 10769534976 1138499680 12.201 99 51 101 0 1443096144 292402656 12193808
02:57:14 7330 8192000000 10333507872 10792312832 1137713936 12.194 99 35 70 2 1428689216 281520976 9454304
02:58:27 9293 8192000000 10312847664 10734555136 1126219920 12.086 98 45 90 0 1399198880 261725824 11253136
02:59:40 13098 8192000000 10365976976 10799394816 1132611488 12.146 99 42 84 0 1457047296 313789552 10646256
03:01:05 7417 8192000000 10233490832 10682531840 1118722384 12.015 99 25 50 0 1323471040 198713152 6035504
03:02:17 22754 8192000000 10231818480 10696499200 1116033984 11.990 99 24 48 3 1343144160 221330352 5779824
03:03:46 40514 8192000000 10122979408 10596651008 1111823536 11.950 100 12 24 1 1256272672 141860144 2588992
03:05:27 56659 8192000000 10060226448 10498797568 1098396160 11.822 98 15 30 0 1149409760 47577376 3436224
03:07:32 7344 8192000000 10068534080 10477707264 1098872048 11.827 99 74 147 2 1150895536 35702016 16321472
03:08:43 41996 8192000000 10059162400 10486013952 1111067184 11.943 99 32 62 1 1133272464 15461760 6743520
03:10:26 111764 8192000000 10103481520 10551963648 1129784528 12.119 100 25 49 1 1200281952 65039040 5458384
03:13:25 38702 8192000000 10074999968 10564644864 1143719680 12.251 100 11 22 0 1177205728 31423888 2062160
03:15:06 21188 8192000000 10108269040 10556780544 1152522480 12.333 100 25 49 0 1208776144 51504512 4749152
03:16:28 41592 8192000000 10160170752 10602225664 1161961472 12.422 100 21 43 0 1254249520 87409232 4878816
03:18:12 101567 8192000000 10156175968 10599202816 1132191008 12.142 97 25 51 1 1247469456 109361360 5917088
03:20:57 49476 8192000000 10123701408 10587156480 1151433616 12.323 99 11 22 1 1202413376 48463936 2515824
03:23:40 48479 8192000000 10095130496 10552745984 1163300288 12.434 100 14 28 0 1189946368 23990080 2656000
03:25:37 20388 8192000000 10145172288 10604388352 1165140752 12.451 100 46 92 0 1238751648 64258544 9352352
03:27:05 63227 8192000000 10095097280 10573160448 1155096880 12.357 99 13 26 0 1203223776 45653136 2473760
03:29:10 65737 8192000000 10137754000 10571452416 1148749552 12.298 99 29 58 0 1205259632 50748640 5761440
03:31:17 61450 8192000000 10092804016 10603651072 1126753872 12.091 98 18 36 0 1229956080 99103568 4098640
03:33:36 46816 8192000000 10091950016 10543837184 1099501984 11.833 96 28 56 1 1167811488 61765680 6543824
03:35:47 14253 8192000000 10142862352 10578399232 1138218864 12.199 100 54 108 0 1217303392 67881136 11203392
03:37:03 71448 8192000000 10051680832 10514194432 1103189120 11.868 97 36 72 0 1179412112 68346448 7876544
03:39:17 26000 8192000000 10086354480 10535600128 1128704592 12.109 100 25 51 0 1180346160 46617392 5024176
03:40:49 45236 8192000000 10050565824 10538930176 1120775616 12.034 99 6 12 0 1155512464 33656032 1080816
03:42:34 55143 8192000000 10062006144 10530471936 1089366800 11.737 96 10 20 0 1130265456 38839408 2059248
03:44:41 75196 8192000000 10103447808 10567905280 1110087104 11.933 98 27 54 1 1197814096 81438512 6288480
03:46:59 51957 8192000000 10126071088 10597212160 1121307488 12.039 99 25 48 2 1236283680 109582224 5393968
03:48:55 41661 8192000000 10115438416 10573074432 1101661680 11.853 97 32 64 1 1179035296 70607344 6766272
03:50:38 45507 8192000000 10067048544 10489200640 1096218880 11.802 98 29 57 1 1148064560 45802704 6042976
03:52:25 28093 8192000000 10093526736 10517803008 1101400912 11.851 98 42 84 0 1151751632 42101296 8249424
03:53:58 64573 8192000000 10067692560 10489958400 1063152208 11.487 95 20 40 0 1115972384 48718944 4101232
03:56:09 77206 8192000000 10033471728 10525466624 1078635408 11.634 97 13 26 0 1135726112 54345760 2744944
03:58:49 59557 8192000000 10055511424 10483847168 1091125184 11.753 98 32 63 0 1136018464 38726160 6167120
04:00:49 84735 8192000000 10067717792 10525843456 1114473712 11.975 100 13 26 0 1182025536 63927440 3624384
04:03:16 43958 8192000000 10088421824 10583908352 1116521088 11.994 99 16 32 1 1200561248 79996240 4043920
04:06:07 28324 8192000000 10077566096 10518409216 1127704032 12.100 100 28 56 2 1152236928 18861296 5671600
04:07:37 60414 8192000000 10061565248 10511626240 1136418992 12.182 100 29 58 0 1164398224 21943728 6035504
04:09:41 36541 8192000000 10103738624 10546044928 1117820816 12.006 98 24 49 1 1169295600 46779776 4695008
04:11:18 63964 8192000000 10127655232 10598592512 1124063904 12.065 98 11 22 0 1219438864 93069936 2305024
04:13:24 51118 8192000000 10112502992 10592436224 1139050448 12.207 99 25 48 1 1212805216 69294736 4460032
04:15:17 36510 8192000000 10078891488 10516480000 1085894240 11.704 95 34 66 0 1138216896 45365456 6957200
04:17:05 98492 8192000000 10092273296 10557288448 1131599632 12.136 99 37 75 1 1184849280 45840960 7408688
04:19:45 65005 8192000000 10053439424 10519171072 1134594800 12.165 100 7 15 0 1161082640 25118992 1368848
04:21:50 80076 8192000000 10059944944 10517921792 1084356000 11.689 96 10 20 0 1114651136 28251168 2043968
04:24:45 65269 8192000000 10035833040 10513285120 1061065408 11.467 94 16 30 1 1111545168 47226720 3253040
04:26:54 85500 8192000000 10156205680 10603786240 1082964336 11.676 96 21 42 0 1179928720 92073856 4890528
04:29:29 102037 8192000000 10173670352 10705195008 1125743456 12.081 99 20 40 1 1286794288 156260768 4790064
04:32:14 70475 8192000000 10084583728 10534199296 1074927824 11.599 96 23 46 0 1140154640 60226304 5000512
04:34:32 38425 8192000000 10082939776 10534719488 1129990224 12.121 100 20 40 0 1187712592 53628864 4093504
04:36:16 83826 8192000000 9389304144 9771290624 506666464 5.824 45 10 20 0 570776176 62094256 2015456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-09-2021 04:39:52 to 12-09-2021 04:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-09-2021 04:40:08 to 12-09-2021 04:40:10.

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          240829
MON$OLDEST_ACTIVE               240830
MON$OLDEST_SNAPSHOT             240830
MON$NEXT_TRANSACTION            240830
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                       2060704
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             813
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2588 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2588 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

12.09.21 04:40:11. 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-12 04:40:13 No severe PSQL-related problems occured
12.09.21 04:40:14. End of report.