Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.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 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 15597864 kB
MemAvailable 30677056 kB
Buffers 20 kB
Cached 16075432 kB
SwapCached 3788 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-14 04:37:07
DTS_END                         2021-10-14 04:37:07.5140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37848
TRN_ID                          231469
ATT_ID                          671
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: 9200.95 31811 1104114
customer order: creation 159.08 416 19090
customer order: refuse 14.39 337 1727
order to supplier: creation 43.75 1378 5250
order to supplier: removal 3.40 111845 408
invoice (draft): creation 43.72 3574 5246
invoice (draft): removal 51.72 1034 6206
invoice accept: apply 41.87 18771 5024
invoice accept: cancel 47.12 817 5654
customer reserve: creation 3852.78 186 462333
customer reserve: removal 4660.49 62 559259
realization accept: apply 77.63 200 9316
realization accept: cancel 82.51 121 9901
payment from customer: creation 56.19 124 6743
payment from customer: removal 5.63 115 675
payment to supplier: creation 47.23 135 5668
payment to supplier: removal 3.18 82 382
service: total inventory turnovers 3.56 4024 427
service: total monetary turnovers 6.00 519 720
service: aggregate perf. data 0.68 25280 81
service: refresh index statistics 0.03 467191 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-10-2021 04:38:45 to 14-10-2021 04:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9327
TEST_TIME 2 7736
TEST_TIME 3 6447
TEST_TIME 4 6604
TEST_TIME 5 6852
TEST_TIME 6 6411
TEST_TIME 7 6407
TEST_TIME 8 6568
TEST_TIME 9 6624
TEST_TIME 10 7144
TEST_TIME 11 7447
TEST_TIME 12 7852
TEST_TIME 13 7723
TEST_TIME 14 7741
TEST_TIME 15 7935
TEST_TIME 16 8221
TEST_TIME 17 8210
TEST_TIME 18 8367
TEST_TIME 19 8860
TEST_TIME 20 8924
TEST_TIME 21 8758
TEST_TIME 22 8930
TEST_TIME 23 8933
TEST_TIME 24 9047
TEST_TIME 25 9327
TEST_TIME 26 9827
TEST_TIME 27 10576
TEST_TIME 28 11101
TEST_TIME 29 10800
TEST_TIME 30 11004
TEST_TIME 31 11325
TEST_TIME 32 11169
TEST_TIME 33 11128
TEST_TIME 34 10979
TEST_TIME 35 10741
TEST_TIME 36 10630
TEST_TIME 37 10654
TEST_TIME 38 10516
TEST_TIME 39 10467
TEST_TIME 40 10588
TEST_TIME 41 10508
TEST_TIME 42 10713
TEST_TIME 43 10566
TEST_TIME 44 10336
TEST_TIME 45 10123
TEST_TIME 46 10069
TEST_TIME 47 10082
TEST_TIME 48 9980
TEST_TIME 49 9886
TEST_TIME 50 9942
TEST_TIME 51 9949
TEST_TIME 52 9933
TEST_TIME 53 9847
TEST_TIME 54 9820
TEST_TIME 55 9778
TEST_TIME 56 9692
TEST_TIME 57 9810
TEST_TIME 58 9773
TEST_TIME 59 9780
TEST_TIME 60 9752
TEST_TIME 61 9752
TEST_TIME 62 9728
TEST_TIME 63 9765
TEST_TIME 64 9852
TEST_TIME 65 9823
TEST_TIME 66 9847
TEST_TIME 67 9769
TEST_TIME 68 9874
TEST_TIME 69 9914
TEST_TIME 70 9824
TEST_TIME 71 9777
TEST_TIME 72 9864
TEST_TIME 73 9790
TEST_TIME 74 9734
TEST_TIME 75 9677
TEST_TIME 76 9672
TEST_TIME 77 9664
TEST_TIME 78 9591
TEST_TIME 79 9596
TEST_TIME 80 9593
TEST_TIME 81 9563
TEST_TIME 82 9549
TEST_TIME 83 9540
TEST_TIME 84 9574
TEST_TIME 85 9565
TEST_TIME 86 9688
TEST_TIME 87 9661
TEST_TIME 88 9681
TEST_TIME 89 9659
TEST_TIME 90 9684
TEST_TIME 91 9639
TEST_TIME 92 9550
TEST_TIME 93 9535
TEST_TIME 94 9522
TEST_TIME 95 9473
TEST_TIME 96 9452
TEST_TIME 97 9411
TEST_TIME 98 9401
TEST_TIME 99 9331
TEST_TIME 100 9331
TEST_TIME 101 9306
TEST_TIME 102 9312
TEST_TIME 103 9321
TEST_TIME 104 9408
TEST_TIME 105 9390
TEST_TIME 106 9453
TEST_TIME 107 9430
TEST_TIME 108 9440
TEST_TIME 109 9474
TEST_TIME 110 9458
TEST_TIME 111 9513
TEST_TIME 112 9496
TEST_TIME 113 9477
TEST_TIME 114 9413
TEST_TIME 115 9386
TEST_TIME 116 9347
TEST_TIME 117 9341
TEST_TIME 118 9312
TEST_TIME 119 9272
TEST_TIME 120 9204

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2021 04:38:49 to 14-10-2021 04:38:53.

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 1530925 1530925 0 0.00 0 31963 7 0 0
doc_list_biud 1530925 1530925 0 0.00 0 81734 29 0 0
sp_add_invoice_to_stock 5179 5024 155 2.99 32 269616 18771 0 155
sp_cancel_adding_invoice 5654 5654 0 0.00 5 59142 818 0 0
sp_cancel_client_order 1727 1727 0 0.00 5 12383 338 0 0
sp_cancel_customer_reserve 559259 559259 0 0.00 1 49516 62 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 6478 115 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 2627 82 0 0
sp_cancel_supplier_invoice 6217 6206 11 0.17 2 81739 1035 0 11
sp_cancel_supplier_order 408 408 0 0.00 7 1173698 111845 0 0
sp_cancel_write_off 9901 9901 0 0.00 1 31999 122 0 0
sp_client_order 19090 19090 0 0.00 4 44830 417 0 0
sp_customer_reserve 462333 462333 0 0.00 0 87793 187 0 0
sp_fill_shopping_cart 29578 29578 0 0.00 1 13976 43 0 0
sp_fill_shopping_cart_clo_res 462326 462326 0 0.00 0 16877 15 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 23835 267 0 0
sp_kill_cost_storno 16612 16612 0 0.00 0 31961 64 0 0
sp_kill_qstorno_ret_qs2qd 1135033 1135033 0 0.00 0 81732 35 0 0
sp_kill_qty_storno 597488 597488 0 0.00 0 81733 74 0 0
sp_lock_selected_doc 19969 19969 0 0.00 0 7556 5 0 0
sp_make_cost_storno 26744 26744 0 0.00 0 30551 62 0 0
sp_multiply_rows_for_pdistr 26744 26744 0 0.00 0 13639 35 0 0
sp_multiply_rows_for_qdistr 19090 19090 0 0.00 0 13123 186 0 0
sp_pay_from_customer 6743 6743 0 0.00 4 14042 125 0 0
sp_pay_to_supplier 5668 5668 0 0.00 5 21272 135 0 0
sp_payment_common 12411 12411 0 0.00 1 7424 20 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 9299 291 0 0
sp_qd_handle_on_invoice_upd_sts 10671 10671 0 0.00 0 21065 355 0 0
sp_qd_handle_on_reserve_upd_sts 9316 9316 0 0.00 0 12588 55 0 0
sp_reserve_write_off 9316 9316 0 0.00 7 24933 201 0 0
sp_supplier_invoice 5246 5246 0 0.00 7 77482 3575 0 0
sp_supplier_order 5250 5250 0 0.00 6 58457 1379 0 0
srv_aggregate_perf_data 81 81 0 0.00 3124 547624 25280 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1979 203558 48214 0 0
srv_find_qd_qs_mism 1057479 1057479 0 0.00 0 18535 5 0 0
srv_make_invnt_saldo 427 427 0 0.00 67 96532 4024 0 0
srv_make_money_saldo 720 720 0 0.00 1 15004 519 0 0
srv_recalc_idx_stat 4 4 0 0.00 399214 508240 467191 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 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 5026 5026 0 0.00 0 12090 216 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 2396 75 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 6927 23 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 5562 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 472 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 423 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1394 18 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 28167 1637 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1056 8 0 0
t$perf-norm:sp_client_order 19090 19090 0 0.00 0 10492 18 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 2943 10 0 0
t$perf-norm:sp_pay_from_customer 6743 6743 0 0.00 0 9859 13 0 0
t$perf-norm:sp_pay_to_supplier 5668 5668 0 0.00 0 6447 12 0 0
t$perf-norm:sp_reserve_write_off 9316 9316 0 0.00 0 9858 11 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 9050 23 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 4591 14 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 189 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 636 8 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 278 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 5 0 0
v_add_invoice_to_stock 10344 10034 310 2.99 0 7740 23 0 310
v_all_customers 34984 34984 0 0.00 0 22 1 0 0
v_all_suppliers 29302 29302 0 0.00 0 108 1 0 0
v_all_wares 38180 38180 0 0.00 0 81 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2066 8 0 0
v_cancel_client_order 3454 3454 0 0.00 0 12288 14 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 3962 17 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 983 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 2521 13 0 0
v_cancel_supplier_invoice 814 792 22 2.70 0 2100 16 0 22
v_cancel_supplier_order 816 816 0 0.00 0 12288 25 0 0
v_cancel_write_off 1940 1940 0 0.00 0 3696 22 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 875 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 5219 17 0 0
v_max_id_ord_sup 5240 5240 0 0.00 0 8203 9 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 199 2 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 794 3 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 2834 6 0 0
v_min_id_clo_res 964 964 0 0.00 0 8747 23 0 0
v_min_id_ord_sup 5240 5240 0 0.00 0 8483 12 0 0
v_min_non_paid_invoice 5668 5668 0 0.00 0 7418 16 0 0
v_min_non_paid_realizn 6743 6743 0 0.00 0 6184 11 0 0
v_our_firm 9618 9618 0 0.00 0 25 1 0 0
v_reserve_write_off 18632 18632 0 0.00 0 13564 13 0 0
x_lock_depdocs_on_canc_invoice 11464 11464 0 0.00 0 11703 117 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 7584 364 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 2 58378 1298 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 4 73861 3418 0 0
x_make_qstorno_2100_3300 462333 462333 0 0.00 0 87551 168 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-10-2021 04:38:53 to 14-10-2021 04:38:58.

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:11 20 8192000000 8802636384 8815435776 7421248 0.090 3 1 2 0 7520672 63648 35776
02:08:12 21448 8192000000 9714308576 10006597632 786385584 8.758 100 90 180 0 815857392 7426096 22045712
02:09:40 28356 8192000000 9749360400 10051612672 821919792 9.118 100 90 180 0 855381696 12026640 21435264
02:11:19 14535 8192000000 9804172192 10122362880 871675776 9.617 100 81 162 0 914103792 24213728 18214288
02:12:42 10493 8192000000 9888574416 10222829568 931468352 10.209 100 87 174 1 989287344 39003984 18815008
02:13:56 8723 8192000000 9933255680 10277269504 962724896 10.516 100 90 178 1 1041356320 59563328 19068096
02:15:09 4406 8192000000 9949159712 10304122880 992571168 10.806 100 76 151 0 1062278496 53978064 15729264
02:16:16 3058 8192000000 10015248032 10375401472 1025091904 11.121 100 84 172 0 1124437040 82978960 16366176
02:17:22 1492 8192000000 10027654768 10395017216 1049084800 11.352 100 88 175 1 1136039440 69069008 17885632
02:18:25 1279 8192000000 10032748192 10406813696 1061127792 11.467 100 82 165 1 1144268544 65417200 17723552
02:19:27 5583 8192000000 10006299024 10400358400 1073394544 11.584 100 39 77 1 1119997232 39326592 7276096
02:20:36 53022 8192000000 9990232496 10400952320 1079131600 11.639 100 49 98 3 1120810192 31074816 10603776
02:22:33 5168 8192000000 10006971184 10402267136 1082164816 11.668 100 36 72 0 1116480304 26852560 7462928
02:23:40 4549 8192000000 10009879808 10398937088 1086530048 11.710 100 42 84 1 1113571200 18585120 8456032
02:24:48 8927 8192000000 10027208272 10437857280 1095547088 11.795 100 37 74 1 1133926336 30393600 7985648
02:25:58 24133 8192000000 10024604688 10418044928 1093308816 11.774 100 53 103 2 1122402240 17462832 11630592
02:27:23 6740 8192000000 10015495328 10419286016 1091913840 11.761 100 39 78 0 1129768896 29243840 8611216
02:28:34 6473 8192000000 10001430320 10405175296 1069622224 11.548 99 45 91 0 1105145152 25617552 9905376
02:29:41 8001 8192000000 9967897776 10355576832 1032053936 11.188 98 25 50 1 1053410544 15512016 5844592
02:30:53 21368 8192000000 9925407744 10314674176 949774416 10.389 91 21 42 1 984184176 29948048 4461712
02:32:17 7388 8192000000 9961692352 10343899136 1018327792 11.056 99 48 92 1 1045293088 16880704 10084592
02:33:27 8017 8192000000 9963344208 10354565120 1046679008 11.329 100 44 90 3 1072706128 16013248 10013872
02:34:37 64606 8192000000 9972313440 10374541312 1054821536 11.407 100 17 35 1 1097751408 39342368 3587504
02:36:46 8994 8192000000 9983529904 10388160512 1062568624 11.481 100 40 82 1 1087588752 16376896 8643232
02:38:04 3904 8192000000 10018486432 10409504768 1076801968 11.617 100 68 134 0 1126913952 35623472 14488512
02:39:12 41925 8192000000 10055897616 10463227904 1092671760 11.768 100 49 97 0 1163201744 60346480 10183504
02:40:59 24581 8192000000 10052440864 10455875584 1106517136 11.899 100 38 75 3 1157920976 43077936 8325904
02:42:32 27173 8192000000 10070762656 10477264896 1114907120 11.979 100 44 87 0 1188013600 63564928 9541552
02:44:12 41452 8192000000 10123132912 10547404800 1127847808 12.101 100 33 66 0 1233240384 97697200 7695376
02:45:59 19012 8192000000 10166274640 10577084416 1139829008 12.214 100 60 120 0 1270837296 118505504 12502784
02:47:32 3654 8192000000 10199635312 10596257792 1168700816 12.485 100 70 135 1 1301486320 117962576 14822928
02:48:40 2597 8192000000 10259475632 10662486016 1177384528 12.566 100 74 147 0 1359300960 166799312 15117120
02:49:47 1975 8192000000 10282835328 10690355200 1172941856 12.524 100 71 142 0 1390805040 202504656 15358528
02:50:53 3834 8192000000 10305188496 10706399232 1164918000 12.449 100 83 166 1 1406558768 223330128 18310640
02:52:00 3183 8192000000 10341224688 10749054976 1163376928 12.435 100 79 158 0 1441733152 260932160 17424064
02:53:07 2056 8192000000 10365589376 10769993728 1167242320 12.471 100 88 175 0 1467895792 281022400 19631072
02:54:13 4914 8192000000 10403433760 10810359808 1168249408 12.480 100 74 148 1 1490893936 305717040 16927488
02:55:24 2420 8192000000 10416811104 10810925056 1131745792 12.138 97 91 183 0 1499783520 346307776 21729952
02:56:33 14318 8192000000 10325850704 10754834432 1108643808 11.920 97 62 124 0 1434763520 311400880 14718832
02:57:52 6152 8192000000 10376612080 10805403648 1158296832 12.387 100 39 77 0 1478189776 310248000 9644944
02:59:07 4497 8192000000 10440853392 10851418112 1164934784 12.449 100 70 140 1 1541053680 359012592 17106304
03:00:16 17751 8192000000 10459280288 10885693440 1146687232 12.278 100 41 81 1 1540943824 383689888 10566704
03:01:42 23096 8192000000 10423861408 10880462848 1125351936 12.078 99 41 80 1 1544176928 408102752 10722240
03:03:14 11087 8192000000 10380979872 10835509248 1121206816 12.038 100 31 61 1 1483564112 354209312 8147984
03:04:28 19047 8192000000 10215456656 10709348352 1122378160 12.049 99 24 48 0 1347920016 219768528 5773328
03:05:52 62378 8192000000 10176291072 10614366208 1120762688 12.034 99 21 42 1 1254259904 128433056 5064160
03:08:02 89884 8192000000 10112463968 10672218112 1107053600 11.905 98 10 20 0 1211075136 101822032 2199504
03:10:33 115492 8192000000 10078439040 10501804032 1095632704 11.796 97 8 16 0 1126124896 28965712 1526480
03:13:28 94793 8192000000 10075946592 10505388032 1104998416 11.885 98 33 67 1 1146068752 34344704 6725632
03:16:23 79403 8192000000 10074990560 10555723776 1114485328 11.975 98 15 30 1 1179470048 61452720 3532000
03:18:44 117766 8192000000 10070165472 10560430080 1116949440 11.998 97 10 20 1 1177840736 59299296 1592000
03:22:43 39003 8192000000 10139194176 10569605120 1148567760 12.296 100 49 98 0 1229355856 71302176 9485920
03:24:23 22229 8192000000 10106045728 10565287936 1143750816 12.251 99 32 63 1 1194767568 43112784 7903968
03:25:50 203530 8192000000 10130868592 10609934336 1156108048 12.367 99 17 34 0 1287546112 127707856 3730208
03:30:47 71241 8192000000 10145448176 10628403200 1150479472 12.314 99 23 46 0 1233628624 77839792 5309360
03:33:15 57133 8192000000 10126371552 10648383488 1135083360 12.169 98 12 24 0 1250367008 112520560 2763088
03:35:16 45981 8192000000 10112090240 10563207168 1155183344 12.358 100 37 74 0 1200738176 38284336 7270496
03:37:04 65257 8192000000 10114229920 10572771328 1154411280 12.351 100 17 34 0 1217650384 59855600 3383504
03:39:09 81288 8192000000 10155380832 10592874496 1139375104 12.210 99 32 64 0 1199595840 53908368 6312368
03:42:19 101448 8192000000 10097092592 10584195072 1109998560 11.932 97 13 26 0 1142496096 29739456 2758080
03:45:02 84436 8192000000 10116964560 10588643328 1091911728 11.761 95 19 38 1 1165980448 70091152 3977568
03:47:41 89278 8192000000 10092860624 10619768832 1121903440 12.045 98 24 48 2 1214353328 86977504 5472384
03:50:24 32724 8192000000 10076747552 10530553856 1130203120 12.123 99 23 46 0 1169786288 35347408 4235760
03:52:03 56490 8192000000 10114104000 10563407872 1141369168 12.228 100 25 49 3 1222189520 76133968 4686384
03:54:43 41670 8192000000 10094440592 10545741824 1121336256 12.040 99 20 39 1 1183345744 58177824 3831664
03:56:31 43298 8192000000 10079866144 10587348992 1135920960 12.177 100 10 20 0 1177447152 39255184 2271008
03:58:15 24669 8192000000 10078912448 10508550144 1133485408 12.154 100 30 59 1 1180772448 41047344 6239696
03:59:55 70402 8192000000 10103272592 10540113920 1054887168 11.408 93 26 52 1 1130035008 69393360 5754480
04:02:07 103937 8192000000 10105636016 10638155776 1097393024 11.813 97 18 36 0 1211885312 110417424 4074864
04:05:31 32459 8192000000 10067148192 10524123136 1092053952 11.762 98 39 78 0 1131727984 32371360 7302672
04:07:03 24773 8192000000 10065417584 10526842880 1118475728 12.013 100 26 53 0 1184540192 60675216 5389248
04:08:30 84913 8192000000 10069040192 10506190848 1121713344 12.043 100 22 44 0 1157844416 31777104 4353968
04:12:11 62696 8192000000 10041056112 10490966016 1100971776 11.847 98 12 24 1 1141368464 38010384 2386304
04:14:28 56617 8192000000 10050133888 10512928768 1109738096 11.930 99 16 32 0 1157358208 44416896 3203216
04:16:34 40803 8192000000 10069695632 10529259520 1116936752 11.998 99 11 21 0 1161327504 42541280 1849472
04:18:15 53067 8192000000 10096232288 10549747712 1115630224 11.986 99 21 38 2 1183480032 63566400 4283408
04:20:08 76440 8192000000 10130361200 10571493376 1129735904 12.119 99 19 38 1 1198496608 64413680 4347024
04:22:25 44105 8192000000 10103106320 10559242240 1132129488 12.141 99 16 31 1 1193167504 57981888 3056128
04:24:10 61921 8192000000 10058199328 10529656832 1058225440 11.439 93 29 58 0 1112935776 48956512 5753824
04:26:14 36458 8192000000 10090018352 10542972928 1123387360 12.059 100 23 46 0 1179816672 51307552 5121760
04:27:54 79470 8192000000 10068326528 10532118528 1127226960 12.095 99 29 58 1 1203818992 70782400 5809632
04:30:15 20420 8192000000 10102993280 10528735232 1142550512 12.240 100 37 73 1 1175471600 25471088 7450000
04:31:40 94250 8192000000 10083792272 10546835456 1108190192 11.915 98 34 67 3 1155113584 39195216 7728176
04:34:32 60048 8192000000 10062836448 10519613440 1126968384 12.093 99 14 28 0 1170179024 40377808 2832832
04:36:36 40160 8192000000 9191658560 9481908224 342560960 4.013 30 4 8 0 352649728 9335056 753712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2021 04:38:59 to 14-10-2021 04:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 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 14-10-2021 04:39:15 to 14-10-2021 04:39:17.

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          231727
MON$OLDEST_ACTIVE               231728
MON$OLDEST_SNAPSHOT             231728
MON$NEXT_TRANSACTION            231728
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                       2070368
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Oct 14 04:37:21 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Oct 14 04:37:22 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Oct 14 04:37:22 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Oct 14 04:37:22 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Oct 14 04:37:22 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

14.10.21 04:39:18. 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-14 04:39:21 No severe PSQL-related problems occured
14.10.21 04:39:22. End of report.