Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.08.2021 10:37
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 16258860 kB
MemAvailable 30901560 kB
Buffers 0 kB
Cached 15398052 kB
SwapCached 1324 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-08-28 10:37:06
DTS_END                         2021-08-28 10:37:06.8750
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/40486
TRN_ID                          237437
ATT_ID                          593
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: 8903.21 21296 1068385
customer order: creation 180.40 379 21648
customer order: refuse 15.18 370 1821
order to supplier: creation 46.83 1478 5619
order to supplier: removal 3.21 111376 385
invoice (draft): creation 47.45 3840 5694
invoice (draft): removal 47.38 1044 5685
invoice accept: apply 43.36 19727 5203
invoice accept: cancel 42.45 834 5094
customer reserve: creation 3996.15 196 479538
customer reserve: removal 4180.48 66 501658
realization accept: apply 87.53 195 10504
realization accept: cancel 80.47 136 9657
payment from customer: creation 61.20 123 7344
payment from customer: removal 5.63 86 675
payment to supplier: creation 50.55 122 6066
payment to supplier: removal 3.23 61 388
service: total inventory turnovers 4.00 3732 480
service: total monetary turnovers 6.66 554 799
service: aggregate perf. data 1.02 12982 122
service: refresh index statistics 0.04 268624 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-08-2021 10:37:46 to 28-08-2021 10:37:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4503
TEST_TIME 2 3615
TEST_TIME 3 3571
TEST_TIME 4 3955
TEST_TIME 5 3995
TEST_TIME 6 4483
TEST_TIME 7 4400
TEST_TIME 8 5006
TEST_TIME 9 5794
TEST_TIME 10 6419
TEST_TIME 11 6328
TEST_TIME 12 6433
TEST_TIME 13 6367
TEST_TIME 14 6542
TEST_TIME 15 6834
TEST_TIME 16 6846
TEST_TIME 17 7022
TEST_TIME 18 7454
TEST_TIME 19 7676
TEST_TIME 20 7812
TEST_TIME 21 8023
TEST_TIME 22 8508
TEST_TIME 23 8547
TEST_TIME 24 8748
TEST_TIME 25 8607
TEST_TIME 26 9318
TEST_TIME 27 9232
TEST_TIME 28 9389
TEST_TIME 29 9824
TEST_TIME 30 10135
TEST_TIME 31 10252
TEST_TIME 32 10417
TEST_TIME 33 10155
TEST_TIME 34 9906
TEST_TIME 35 9798
TEST_TIME 36 9719
TEST_TIME 37 9687
TEST_TIME 38 9740
TEST_TIME 39 9633
TEST_TIME 40 9631
TEST_TIME 41 9633
TEST_TIME 42 9630
TEST_TIME 43 9675
TEST_TIME 44 9563
TEST_TIME 45 9463
TEST_TIME 46 9347
TEST_TIME 47 9292
TEST_TIME 48 9474
TEST_TIME 49 9540
TEST_TIME 50 9462
TEST_TIME 51 9452
TEST_TIME 52 9595
TEST_TIME 53 9577
TEST_TIME 54 9550
TEST_TIME 55 9559
TEST_TIME 56 9604
TEST_TIME 57 9538
TEST_TIME 58 9555
TEST_TIME 59 9714
TEST_TIME 60 9617
TEST_TIME 61 9494
TEST_TIME 62 9460
TEST_TIME 63 9400
TEST_TIME 64 9406
TEST_TIME 65 9348
TEST_TIME 66 9281
TEST_TIME 67 9203
TEST_TIME 68 9080
TEST_TIME 69 9065
TEST_TIME 70 9041
TEST_TIME 71 9113
TEST_TIME 72 9239
TEST_TIME 73 9188
TEST_TIME 74 9317
TEST_TIME 75 9292
TEST_TIME 76 9239
TEST_TIME 77 9261
TEST_TIME 78 9271
TEST_TIME 79 9324
TEST_TIME 80 9320
TEST_TIME 81 9348
TEST_TIME 82 9403
TEST_TIME 83 9358
TEST_TIME 84 9305
TEST_TIME 85 9230
TEST_TIME 86 9217
TEST_TIME 87 9202
TEST_TIME 88 9143
TEST_TIME 89 9113
TEST_TIME 90 9087
TEST_TIME 91 9073
TEST_TIME 92 9048
TEST_TIME 93 9061
TEST_TIME 94 9066
TEST_TIME 95 9050
TEST_TIME 96 9040
TEST_TIME 97 9110
TEST_TIME 98 9120
TEST_TIME 99 9143
TEST_TIME 100 9118
TEST_TIME 101 9133
TEST_TIME 102 9155
TEST_TIME 103 9202
TEST_TIME 104 9140
TEST_TIME 105 9093
TEST_TIME 106 9066
TEST_TIME 107 9032
TEST_TIME 108 8984
TEST_TIME 109 8942
TEST_TIME 110 8911
TEST_TIME 111 8923
TEST_TIME 112 8929
TEST_TIME 113 8945
TEST_TIME 114 8922
TEST_TIME 115 8934
TEST_TIME 116 8939
TEST_TIME 117 8976
TEST_TIME 118 8938
TEST_TIME 119 8976
TEST_TIME 120 8907

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-08-2021 10:37:50 to 28-08-2021 10:37: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 1511417 1511417 0 0.00 0 19366 7 0 0
doc_list_biud 1511417 1511417 0 0.00 0 84691 29 0 0
sp_add_invoice_to_stock 5356 5203 153 2.85 20 233120 19727 0 153
sp_cancel_adding_invoice 5094 5094 0 0.00 5 74225 835 0 0
sp_cancel_client_order 1821 1821 0 0.00 5 9005 371 0 0
sp_cancel_customer_reserve 501658 501658 0 0.00 1 42090 66 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 5121 87 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4046 62 0 0
sp_cancel_supplier_invoice 5702 5685 17 0.29 2 84704 1044 0 17
sp_cancel_supplier_order 385 385 0 0.00 7 1105271 111376 0 0
sp_cancel_write_off 9657 9657 0 0.00 1 20763 136 0 0
sp_client_order 21648 21648 0 0.00 4 63270 379 0 0
sp_customer_reserve 479538 479538 0 0.00 0 119718 196 0 0
sp_fill_shopping_cart 32949 32949 0 0.00 1 15757 46 0 0
sp_fill_shopping_cart_clo_res 479529 479529 0 0.00 0 17485 17 0 0
sp_get_clo_for_invoice 5194 5194 0 0.00 0 25906 342 0 0
sp_kill_cost_storno 15814 15814 0 0.00 0 9613 63 0 0
sp_kill_qstorno_ret_qs2qd 1019043 1019043 0 0.00 0 84688 37 0 0
sp_kill_qty_storno 539998 539998 0 0.00 0 84689 79 0 0
sp_lock_selected_doc 21647 21647 0 0.00 0 6331 5 0 0
sp_make_cost_storno 29108 29108 0 0.00 0 19318 62 0 0
sp_multiply_rows_for_pdistr 29108 29108 0 0.00 0 9812 32 0 0
sp_multiply_rows_for_qdistr 21648 21648 0 0.00 0 63263 178 0 0
sp_pay_from_customer 7344 7344 0 0.00 4 15513 124 0 0
sp_pay_to_supplier 6066 6066 0 0.00 5 13898 122 0 0
sp_payment_common 13410 13410 0 0.00 1 13816 22 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 8998 338 0 0
sp_qd_handle_on_invoice_upd_sts 10288 10288 0 0.00 0 31055 354 0 0
sp_qd_handle_on_reserve_upd_sts 10504 10504 0 0.00 0 16583 63 0 0
sp_reserve_write_off 10504 10504 0 0.00 7 24487 196 0 0
sp_supplier_invoice 5694 5694 0 0.00 6 91192 3841 0 0
sp_supplier_order 5619 5619 0 0.00 5 31561 1478 0 0
srv_aggregate_perf_data 122 122 0 0.00 3024 103665 12983 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1496 135580 41937 0 0
srv_find_qd_qs_mism 1018480 1018480 0 0.00 0 17612 7 0 0
srv_make_invnt_saldo 480 480 0 0.00 45 99863 3732 0 0
srv_make_money_saldo 799 799 0 0.00 1 15364 555 0 0
srv_recalc_idx_stat 5 5 0 0.00 153444 365280 268624 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5204 5204 0 0.00 0 10965 177 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 4135 100 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 4125 12 0 0
t$perf-norm:sp_cancel_customer_reserve 752 752 0 0.00 0 1321 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 7053 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1852 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 6814 30 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 19743 1541 0 0
t$perf-norm:sp_cancel_write_off 1090 1090 0 0.00 0 1445 7 0 0
t$perf-norm:sp_client_order 21648 21648 0 0.00 0 10561 19 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 2781 7 0 0
t$perf-norm:sp_pay_from_customer 7344 7344 0 0.00 0 9682 11 0 0
t$perf-norm:sp_pay_to_supplier 6066 6066 0 0.00 0 10078 9 0 0
t$perf-norm:sp_reserve_write_off 10504 10504 0 0.00 0 10961 11 0 0
t$perf-norm:sp_supplier_invoice 5694 5694 0 0.00 0 6586 14 0 0
t$perf-norm:sp_supplier_order 5619 5619 0 0.00 0 15195 20 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 330 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 3575 63 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 1282 14 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 631 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 19 7 0 0
v_add_invoice_to_stock 10694 10388 306 2.86 0 5733 19 0 306
v_all_customers 39418 39418 0 0.00 0 22 1 0 0
v_all_suppliers 31620 31620 0 0.00 0 21 1 0 0
v_all_wares 43296 43296 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2339 11 0 0
v_cancel_client_order 3642 3642 0 0.00 0 2402 6 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 597 4 0 0
v_cancel_customer_reserve 1504 1504 0 0.00 0 1300 5 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 458 3 0 0
v_cancel_supplier_invoice 862 828 34 3.94 0 1026 14 0 34
v_cancel_supplier_order 770 770 0 0.00 0 1674 8 0 0
v_cancel_write_off 2180 2180 0 0.00 0 1376 14 0 0
v_max_id_clo_ord 5618 5618 0 0.00 0 592 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 3436 21 0 0
v_max_id_ord_sup 5683 5683 0 0.00 0 4014 4 0 0
v_max_non_paid_invoice 1557 1557 0 0.00 0 466 2 0 0
v_max_non_paid_realizn 3898 3898 0 0.00 0 5149 4 0 0
v_min_id_clo_ord 5618 5618 0 0.00 0 10362 13 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 6557 18 0 0
v_min_id_ord_sup 5683 5683 0 0.00 0 4189 8 0 0
v_min_non_paid_invoice 6066 6066 0 0.00 0 5506 13 0 0
v_min_non_paid_realizn 7344 7344 0 0.00 0 7652 16 0 0
v_our_firm 10770 10770 0 0.00 0 16 1 0 0
v_reserve_write_off 21008 21008 0 0.00 0 3795 5 0 0
x_lock_depdocs_on_canc_invoice 10365 10365 0 0.00 0 19239 119 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 11801 380 0 0
x_make_qstorno_1000_1200 5619 5619 0 0.00 2 31542 1387 0 0
x_make_qstorno_1200_2000 5694 5694 0 0.00 3 90310 3666 0 0
x_make_qstorno_2100_3300 479538 479538 0 0.00 0 119716 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-08-2021 10:37:54 to 28-08-2021 10:37: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
08:07:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 26321 8192000000 9735699200 10039242752 812638224 9.024 100 91 182 0 842811600 8094368 22079008
08:09:40 18452 8192000000 9778809392 10089713664 851058896 9.411 100 86 172 0 882767136 11814512 19893728
08:11:10 28895 8192000000 9824867712 10145689600 887805904 9.777 100 84 168 2 926766528 20231568 18729056
08:12:41 12851 8192000000 9860187552 10193326080 916977376 10.066 100 78 154 2 963380224 29297136 17105712
08:14:01 8681 8192000000 9913297120 10257432576 958589184 10.475 100 79 158 1 1029103296 54095328 16418784
08:15:16 8343 8192000000 9965565488 10320334848 988591088 10.768 100 82 165 2 1084914768 79257664 17066016
08:16:27 3800 8192000000 9993544384 10353623040 1012933440 11.004 100 76 151 1 1099673008 71363904 15375664
08:17:33 2483 8192000000 10015652416 10385338368 1041195232 11.276 100 84 168 0 1127035168 69085088 16754848
08:18:37 1318 8192000000 10041831920 10411040768 1061923856 11.475 100 90 178 2 1157027584 76353536 18750192
08:19:39 8093 8192000000 10034234176 10416504832 1066515520 11.519 100 61 120 0 1144399472 65219344 12664608
08:20:48 5478 8192000000 9977033264 10369716224 1072994976 11.581 100 37 72 0 1104629648 24284880 7349792
08:21:56 6725 8192000000 9994414224 10387025920 1081067488 11.658 100 40 78 3 1110948464 21354096 8526880
08:23:07 58726 8192000000 10006911296 10396164096 1089735232 11.740 100 55 107 0 1121719536 19177920 12806384
08:25:06 3333 8192000000 10012350048 10409340928 1089682288 11.740 100 47 91 5 1128575776 29567888 9325600
08:26:12 4836 8192000000 10010637392 10404896768 1071909232 11.570 98 39 75 0 1112452240 32639920 7903088
08:27:21 4698 8192000000 10022295840 10418073600 1095879264 11.799 100 46 90 0 1138297360 32668864 9749232
08:28:26 52863 8192000000 9994125056 10397503488 1045899152 11.321 96 30 60 1 1081632336 29384224 6348960
08:30:23 9394 8192000000 9966599312 10352439296 1023842448 11.109 97 33 66 3 1050182608 19630880 6709280
08:31:35 5892 8192000000 9953527856 10344349696 1024886016 11.119 98 48 96 0 1058049152 21609536 11553600
08:32:41 8579 8192000000 9925551840 10305462272 973825264 10.624 95 29 58 2 1001571792 21991056 5755472
08:33:50 42804 8192000000 9939547136 10326552576 987196464 10.754 95 25 50 0 1014694848 21854816 5643568
08:35:35 8706 8192000000 9957681328 10352238592 1049083392 11.352 100 48 95 2 1076418176 16631840 10702944
08:36:44 6905 8192000000 9969682000 10364170240 1064808768 11.502 100 49 96 1 1091587024 15282640 11495616
08:38:07 33347 8192000000 10041601904 10431836160 1088827072 11.732 100 54 108 2 1135441424 35723664 10890688
08:39:49 135580 8192000000 10088575888 10511798272 1103832816 11.874 100 36 71 2 1182230384 71042880 7354688
08:43:08 41121 8192000000 10121531376 10540412928 1124065808 12.065 100 40 80 0 1229318208 96097440 9154960
08:44:54 7866 8192000000 10188026416 10596495360 1121408592 12.040 100 64 128 2 1287050272 152101440 13540240
08:46:07 7274 8192000000 10153477072 10580303872 1140848560 12.224 100 63 124 0 1263821200 109650992 13321648
08:47:18 2669 8192000000 10169577440 10599976960 1151480448 12.323 100 80 160 1 1279149648 110226224 17442976
08:48:23 10267 8192000000 10214855696 10632142848 1154302448 12.350 100 67 134 0 1322461872 153748608 14410816
08:49:41 14555 8192000000 10216970960 10634899456 1165942000 12.459 100 50 98 2 1313903136 136624928 11336208
08:51:01 1826 8192000000 10267538368 10675449856 1174546784 12.539 100 80 160 1 1370936688 177914832 18475072
08:52:09 1494 8192000000 10307166736 10723594240 1182398304 12.613 100 92 185 1 1407038704 204988848 19651552
08:53:17 4514 8192000000 10336332992 10753560576 1185786352 12.644 100 68 136 2 1439415904 237699296 15930256
08:54:28 3913 8192000000 10352698704 10770472960 1171618768 12.512 100 80 159 2 1456433952 265021280 19793904
08:55:39 2330 8192000000 10380856160 10791149568 1175063984 12.544 100 87 174 0 1481356016 286200496 20091536
08:56:45 6361 8192000000 10355660400 10787479552 1165135008 12.451 100 42 84 0 1464321616 289344496 9842112
08:57:55 7637 8192000000 10325783680 10766639104 1138724512 12.204 99 40 78 0 1430173184 281390688 10057984
08:59:08 9348 8192000000 10334653296 10782142464 1134959600 12.168 98 45 86 1 1448272128 302050432 11262096
09:00:23 7740 8192000000 10302585488 10722779136 1130197840 12.123 99 39 78 4 1393359760 253639392 9522528
09:01:32 23598 8192000000 10281054240 10730897408 1108057344 11.914 99 24 48 0 1386864000 272568112 6238544
09:03:01 10839 8192000000 10300144512 10742722560 1118335168 12.011 100 40 80 0 1400295680 272172624 9787888
09:04:16 20859 8192000000 10223034656 10649243648 1098550944 11.824 99 28 56 1 1299831264 194893376 6386944
09:05:40 17656 8192000000 10183532176 10638655488 1088727504 11.731 99 21 42 1 1309838304 215412208 5698592
09:07:06 46777 8192000000 10102252480 10554761216 1089780384 11.741 98 13 26 2 1189742768 97258048 2704336
09:08:53 40438 8192000000 10051383664 10549256192 1098999168 11.828 99 15 31 0 1178550528 76017488 3533872
09:10:52 86285 8192000000 10078563984 10510147584 1102799040 11.864 99 55 109 1 1142974608 28562096 11613472
09:13:20 63905 8192000000 10132292672 10565914624 1125881376 12.083 100 27 55 1 1239797600 107902912 6013312
09:15:26 69616 8192000000 10099832832 10530881536 1100031968 11.838 97 28 56 0 1148067360 41737904 6297488
09:17:38 24573 8192000000 10136518096 10589487104 1131281536 12.133 99 40 83 0 1251048784 110551664 9215584
09:19:03 44337 8192000000 10115140160 10579537920 1124548512 12.070 99 19 37 0 1218399632 89420944 4430176
09:20:49 23977 8192000000 10061139712 10503385088 1141567120 12.230 100 18 36 1 1178452368 32733120 4152128
09:22:34 38847 8192000000 10140913168 10587832320 1155626480 12.362 100 21 42 3 1225384800 64888272 4870048
09:24:14 43343 8192000000 10167730432 10659557376 1137500944 12.192 99 17 34 2 1271259024 129925168 3832912
09:26:32 44749 8192000000 10108480240 10606264320 1146622176 12.278 100 18 34 1 1218262960 67668960 3971824
09:28:17 66622 8192000000 10133369936 10613268480 1142031808 12.235 99 27 54 0 1242052640 94069920 5950912
09:30:32 38465 8192000000 10087543744 10557132800 1132828432 12.148 98 15 30 0 1191418112 55478800 3110880
09:32:12 42093 8192000000 10120597584 10572013568 1145785232 12.270 99 30 60 1 1215228992 63179872 6263888
09:34:05 19660 8192000000 10110449904 10550775808 1144704272 12.260 99 38 76 0 1182265952 29260496 8301184
09:35:24 75096 8192000000 10157624368 10637041664 1148701008 12.297 99 17 32 2 1238306800 85688992 3916800
09:37:43 79680 8192000000 10104939984 10538524672 1089201968 11.735 94 35 70 1 1146574832 50012608 7360256
09:40:09 50753 8192000000 10100042976 10580447232 1131496192 12.135 99 27 54 0 1196722016 59256848 5968976
09:42:01 24885 8192000000 10074300400 10518921216 1122616464 12.052 98 19 37 0 1148038720 22012432 3409824
09:43:27 45548 8192000000 10088372848 10540883968 1119201808 12.019 97 13 25 1 1169045248 47166944 2676496
09:45:13 59028 8192000000 10107203136 10593538048 1137694736 12.194 99 17 34 0 1228051392 86449792 3906864
09:47:37 54229 8192000000 10160078192 10635026432 1124074800 12.065 98 13 25 1 1271415232 144757200 2583232
09:49:43 32827 8192000000 10069334032 10514558976 1125864800 12.082 99 21 42 0 1161311088 31027392 4418896
09:51:17 48992 8192000000 10062886448 10575269888 1104898016 11.884 97 9 18 0 1199354768 92310096 2146656
09:53:12 31383 8192000000 10065137680 10486292480 1055187728 11.410 94 35 70 0 1093366848 30301568 7877552
09:54:48 30230 8192000000 10071720544 10520997888 1101045456 11.848 99 25 50 0 1180189648 74463984 4680208
09:56:19 48852 8192000000 10090707792 10576285696 1087759520 11.721 97 36 72 1 1183594608 88300176 7534912
09:58:16 27863 8192000000 10069627808 10492182528 1090201952 11.745 98 34 68 0 1149172976 52258336 6712688
09:59:45 61187 8192000000 10035290448 10478116864 1069878352 11.551 97 24 48 0 1111597744 36956976 4762416
10:01:49 83855 8192000000 10036095680 10471145472 1096379984 11.803 99 18 35 1 1124887488 24820160 3687344
10:04:14 89454 8192000000 10030081472 10490753024 1084635104 11.692 97 21 42 0 1121184976 32293088 4256784
10:06:43 52821 8192000000 10051777120 10518724608 1121977360 12.046 100 4 8 0 1157830336 35035296 817680
10:08:42 33364 8192000000 10060026128 10496905216 1116990736 11.999 99 31 57 2 1143408592 20413728 6004128
10:10:16 55924 8192000000 10110810720 10575429632 1146456304 12.276 100 27 54 1 1220084464 68235808 5392352
10:12:15 59033 8192000000 10108037616 10566791168 1125525952 12.079 99 21 42 1 1202815616 72984704 4304960
10:14:14 34359 8192000000 10082971376 10551812096 1139080112 12.207 99 28 56 0 1184586560 39349888 6156560
10:15:50 78150 8192000000 10081354720 10519969792 1132127248 12.141 98 27 53 3 1186373008 48673264 5572496
10:18:12 43430 8192000000 10112696768 10577682432 1141523952 12.230 99 24 47 2 1214491072 68171632 4795488
10:20:01 39809 8192000000 10112383824 10555871232 1109165312 11.925 97 38 76 1 1153965472 37512208 7287952
10:21:44 96230 8192000000 10063989248 10525483008 1135783312 12.176 100 23 46 2 1180918672 39465888 5669472
10:24:25 64808 8192000000 10046602912 10494849024 1110945776 11.941 97 14 29 1 1143082336 29244704 2891856
10:26:30 55076 8192000000 10072906624 10530521088 1110775552 11.940 98 7 14 0 1162367536 50184000 1407984
10:28:25 60266 8192000000 10101503984 10550853632 1106187440 11.896 97 23 46 1 1148117712 37179568 4750704
10:30:58 28355 8192000000 10042650896 10490937344 1094125136 11.782 97 16 32 1 1116002416 19004176 2873104
10:32:28 122477 8192000000 10121020912 10616897536 1120096496 12.028 99 24 48 1 1222330896 97016224 5218176
10:35:37 77185 8192000000 10073336640 10552885248 1102978384 11.866 97 5 10 0 1176868656 72861728 1028544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-08-2021 10:38:00 to 28-08-2021 10:38:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-08-2021 10:38:17 to 28-08-2021 10:38:19.

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          236780
MON$OLDEST_ACTIVE               237556
MON$OLDEST_SNAPSHOT             237556
MON$NEXT_TRANSACTION            237556
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                       2026336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2574 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2574 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2574 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Aug 28 10:37:09 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Aug 28 10:37:13 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Aug 28 10:37:13 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

28.08.21 10:38:20. 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-08-28 10:38:22 No severe PSQL-related problems occured
28.08.21 10:38:23. End of report.