Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.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 16189312 kB
MemAvailable 31038164 kB
Buffers 0 kB
Cached 15469876 kB
SwapCached 2580 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-05 10:37:05
DTS_END                         2021-08-05 10:37:05.1330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44610
TRN_ID                          244692
ATT_ID                          719
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: 9061.48 20416 1087378
customer order: creation 177.15 406 21258
customer order: refuse 14.67 334 1760
order to supplier: creation 47.93 1503 5752
order to supplier: removal 3.38 95379 405
invoice (draft): creation 47.49 3446 5699
invoice (draft): removal 48.95 935 5874
invoice accept: apply 43.07 16529 5168
invoice accept: cancel 43.76 839 5251
customer reserve: creation 3989.09 162 478691
customer reserve: removal 4352.38 57 522285
realization accept: apply 86.58 190 10389
realization accept: cancel 75.45 118 9054
payment from customer: creation 61.18 115 7342
payment from customer: removal 5.70 71 684
payment to supplier: creation 50.23 138 6028
payment to supplier: removal 3.62 88 434
service: total inventory turnovers 3.78 3099 454
service: total monetary turnovers 6.22 388 746
service: aggregate perf. data 0.83 17784 100
service: refresh index statistics 0.03 266738 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 10:38:16 to 05-08-2021 10:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4423
TEST_TIME 2 4682
TEST_TIME 3 5207
TEST_TIME 4 4302
TEST_TIME 5 4385
TEST_TIME 6 4843
TEST_TIME 7 4734
TEST_TIME 8 4966
TEST_TIME 9 5288
TEST_TIME 10 5701
TEST_TIME 11 5827
TEST_TIME 12 6531
TEST_TIME 13 7280
TEST_TIME 14 7798
TEST_TIME 15 7836
TEST_TIME 16 8130
TEST_TIME 17 8771
TEST_TIME 18 9741
TEST_TIME 19 9632
TEST_TIME 20 10023
TEST_TIME 21 9936
TEST_TIME 22 10089
TEST_TIME 23 10025
TEST_TIME 24 9940
TEST_TIME 25 9608
TEST_TIME 26 9554
TEST_TIME 27 9569
TEST_TIME 28 9683
TEST_TIME 29 9758
TEST_TIME 30 10185
TEST_TIME 31 10170
TEST_TIME 32 9973
TEST_TIME 33 9941
TEST_TIME 34 10047
TEST_TIME 35 9992
TEST_TIME 36 9871
TEST_TIME 37 9829
TEST_TIME 38 9992
TEST_TIME 39 9979
TEST_TIME 40 10057
TEST_TIME 41 9979
TEST_TIME 42 9891
TEST_TIME 43 9677
TEST_TIME 44 9700
TEST_TIME 45 9545
TEST_TIME 46 9524
TEST_TIME 47 9407
TEST_TIME 48 9415
TEST_TIME 49 9385
TEST_TIME 50 9415
TEST_TIME 51 9439
TEST_TIME 52 9363
TEST_TIME 53 9464
TEST_TIME 54 9458
TEST_TIME 55 9500
TEST_TIME 56 9574
TEST_TIME 57 9528
TEST_TIME 58 9458
TEST_TIME 59 9617
TEST_TIME 60 9564
TEST_TIME 61 9594
TEST_TIME 62 9672
TEST_TIME 63 9702
TEST_TIME 64 9626
TEST_TIME 65 9717
TEST_TIME 66 9754
TEST_TIME 67 9633
TEST_TIME 68 9595
TEST_TIME 69 9520
TEST_TIME 70 9442
TEST_TIME 71 9503
TEST_TIME 72 9411
TEST_TIME 73 9375
TEST_TIME 74 9368
TEST_TIME 75 9343
TEST_TIME 76 9307
TEST_TIME 77 9284
TEST_TIME 78 9300
TEST_TIME 79 9260
TEST_TIME 80 9262
TEST_TIME 81 9354
TEST_TIME 82 9339
TEST_TIME 83 9300
TEST_TIME 84 9298
TEST_TIME 85 9301
TEST_TIME 86 9400
TEST_TIME 87 9390
TEST_TIME 88 9427
TEST_TIME 89 9460
TEST_TIME 90 9397
TEST_TIME 91 9323
TEST_TIME 92 9258
TEST_TIME 93 9270
TEST_TIME 94 9229
TEST_TIME 95 9186
TEST_TIME 96 9157
TEST_TIME 97 9149
TEST_TIME 98 9189
TEST_TIME 99 9153
TEST_TIME 100 9148
TEST_TIME 101 9259
TEST_TIME 102 9263
TEST_TIME 103 9278
TEST_TIME 104 9335
TEST_TIME 105 9337
TEST_TIME 106 9333
TEST_TIME 107 9371
TEST_TIME 108 9356
TEST_TIME 109 9286
TEST_TIME 110 9251
TEST_TIME 111 9203
TEST_TIME 112 9171
TEST_TIME 113 9175
TEST_TIME 114 9130
TEST_TIME 115 9139
TEST_TIME 116 9201
TEST_TIME 117 9189
TEST_TIME 118 9164
TEST_TIME 119 9137
TEST_TIME 120 9063

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-08-2021 10:38:19 to 05-08-2021 10:38:23.

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 1529076 1529076 0 0.00 0 21968 7 0 0
doc_list_biud 1529073 1529073 0 0.00 0 69590 26 0 0
sp_add_invoice_to_stock 5261 5168 93 1.76 51 182030 16529 0 93
sp_cancel_adding_invoice 5251 5251 0 0.00 4 54341 839 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 13327 334 0 0
sp_cancel_customer_reserve 522285 522285 0 0.00 1 54840 58 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 2 1852 72 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 4319 88 0 0
sp_cancel_supplier_invoice 5885 5874 11 0.18 2 69594 935 0 11
sp_cancel_supplier_order 405 405 0 0.00 6 760700 95380 0 0
sp_cancel_write_off 9054 9054 0 0.00 1 11410 119 0 0
sp_client_order 21258 21258 0 0.00 4 21167 407 0 0
sp_customer_reserve 478691 478691 0 0.00 0 76627 163 0 0
sp_fill_shopping_cart 32702 32702 0 0.00 1 16438 44 0 0
sp_fill_shopping_cart_clo_res 478689 478689 0 0.00 0 25693 16 0 0
sp_get_clo_for_invoice 5166 5166 0 0.00 0 40985 351 0 0
sp_kill_cost_storno 15423 15423 0 0.00 0 7260 55 0 0
sp_kill_qstorno_ret_qs2qd 1059903 1059903 0 0.00 0 69589 32 0 0
sp_kill_qty_storno 560182 560182 0 0.00 0 69589 70 0 0
sp_lock_selected_doc 21450 21450 0 0.00 0 10144 5 0 0
sp_make_cost_storno 28925 28925 0 0.00 0 21962 61 0 0
sp_multiply_rows_for_pdistr 28923 28923 0 0.00 0 7186 33 0 0
sp_multiply_rows_for_qdistr 21258 21258 0 0.00 0 18933 225 0 0
sp_pay_from_customer 7342 7342 0 0.00 4 13547 115 0 0
sp_pay_to_supplier 6028 6028 0 0.00 5 20045 138 0 0
sp_payment_common 13370 13370 0 0.00 1 11586 26 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 13287 292 0 0
sp_qd_handle_on_invoice_upd_sts 10415 10415 0 0.00 0 36311 343 0 0
sp_qd_handle_on_reserve_upd_sts 10389 10389 0 0.00 0 17963 57 0 0
sp_reserve_write_off 10389 10389 0 0.00 7 22060 191 0 0
sp_supplier_invoice 5699 5699 0 0.00 7 110185 3447 0 0
sp_supplier_order 5752 5752 0 0.00 6 48285 1504 0 0
srv_aggregate_perf_data 100 100 0 0.00 2783 489700 17784 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2618 132476 48133 0 0
srv_find_qd_qs_mism 1037387 1037387 0 0.00 0 12386 6 0 0
srv_make_invnt_saldo 454 454 0 0.00 60 90929 3099 0 0
srv_make_money_saldo 746 746 0 0.00 1 11278 388 0 0
srv_recalc_idx_stat 4 4 0 0.00 160349 389610 266739 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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 5168 5168 0 0.00 0 10285 124 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 6051 74 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 3455 11 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1901 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 430 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 239 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 1112 13 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 27872 1482 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 953 5 0 0
t$perf-norm:sp_client_order 21258 21258 0 0.00 0 21155 17 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 16795 22 0 0
t$perf-norm:sp_pay_from_customer 7342 7342 0 0.00 0 9126 11 0 0
t$perf-norm:sp_pay_to_supplier 6028 6028 0 0.00 0 8043 8 0 0
t$perf-norm:sp_reserve_write_off 10389 10389 0 0.00 0 7401 10 0 0
t$perf-norm:sp_supplier_invoice 5699 5699 0 0.00 0 7398 15 0 0
t$perf-norm:sp_supplier_order 5752 5752 0 0.00 0 12216 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 343 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 909 8 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 6539 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10514 10328 186 1.76 0 11081 23 0 186
v_all_customers 38512 38512 0 0.00 0 17 1 0 0
v_all_suppliers 31598 31598 0 0.00 0 18 1 0 0
v_all_wares 42516 42516 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 11091 22 0 0
v_cancel_client_order 3520 3520 0 0.00 0 11080 11 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1775 7 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 2797 8 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 2159 12 0 0
v_cancel_supplier_invoice 898 876 22 2.44 0 4752 25 0 22
v_cancel_supplier_order 810 810 0 0.00 0 2098 11 0 0
v_cancel_write_off 2142 2142 0 0.00 0 11090 21 0 0
v_max_id_clo_ord 5752 5752 0 0.00 0 1612 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 4905 22 0 0
v_max_id_ord_sup 5692 5692 0 0.00 0 5023 5 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 1456 3 0 0
v_max_non_paid_realizn 4133 4133 0 0.00 0 707 2 0 0
v_min_id_clo_ord 5752 5752 0 0.00 0 4956 9 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 2433 13 0 0
v_min_id_ord_sup 5692 5692 0 0.00 0 8275 12 0 0
v_min_non_paid_invoice 6028 6028 0 0.00 0 11582 20 0 0
v_min_non_paid_realizn 7342 7342 0 0.00 0 6585 21 0 0
v_our_firm 10422 10422 0 0.00 0 99 1 0 0
v_reserve_write_off 20776 20776 0 0.00 0 11087 9 0 0
x_lock_depdocs_on_canc_invoice 10687 10687 0 0.00 0 11551 97 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 12878 305 0 0
x_make_qstorno_1000_1200 5752 5752 0 0.00 2 48253 1430 0 0
x_make_qstorno_1200_2000 5699 5699 0 0.00 3 110176 3274 0 0
x_make_qstorno_2100_3300 478691 478691 0 0.00 0 76622 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 10:38:24 to 05-08-2021 10:38:28.

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:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 37778 8192000000 9719141520 10016485376 795886880 8.855 100 87 175 0 824492800 7675760 20930160
08:09:50 16004 8192000000 9748416832 10058285056 830692288 9.206 100 82 163 0 863262192 12743744 19826160
08:11:12 18428 8192000000 9801111632 10120806400 868043248 9.581 100 83 166 0 910063872 22798112 19222512
08:12:33 15480 8192000000 9877600224 10212847616 929358176 10.188 100 79 158 1 992233552 45374224 17501152
08:13:53 6517 8192000000 9945133280 10288664576 962870912 10.517 100 90 180 0 1058787088 77298736 18617440
08:15:01 4480 8192000000 9949840432 10308337664 995918912 10.839 100 67 135 2 1070646080 61057984 13669184
08:16:09 8484 8192000000 9984566352 10348503040 1025574416 11.126 100 74 149 0 1093332368 53168784 14589168
08:17:19 2483 8192000000 10012345568 10384191488 1052526608 11.385 100 78 154 1 1125453824 57678944 15248272
08:18:23 31148 8192000000 10001666720 10380128256 1061086416 11.467 100 59 122 0 1114128192 40363248 12678528
08:19:55 5704 8192000000 9991649376 10379624448 1069368688 11.546 100 44 88 1 1097648800 18743200 9536912
08:21:02 7136 8192000000 9989917616 10399346688 1077761280 11.626 100 27 53 1 1112762816 29768064 5233472
08:22:11 9086 8192000000 10020277568 10425405440 1086600576 11.710 100 35 70 1 1114466192 20341872 7523744
08:23:23 33277 8192000000 10021736272 10435112960 1090886416 11.751 100 54 109 1 1125506064 21878784 12740864
08:24:56 4851 8192000000 10004708320 10413363200 1093910016 11.780 100 55 108 2 1144099968 38399616 11790336
08:26:05 6865 8192000000 10009773776 10419761152 1098149696 11.820 100 38 75 1 1118708592 12618112 7940784
08:27:13 8569 8192000000 9978020304 10376036352 1049127152 11.352 97 23 46 0 1075619072 21920864 4571056
08:28:26 53657 8192000000 9947678752 10342232064 952233616 10.413 89 15 31 2 975579184 20431776 2913792
08:30:26 5819 8192000000 9927690912 10306220032 984465680 10.728 96 56 111 2 1010102464 13318288 12318496
08:31:32 6698 8192000000 9938266096 10324217856 1016403680 11.037 99 41 83 0 1043579184 16798752 10376752
08:32:41 7002 8192000000 9964852736 10359386112 1051234080 11.373 100 44 86 3 1080773632 20159088 9380464
08:33:50 20332 8192000000 9978084288 10383966208 1060452672 11.461 100 53 106 1 1108023504 36280560 11290272
08:35:11 6673 8192000000 9973031568 10372349952 1066056032 11.514 100 46 93 2 1086425680 10488384 9881264
08:36:18 11111 8192000000 9993266672 10406264832 1075503264 11.605 100 39 78 1 1113108688 28375248 9230176
08:37:32 34571 8192000000 10033122128 10430058496 1104265648 11.878 100 46 93 1 1140084544 25697680 10121216
08:39:23 92284 8192000000 10123505808 10568601600 1127002192 12.093 100 29 58 0 1259918304 126239424 6676688
08:42:03 29301 8192000000 10143171904 10551758848 1123307968 12.058 99 52 104 1 1252935376 117949072 11678336
08:43:39 29842 8192000000 10172822800 10593116160 1147562560 12.287 100 34 68 1 1279992560 124490448 7939552
08:45:24 7558 8192000000 10253052272 10666602496 1158587296 12.390 100 63 126 0 1351852080 178474224 14790560
08:46:37 17453 8192000000 10269392496 10694103040 1164095040 12.442 100 47 94 0 1368446688 193525792 10825856
08:48:02 7938 8192000000 10263816976 10698743808 1168811232 12.486 100 41 82 0 1375531184 196417072 10302880
08:49:18 2945 8192000000 10272952848 10730729472 1168390192 12.482 100 72 145 1 1377945168 192847232 16707744
08:50:24 2819 8192000000 10256989296 10669871104 1159474608 12.398 99 82 164 4 1348600912 171736528 17389776
08:51:30 2617 8192000000 10249162624 10658881536 1154908464 12.356 100 66 130 4 1341080448 172302160 13869824
08:52:39 18728 8192000000 10244510112 10676400128 1133395344 12.153 99 28 56 2 1353386608 212913312 7077952
08:53:58 3365 8192000000 10230262000 10643578880 1108419456 11.917 99 70 138 3 1323549536 198207488 16922592
08:55:07 7606 8192000000 10188291904 10603548672 1098461808 11.823 99 46 91 3 1293057328 183141520 11454000
08:56:26 13509 8192000000 10113015376 10529538048 1083241520 11.678 98 37 73 0 1187908992 95671840 8995632
08:57:41 22813 8192000000 10140154560 10576867328 1100111856 11.839 99 18 36 1 1229075824 124392240 4571728
08:59:15 11005 8192000000 10156833264 10579406848 1107053952 11.905 99 44 88 0 1244405264 128241728 9109584
09:00:30 28483 8192000000 10094391440 10520428544 1120080976 12.028 100 21 42 1 1193780432 69231968 4467488
09:02:02 36146 8192000000 10144036992 10588278784 1124895568 12.073 100 32 64 3 1248025280 114483536 8646176
09:04:05 28373 8192000000 10199880864 10654982144 1102967984 11.866 98 21 42 1 1298876880 190732592 5176304
09:05:34 47913 8192000000 10169609904 10660462592 1107834288 11.912 99 20 40 1 1280315328 168233376 4247664
09:07:23 57807 8192000000 10082329664 10557181952 1096711536 11.806 98 12 25 0 1146271136 47267104 2292496
09:09:27 30430 8192000000 10056971920 10467450880 1111624752 11.948 99 42 81 1 1146324896 25477232 9222912
09:10:58 48161 8192000000 10080549024 10552205312 1116117168 11.990 99 7 14 0 1182079824 64630256 1332400
09:12:46 75723 8192000000 10095254496 10520154112 1131995120 12.140 99 38 76 3 1157623504 17538336 8090048
09:15:05 112339 8192000000 10078828112 10523820032 1131659264 12.137 99 11 21 0 1187221856 53454144 2108448
09:17:59 95974 8192000000 10106649664 10556334080 1143634704 12.250 99 18 35 1 1189373888 41951296 3787888
09:20:39 69984 8192000000 10058467424 10517569536 1147210944 12.283 100 23 46 0 1189297616 36732304 5354368
09:22:51 18939 8192000000 10086598336 10525184000 1150674976 12.316 100 24 46 1 1179557536 24059424 4823136
09:24:11 32063 8192000000 10159153904 10593587200 1157431520 12.379 99 47 93 1 1226601552 59000736 10169296
09:26:17 104959 8192000000 10101786192 10603184128 1107271856 11.907 95 18 35 3 1191678336 80308832 4097648
09:29:09 70604 8192000000 10154092400 10625351680 1141905328 12.233 99 14 28 0 1251622720 106448656 3268736
09:31:52 54804 8192000000 10095558064 10565152768 1136189888 12.180 99 20 40 0 1207320400 66992624 4137888
09:33:54 48386 8192000000 10071866944 10509742080 1109525168 11.928 97 29 58 0 1141610992 25944192 6141632
09:35:43 23469 8192000000 10118263456 10548289536 1106286640 11.897 98 36 72 0 1186267808 72630240 7350928
09:37:10 38500 8192000000 10069324304 10490339328 1089071072 11.734 96 26 52 0 1133187952 38924224 5192656
09:38:52 38305 8192000000 10083226304 10544144384 1105754336 11.892 99 21 42 0 1179200288 69170752 4275200
09:40:40 81943 8192000000 10105262656 10565054464 1099130640 11.829 97 15 30 1 1180548416 78319488 3098288
09:43:03 65132 8192000000 10076482800 10563006464 1120865056 12.035 100 20 40 0 1185688192 60916816 3906320
09:45:08 101632 8192000000 10089690288 10552721408 1080437328 11.652 96 18 36 1 1114095840 29990432 3668080
09:48:38 56290 8192000000 10062053856 10528493568 1074474928 11.595 96 9 18 1 1138099216 61628560 1995728
09:50:52 55412 8192000000 10067020096 10538000384 1075545584 11.605 97 21 42 0 1160166224 79832944 4787696
09:52:57 61129 8192000000 10068291024 10533187584 1111412000 11.946 100 19 36 2 1187459088 71507504 4539584
09:55:14 45188 8192000000 10053193152 10493689856 1098518672 11.824 98 17 33 1 1146869408 45156560 3194176
09:57:08 50526 8192000000 10108189072 10570555392 1107279552 11.907 98 24 49 1 1184987840 72610944 5097344
09:59:02 64401 8192000000 10050627216 10495098880 1092356720 11.765 97 22 44 1 1119472656 22463296 4652640
10:01:07 56256 8192000000 10104329072 10551971840 1118481888 12.013 99 28 56 0 1195027024 70809584 5735552
10:03:05 84062 8192000000 10073607264 10539094016 1136670416 12.184 100 16 32 0 1203922496 64085072 3167008
10:05:30 47183 8192000000 10074725040 10536738816 1092385872 11.765 96 21 42 0 1142430976 46209056 3836048
10:07:18 116814 8192000000 10089461744 10542112768 1149714000 12.307 100 23 46 0 1191111536 36560432 4837104
10:10:18 41206 8192000000 10061243488 10512183296 1126209808 12.086 99 16 32 0 1153578064 24354592 3013664
10:12:00 70798 8192000000 10113250592 10581073920 1141125440 12.226 99 16 32 1 1199852560 55356880 3370240
10:14:14 47483 8192000000 10155053616 10621755392 1158538448 12.390 100 29 58 0 1262555248 97133792 6883008
10:16:22 21041 8192000000 10194343104 10646892544 1155136720 12.358 99 26 51 0 1281641888 120491904 6013264
10:17:50 60276 8192000000 10138532272 10658398208 1139454304 12.210 98 25 51 0 1299830208 154182784 6193120
10:20:05 33322 8192000000 10122026064 10579251200 1096508688 11.805 96 40 80 0 1200178720 94631264 9038768
10:21:42 49972 8192000000 10096639344 10586468352 1111249072 11.944 99 29 58 0 1180527360 63423344 5854944
10:23:33 36169 8192000000 10102588384 10532728832 1081401152 11.661 96 33 66 2 1185124096 96486592 7236352
10:25:14 132475 8192000000 10053303392 10504085504 1101229696 11.849 98 23 46 0 1134032688 28076144 4726848
10:29:17 64931 8192000000 10061979360 10530316288 1067307424 11.526 96 8 16 0 1124591152 55578016 1705712
10:31:32 61298 8192000000 10106550656 10587041792 1109426176 11.927 99 22 44 1 1202004144 87790160 4787808
10:33:38 65773 8192000000 10059838672 10505641984 1099053440 11.829 98 18 36 0 1134116864 30876896 4186528
10:35:55 47588 8192000000 10092513232 10535440384 1103091488 11.867 98 17 34 0 1165678608 59247648 3339472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-08-2021 10:38:30 to 05-08-2021 10:38:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 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 05-08-2021 10:38:46 to 05-08-2021 10:38:47.

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          245021
MON$OLDEST_ACTIVE               245022
MON$OLDEST_SNAPSHOT             245022
MON$NEXT_TRANSACTION            245022
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                       2051616
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             822
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2558 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2558 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2558 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Aug  5 10:37:18 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Aug  5 10:37:41 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

05.08.21 10:38:49. 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-05 10:38:51 No severe PSQL-related problems occured
05.08.21 10:38:52. End of report.