Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 04: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16206396 kB
MemAvailable 31328284 kB
Buffers 20 kB
Cached 15386648 kB
SwapCached 440 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-12-25 04:37:10
DTS_END                         2021-12-25 04:37:10.5810
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/58032
TRN_ID                          226718
ATT_ID                          635
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: 8935.07 23431 1072208
customer order: creation 158.98 475 19078
customer order: refuse 14.01 427 1681
order to supplier: creation 42.99 1571 5159
order to supplier: removal 3.33 117837 400
invoice (draft): creation 43.03 4336 5164
invoice (draft): removal 52.42 979 6290
invoice accept: apply 39.13 20822 4696
invoice accept: cancel 47.01 879 5641
customer reserve: creation 3630.86 203 435703
customer reserve: removal 4623.40 66 554808
realization accept: apply 77.13 206 9256
realization accept: cancel 83.21 137 9985
payment from customer: creation 55.13 135 6616
payment from customer: removal 5.34 100 641
payment to supplier: creation 45.68 150 5482
payment to supplier: removal 3.23 113 387
service: total inventory turnovers 3.38 4012 406
service: total monetary turnovers 5.93 580 712
service: aggregate perf. data 0.82 17183 98
service: refresh index statistics 0.04 298407 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-12-2021 04:38:27 to 25-12-2021 04:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8006
TEST_TIME 2 5262
TEST_TIME 3 4841
TEST_TIME 4 5096
TEST_TIME 5 4892
TEST_TIME 6 5096
TEST_TIME 7 5058
TEST_TIME 8 5098
TEST_TIME 9 5166
TEST_TIME 10 5071
TEST_TIME 11 5355
TEST_TIME 12 5817
TEST_TIME 13 6204
TEST_TIME 14 6369
TEST_TIME 15 6931
TEST_TIME 16 7007
TEST_TIME 17 7276
TEST_TIME 18 7357
TEST_TIME 19 7332
TEST_TIME 20 7727
TEST_TIME 21 8121
TEST_TIME 22 8435
TEST_TIME 23 8290
TEST_TIME 24 8399
TEST_TIME 25 8494
TEST_TIME 26 9540
TEST_TIME 27 9664
TEST_TIME 28 10510
TEST_TIME 29 10601
TEST_TIME 30 10660
TEST_TIME 31 10418
TEST_TIME 32 10355
TEST_TIME 33 10473
TEST_TIME 34 10646
TEST_TIME 35 10646
TEST_TIME 36 10550
TEST_TIME 37 10343
TEST_TIME 38 10123
TEST_TIME 39 10224
TEST_TIME 40 10022
TEST_TIME 41 10032
TEST_TIME 42 9974
TEST_TIME 43 9894
TEST_TIME 44 9820
TEST_TIME 45 9895
TEST_TIME 46 9878
TEST_TIME 47 9786
TEST_TIME 48 9697
TEST_TIME 49 9568
TEST_TIME 50 9589
TEST_TIME 51 9626
TEST_TIME 52 9551
TEST_TIME 53 9666
TEST_TIME 54 9652
TEST_TIME 55 9771
TEST_TIME 56 9772
TEST_TIME 57 9719
TEST_TIME 58 9622
TEST_TIME 59 9655
TEST_TIME 60 9780
TEST_TIME 61 9833
TEST_TIME 62 9866
TEST_TIME 63 9844
TEST_TIME 64 9777
TEST_TIME 65 9634
TEST_TIME 66 9588
TEST_TIME 67 9606
TEST_TIME 68 9517
TEST_TIME 69 9455
TEST_TIME 70 9367
TEST_TIME 71 9319
TEST_TIME 72 9301
TEST_TIME 73 9318
TEST_TIME 74 9308
TEST_TIME 75 9374
TEST_TIME 76 9332
TEST_TIME 77 9439
TEST_TIME 78 9574
TEST_TIME 79 9592
TEST_TIME 80 9511
TEST_TIME 81 9601
TEST_TIME 82 9565
TEST_TIME 83 9537
TEST_TIME 84 9458
TEST_TIME 85 9436
TEST_TIME 86 9401
TEST_TIME 87 9393
TEST_TIME 88 9333
TEST_TIME 89 9261
TEST_TIME 90 9314
TEST_TIME 91 9285
TEST_TIME 92 9224
TEST_TIME 93 9201
TEST_TIME 94 9164
TEST_TIME 95 9138
TEST_TIME 96 9100
TEST_TIME 97 9141
TEST_TIME 98 9128
TEST_TIME 99 9104
TEST_TIME 100 9086
TEST_TIME 101 9132
TEST_TIME 102 9176
TEST_TIME 103 9247
TEST_TIME 104 9240
TEST_TIME 105 9226
TEST_TIME 106 9267
TEST_TIME 107 9297
TEST_TIME 108 9253
TEST_TIME 109 9206
TEST_TIME 110 9145
TEST_TIME 111 9115
TEST_TIME 112 9097
TEST_TIME 113 9045
TEST_TIME 114 9037
TEST_TIME 115 8998
TEST_TIME 116 9037
TEST_TIME 117 9001
TEST_TIME 118 8996
TEST_TIME 119 9004
TEST_TIME 120 8940

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 04:38:31 to 25-12-2021 04:38:34.

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 1474259 1474259 0 0.00 0 37247 7 0 0
doc_list_biud 1474259 1474259 0 0.00 0 46557 32 0 0
sp_add_invoice_to_stock 4831 4696 135 2.79 31 192850 20823 0 135
sp_cancel_adding_invoice 5641 5641 0 0.00 4 56527 880 0 0
sp_cancel_client_order 1681 1681 0 0.00 4 16159 428 0 0
sp_cancel_customer_reserve 554808 554808 0 0.00 1 35895 66 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 10646 100 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 6664 113 0 0
sp_cancel_supplier_invoice 6301 6290 11 0.17 2 46577 980 0 11
sp_cancel_supplier_order 400 400 0 0.00 9 1295012 117837 0 0
sp_cancel_write_off 9985 9985 0 0.00 1 37248 137 0 0
sp_client_order 19078 19078 0 0.00 4 28999 476 0 0
sp_customer_reserve 435703 435703 0 0.00 0 46848 203 0 0
sp_fill_shopping_cart 29385 29385 0 0.00 1 18057 49 0 0
sp_fill_shopping_cart_clo_res 435692 435692 0 0.00 0 17392 16 0 0
sp_get_clo_for_invoice 4685 4685 0 0.00 0 19915 356 0 0
sp_kill_cost_storno 16654 16654 0 0.00 0 37246 71 0 0
sp_kill_qstorno_ret_qs2qd 1126291 1126291 0 0.00 0 46549 36 0 0
sp_kill_qty_storno 592746 592746 0 0.00 0 46556 79 0 0
sp_lock_selected_doc 19347 19347 0 0.00 0 9585 7 0 0
sp_make_cost_storno 26039 26039 0 0.00 0 15577 64 0 0
sp_multiply_rows_for_pdistr 26039 26039 0 0.00 0 10010 39 0 0
sp_multiply_rows_for_qdistr 19078 19078 0 0.00 0 19898 241 0 0
sp_pay_from_customer 6616 6616 0 0.00 4 15025 135 0 0
sp_pay_to_supplier 5482 5482 0 0.00 5 13921 151 0 0
sp_payment_common 12098 12098 0 0.00 1 10543 30 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 16152 383 0 0
sp_qd_handle_on_invoice_upd_sts 10326 10326 0 0.00 0 29379 405 0 0
sp_qd_handle_on_reserve_upd_sts 9256 9256 0 0.00 0 14730 65 0 0
sp_reserve_write_off 9256 9256 0 0.00 6 14796 206 0 0
sp_supplier_invoice 5164 5164 0 0.00 9 91560 4336 0 0
sp_supplier_order 5159 5159 0 0.00 5 28990 1572 0 0
srv_aggregate_perf_data 98 98 0 0.00 2632 206788 17184 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1581 143880 46879 0 0
srv_find_qd_qs_mism 1026947 1026947 0 0.00 0 13336 6 0 0
srv_make_invnt_saldo 406 406 0 0.00 50 106327 4012 0 0
srv_make_money_saldo 712 712 0 0.00 1 14374 580 0 0
srv_recalc_idx_stat 5 5 0 0.00 130591 473586 298407 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4696 4696 0 0.00 0 37219 210 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 1 4089 121 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 1333 11 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 1930 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 6460 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 355 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 650 18 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 20898 1533 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 2538 6 0 0
t$perf-norm:sp_client_order 19078 19078 0 0.00 0 37003 26 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 405 4 0 0
t$perf-norm:sp_pay_from_customer 6616 6616 0 0.00 0 5392 9 0 0
t$perf-norm:sp_pay_to_supplier 5482 5482 0 0.00 0 38639 19 0 0
t$perf-norm:sp_reserve_write_off 9256 9256 0 0.00 0 4834 9 0 0
t$perf-norm:sp_supplier_invoice 5164 5164 0 0.00 0 3726 21 0 0
t$perf-norm:sp_supplier_order 5159 5159 0 0.00 0 5713 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 2038 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 172 8 0 0
t$perf-norm:srv_make_invnt_saldo 406 406 0 0.00 0 521 7 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 5287 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9640 9370 270 2.80 0 5639 20 0 270
v_all_customers 34960 34960 0 0.00 0 70 1 0 0
v_all_suppliers 28438 28438 0 0.00 0 91 1 0 0
v_all_wares 38156 38156 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 2846 14 0 0
v_cancel_client_order 3362 3362 0 0.00 0 2267 8 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 2273 7 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 2599 13 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 2369 7 0 0
v_cancel_supplier_invoice 798 776 22 2.75 0 3139 21 0 22
v_cancel_supplier_order 800 800 0 0.00 0 673 7 0 0
v_cancel_write_off 1830 1830 0 0.00 0 2717 18 0 0
v_max_id_clo_ord 5153 5153 0 0.00 0 1936 2 0 0
v_max_id_clo_res 919 919 0 0.00 0 2097 20 0 0
v_max_id_ord_sup 5153 5153 0 0.00 0 11041 9 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 7140 7 0 0
v_max_non_paid_realizn 3488 3488 0 0.00 0 6085 8 0 0
v_min_id_clo_ord 5153 5153 0 0.00 0 10551 22 0 0
v_min_id_clo_res 919 919 0 0.00 0 3794 15 0 0
v_min_id_ord_sup 5153 5153 0 0.00 0 9382 16 0 0
v_min_non_paid_invoice 5482 5482 0 0.00 0 10540 17 0 0
v_min_non_paid_realizn 6616 6616 0 0.00 0 9225 21 0 0
v_our_firm 9452 9452 0 0.00 0 14 1 0 0
v_reserve_write_off 18512 18512 0 0.00 0 6422 9 0 0
x_lock_depdocs_on_canc_invoice 11543 11543 0 0.00 0 11404 115 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 11779 481 0 0
x_make_qstorno_1000_1200 5159 5159 0 0.00 1 28851 1463 0 0
x_make_qstorno_1200_2000 5164 5164 0 0.00 5 89947 4170 0 0
x_make_qstorno_2100_3300 435703 435703 0 0.00 0 42960 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 04:38:35 to 25-12-2021 04:38:39.

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:14 1 8192000000 8798580928 8809799680 4478816 0.054 2 1 2 0 4578208 63616 35776
02:08:15 36075 8192000000 9717685232 10016440320 797194752 8.868 100 86 172 0 826147504 8063952 20888800
02:10:02 18220 8192000000 9779094944 10087424000 852456400 9.425 100 89 178 1 886278992 12932992 20889600
02:11:24 14975 8192000000 9814309008 10134319104 877935568 9.679 100 83 166 0 923092064 26209024 18947472
02:12:42 13300 8192000000 9879346976 10210807808 925222112 10.148 100 92 184 0 986846192 41628032 19996048
02:13:56 10518 8192000000 9947449088 10290884608 964955136 10.537 100 85 170 1 1054305152 71646112 17703904
02:15:11 6417 8192000000 9960256448 10319585280 999542656 10.874 100 71 142 1 1076809696 63044272 14222768
02:16:19 3639 8192000000 10002824672 10363891712 1027788544 11.147 100 84 164 0 1114448048 70461904 16197600
02:17:25 2485 8192000000 10010712560 10379665408 1051917440 11.379 100 81 160 2 1130521360 62596288 16007632
02:18:29 2064 8192000000 10023894464 10399346688 1075658992 11.606 100 78 157 1 1133340240 41014048 16667200
02:19:32 1321 8192000000 10057708640 10434195456 1077034928 11.619 100 92 179 3 1170004672 74999296 17970448
02:20:35 5350 8192000000 10005203632 10402172928 1079291456 11.641 100 48 99 0 1127844960 38129216 10424288
02:21:41 10453 8192000000 10001604720 10405449728 1087034352 11.714 100 28 55 0 1118585056 25037856 6512848
02:22:53 8144 8192000000 10000161872 10401492992 1098537744 11.824 100 31 62 1 1134521248 29408592 6574912
02:24:04 67373 8192000000 10031119248 10422734848 1103142848 11.867 100 39 77 1 1129673136 17979744 8550544
02:26:12 4077 8192000000 10026741360 10426146816 1108339376 11.917 100 48 97 0 1135084720 16287808 10457536
02:27:17 8907 8192000000 10018954016 10424401920 1098197904 11.821 100 28 57 1 1119893312 15666432 6028976
02:28:26 49315 8192000000 9985688448 10382249984 1002184720 10.900 92 26 53 0 1031477104 23355216 5937168
02:30:23 5961 8192000000 9923809280 10300514304 973718528 10.623 94 45 89 0 1004881136 21122736 10039872
02:31:30 6445 8192000000 9925526928 10303340544 989105856 10.773 96 35 69 3 1012241392 15064960 8070576
02:32:40 9256 8192000000 9967399696 10351480832 1019874320 11.071 98 41 81 2 1048774976 19769616 9131040
02:33:52 19105 8192000000 9981617344 10383953920 1048102624 11.342 100 28 55 0 1072237472 18734912 5399936
02:35:16 4159 8192000000 9990964192 10372243456 1060585536 11.462 100 55 109 1 1093670496 21852896 11232064
02:36:21 5267 8192000000 9982039904 10371317760 1063716096 11.492 100 54 107 1 1093362576 17318672 12327808
02:37:29 9879 8192000000 10020747616 10411098112 1095471568 11.795 100 57 111 1 1124003296 16348736 12182992
02:38:57 100922 8192000000 10076805808 10474622976 1116711648 11.996 100 48 96 0 1171809088 45076528 10020912
02:41:44 18544 8192000000 10109872592 10528948224 1128682064 12.109 100 34 68 1 1227796016 91855632 7258320
02:43:26 14031 8192000000 10119998336 10532794368 1142463440 12.239 100 44 88 0 1231404768 78966864 9974464
02:44:45 9331 8192000000 10162106352 10568060928 1156007088 12.366 100 50 98 1 1275572160 108847328 10717744
02:46:05 19111 8192000000 10222540064 10634752000 1157833296 12.383 100 59 118 1 1319077088 147891104 13352688
02:47:30 5467 8192000000 10279162448 10688925696 1165765824 12.457 100 70 139 0 1389912800 209049024 15097952
02:48:40 4052 8192000000 10280510576 10686369792 1175723920 12.550 100 82 164 0 1388165200 193658064 18783216
02:49:47 5642 8192000000 10317411888 10724995072 1169876864 12.496 99 72 142 0 1411390272 225730208 15783200
02:51:00 7016 8192000000 10304917328 10712739840 1174253360 12.537 100 72 144 0 1402759568 211806816 16699392
02:52:13 1581 8192000000 10283813664 10682896384 1162095440 12.423 99 84 170 0 1376387392 194390800 19901152
02:53:19 2429 8192000000 10291118464 10696470528 1154094336 12.348 100 82 164 0 1387346528 215137216 18114976
02:54:29 2014 8192000000 10316886336 10716098560 1143806832 12.251 100 87 174 0 1413690928 250587632 19296464
02:55:35 8647 8192000000 10318034976 10720559104 1146747648 12.279 100 74 146 1 1424101760 260121440 17232672
02:56:54 1644 8192000000 10363902080 10756902912 1137184704 12.189 99 89 176 1 1453273824 295864864 20224256
02:58:01 3248 8192000000 10391011920 10783346688 1155762368 12.364 100 94 187 0 1483253392 305852048 21638976
02:59:09 5636 8192000000 10377471072 10783842304 1159325904 12.397 100 54 107 2 1471290880 299308576 12656400
03:00:22 6311 8192000000 10364700736 10774040576 1141447328 12.229 99 47 95 2 1456386352 302955664 11983360
03:01:30 9186 8192000000 10409629360 10823905280 1135458160 12.173 100 44 88 2 1495820512 348942544 11419808
03:02:47 11481 8192000000 10430284672 10869075968 1127293296 12.096 100 44 88 1 1542800336 403767024 11740016
03:04:07 27596 8192000000 10236488800 10673307648 1123025120 12.056 100 24 48 2 1335039568 205999072 6015376
03:05:35 41603 8192000000 10058802736 10543067136 1102808448 11.864 99 16 32 1 1221662592 114853056 4001088
03:07:19 75654 8192000000 10105765824 10545401856 1119336400 12.021 99 18 33 2 1166104624 43107584 3660640
03:09:35 56546 8192000000 10150877056 10592522240 1115021680 11.980 97 25 49 1 1215347200 94868992 5456528
03:11:33 131384 8192000000 10076673056 10507960320 1119081408 12.018 98 14 28 2 1145656848 23905984 2669456
03:14:45 76047 8192000000 10125290736 10547183616 1125826592 12.082 98 47 93 0 1176872752 40731568 10314592
03:17:22 63840 8192000000 10076810368 10514448384 1122458720 12.050 99 37 74 2 1165792608 35317408 8016480
03:19:27 36112 8192000000 10099754144 10532007936 1138770624 12.204 100 26 49 1 1195980336 52306448 4903264
03:21:10 35757 8192000000 10139745808 10581954560 1145337936 12.266 100 42 84 1 1236607232 82393760 8875536
03:22:53 39321 8192000000 10091388928 10576924672 1145884848 12.271 100 15 30 0 1210366944 60825632 3656464
03:24:43 42727 8192000000 10093919552 10548740096 1141063824 12.226 99 9 18 0 1178431712 35322464 2045424
03:26:30 63081 8192000000 10140996432 10610290688 1151926880 12.328 100 23 45 0 1227501552 70159104 5415568
03:28:33 56448 8192000000 10147030368 10610118656 1159184176 12.396 100 22 44 0 1240062672 76139200 4739296
03:30:53 60607 8192000000 10147021888 10581749760 1122577568 12.051 97 25 48 2 1192817232 64811424 5428240
03:33:00 65792 8192000000 10102526384 10569121792 1129604176 12.118 98 12 24 0 1174965328 43229472 2131680
03:35:07 63746 8192000000 10193562560 10634620928 1144426048 12.257 99 27 54 2 1246213472 96242336 5545088
03:37:28 85219 8192000000 10094865424 10590318592 1089945904 11.742 95 10 20 0 1164183248 72323840 1913504
03:39:56 78461 8192000000 10098939680 10536038400 1093155520 11.773 95 15 30 2 1147269744 51276784 2837440
03:42:15 93649 8192000000 10081129136 10516021248 1098939376 11.828 97 33 66 0 1149601744 44059264 6603104
03:44:49 51853 8192000000 10066873456 10525814784 1123106992 12.056 99 8 16 0 1168784496 43784320 1893184
03:46:41 47285 8192000000 10077587840 10543693824 1131581840 12.136 99 17 34 0 1165629680 30202432 3845408
03:48:59 57908 8192000000 10141558080 10586591232 1111274400 11.944 97 23 46 0 1194308192 78249472 4784320
03:50:57 79268 8192000000 10160138288 10650939392 1095570560 11.796 97 25 50 1 1217777312 116499200 5707552
03:53:17 35235 8192000000 10151774128 10567536640 1111304736 11.945 99 43 87 0 1218064544 97336080 9423728
03:54:55 64031 8192000000 10058076912 10575564800 1058896784 11.446 95 12 24 0 1160035536 98507520 2631232
03:57:15 33956 8192000000 10072404048 10491224064 1079909200 11.647 97 32 62 2 1120091872 34010976 6171696
03:58:54 67249 8192000000 10075144016 10506407936 1080329248 11.651 97 34 68 0 1136815328 49406560 7079520
04:01:08 143880 8192000000 10097922384 10543616000 1101842800 11.855 99 36 72 0 1205505184 95956368 7706016
04:05:53 74953 8192000000 10080628240 10557915136 1109081120 11.924 98 12 24 0 1183277984 71508992 2687872
04:08:52 46015 8192000000 10048050016 10469339136 1085961408 11.704 96 37 72 1 1112224144 18762112 7500624
04:10:39 69007 8192000000 10042462064 10521493504 1122407152 12.050 100 17 34 0 1171220096 45240720 3572224
04:12:50 81542 8192000000 10110141472 10555359232 1122695376 12.052 99 9 18 0 1175298624 50521312 2081936
04:15:17 31337 8192000000 10070255072 10499796992 1115308736 11.983 98 24 47 0 1140928592 20952432 4667424
04:17:21 43099 8192000000 10148395744 10589708288 1130382944 12.125 99 21 43 0 1218185920 82792080 5010896
04:19:05 72876 8192000000 10084805456 10584055808 1130134112 12.123 99 9 18 0 1185463984 53514208 1815664
04:22:03 31870 8192000000 10081404400 10509402112 1120458720 12.031 98 50 98 3 1159928192 29028144 10441328
04:23:39 124268 8192000000 10105393712 10543722496 1142913632 12.243 99 21 40 0 1193598304 46671056 4013616
04:26:45 87553 8192000000 10104982400 10558631936 1133753024 12.157 98 36 72 1 1184556464 42987760 7815680
04:29:15 49893 8192000000 10098836368 10585456640 1142196176 12.236 99 12 24 1 1186532656 41300016 3036464
04:31:09 49965 8192000000 10116592464 10627727360 1147565040 12.287 100 7 14 1 1224545888 75176624 1804224
04:33:03 62283 8192000000 10110346992 10614116352 1141736768 12.232 99 21 43 1 1193249760 47297696 4215296
04:35:09 61316 8192000000 10109368240 10588717056 1122386512 12.050 97 24 48 1 1213331840 86081056 4864272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2021 04:38:41 to 25-12-2021 04:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 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:01, from 25-12-2021 04:38:57 to 25-12-2021 04:38:58.

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          220984
MON$OLDEST_ACTIVE               226832
MON$OLDEST_SNAPSHOT             226832
MON$NEXT_TRANSACTION            226832
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                       2067296
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:113: 
:114: mscs-test-161.budzdorov.ru    Sat Dec 25 04:37:14 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Sat Dec 25 04:37:18 2021
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Sat Dec 25 04:37:18 2021
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Sat Dec 25 04:37:20 2021
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
--- end of diff output ---

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

25.12.21 04:39:00. 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-12-25 04:39:02 No severe PSQL-related problems occured
25.12.21 04:39:03. End of report.