Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.04.2021 04:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
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 16683432 kB
MemAvailable 31365908 kB
Buffers 20 kB
Cached 14872860 kB
SwapCached 0 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.0
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-04-04 04:26:58
DTS_END                         2021-04-04 04:26:58.6650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51586
TRN_ID                          240303
ATT_ID                          707
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: 9021.10 25915 1082532
customer order: creation 173.40 381 20808
customer order: refuse 15.78 316 1894
order to supplier: creation 46.96 1375 5635
order to supplier: removal 3.36 113861 403
invoice (draft): creation 46.98 3309 5638
invoice (draft): removal 49.98 952 5998
invoice accept: apply 42.49 17269 5099
invoice accept: cancel 44.66 928 5359
customer reserve: creation 3892.83 169 467139
customer reserve: removal 4420.03 69 530404
realization accept: apply 85.75 160 10290
realization accept: cancel 68.26 140 8191
payment from customer: creation 59.88 108 7186
payment from customer: removal 5.49 82 659
payment to supplier: creation 50.68 122 6081
payment to supplier: removal 3.62 61 434
service: total inventory turnovers 3.98 3052 478
service: total monetary turnovers 6.03 583 724
service: aggregate perf. data 0.90 14094 108
service: refresh index statistics 0.03 361264 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-04-2021 04:27:43 to 04-04-2021 04:27:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2087
TEST_TIME 2 3644
TEST_TIME 3 4508
TEST_TIME 4 4894
TEST_TIME 5 5175
TEST_TIME 6 5188
TEST_TIME 7 5478
TEST_TIME 8 5381
TEST_TIME 9 5982
TEST_TIME 10 6333
TEST_TIME 11 6787
TEST_TIME 12 6914
TEST_TIME 13 7201
TEST_TIME 14 7461
TEST_TIME 15 7730
TEST_TIME 16 7794
TEST_TIME 17 8092
TEST_TIME 18 8184
TEST_TIME 19 8874
TEST_TIME 20 8859
TEST_TIME 21 9045
TEST_TIME 22 8791
TEST_TIME 23 8998
TEST_TIME 24 9363
TEST_TIME 25 9657
TEST_TIME 26 9799
TEST_TIME 27 10191
TEST_TIME 28 11133
TEST_TIME 29 11222
TEST_TIME 30 11021
TEST_TIME 31 10938
TEST_TIME 32 10757
TEST_TIME 33 10503
TEST_TIME 34 10246
TEST_TIME 35 10202
TEST_TIME 36 10208
TEST_TIME 37 10118
TEST_TIME 38 10203
TEST_TIME 39 10057
TEST_TIME 40 9908
TEST_TIME 41 9830
TEST_TIME 42 9778
TEST_TIME 43 9819
TEST_TIME 44 9935
TEST_TIME 45 10044
TEST_TIME 46 10035
TEST_TIME 47 9989
TEST_TIME 48 10009
TEST_TIME 49 9971
TEST_TIME 50 10024
TEST_TIME 51 10099
TEST_TIME 52 10086
TEST_TIME 53 9950
TEST_TIME 54 9796
TEST_TIME 55 9699
TEST_TIME 56 9591
TEST_TIME 57 9490
TEST_TIME 58 9470
TEST_TIME 59 9405
TEST_TIME 60 9487
TEST_TIME 61 9412
TEST_TIME 62 9411
TEST_TIME 63 9443
TEST_TIME 64 9494
TEST_TIME 65 9505
TEST_TIME 66 9565
TEST_TIME 67 9581
TEST_TIME 68 9639
TEST_TIME 69 9605
TEST_TIME 70 9698
TEST_TIME 71 9640
TEST_TIME 72 9521
TEST_TIME 73 9469
TEST_TIME 74 9390
TEST_TIME 75 9337
TEST_TIME 76 9288
TEST_TIME 77 9249
TEST_TIME 78 9225
TEST_TIME 79 9254
TEST_TIME 80 9215
TEST_TIME 81 9215
TEST_TIME 82 9257
TEST_TIME 83 9269
TEST_TIME 84 9265
TEST_TIME 85 9209
TEST_TIME 86 9266
TEST_TIME 87 9323
TEST_TIME 88 9319
TEST_TIME 89 9362
TEST_TIME 90 9299
TEST_TIME 91 9239
TEST_TIME 92 9173
TEST_TIME 93 9116
TEST_TIME 94 9128
TEST_TIME 95 9157
TEST_TIME 96 9127
TEST_TIME 97 9101
TEST_TIME 98 9098
TEST_TIME 99 9116
TEST_TIME 100 9137
TEST_TIME 101 9122
TEST_TIME 102 9155
TEST_TIME 103 9183
TEST_TIME 104 9183
TEST_TIME 105 9191
TEST_TIME 106 9169
TEST_TIME 107 9188
TEST_TIME 108 9196
TEST_TIME 109 9181
TEST_TIME 110 9110
TEST_TIME 111 9079
TEST_TIME 112 9026
TEST_TIME 113 9009
TEST_TIME 114 8967
TEST_TIME 115 8948
TEST_TIME 116 8916
TEST_TIME 117 8884
TEST_TIME 118 8942
TEST_TIME 119 8983
TEST_TIME 120 9028

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-04-2021 04:27:47 to 04-04-2021 04:27:50.

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 1512958 1512958 0 0.00 0 18563 7 0 0
doc_list_biud 1512956 1512956 0 0.00 0 25595 31 0 0
sp_add_invoice_to_stock 5182 5099 83 1.60 35 161744 17270 0 83
sp_cancel_adding_invoice 5359 5359 0 0.00 5 83907 929 0 0
sp_cancel_client_order 1894 1894 0 0.00 5 11893 316 0 0
sp_cancel_customer_reserve 530404 530404 0 0.00 1 25599 70 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 3271 83 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 2255 62 0 0
sp_cancel_supplier_invoice 6002 5998 4 0.06 2 23331 953 0 4
sp_cancel_supplier_order 403 403 0 0.00 9 952586 113862 0 0
sp_cancel_write_off 8191 8191 0 0.00 1 9465 140 0 0
sp_client_order 20808 20808 0 0.00 4 23344 382 0 0
sp_customer_reserve 467139 467139 0 0.00 0 71425 169 0 0
sp_fill_shopping_cart 32070 32070 0 0.00 1 26387 47 0 0
sp_fill_shopping_cart_clo_res 467133 467133 0 0.00 0 22842 17 0 0
sp_get_clo_for_invoice 5089 5089 0 0.00 0 63345 415 0 0
sp_kill_cost_storno 14643 14643 0 0.00 0 12454 60 0 0
sp_kill_qstorno_ret_qs2qd 1075400 1075400 0 0.00 0 25587 38 0 0
sp_kill_qty_storno 567628 567628 0 0.00 0 25593 81 0 0
sp_lock_selected_doc 21319 21319 0 0.00 0 8954 5 0 0
sp_make_cost_storno 28646 28646 0 0.00 0 18542 55 0 0
sp_multiply_rows_for_pdistr 28646 28646 0 0.00 0 13441 31 0 0
sp_multiply_rows_for_qdistr 20808 20808 0 0.00 0 23319 230 0 0
sp_pay_from_customer 7186 7186 0 0.00 4 18587 109 0 0
sp_pay_to_supplier 6081 6081 0 0.00 4 12786 123 0 0
sp_payment_common 13267 13267 0 0.00 1 12048 20 0 0
sp_qd_handle_on_cancel_clo 1894 1894 0 0.00 0 11882 279 0 0
sp_qd_handle_on_invoice_upd_sts 10448 10448 0 0.00 0 17396 390 0 0
sp_qd_handle_on_reserve_upd_sts 10290 10290 0 0.00 0 10707 41 0 0
sp_reserve_write_off 10290 10290 0 0.00 6 14601 161 0 0
sp_supplier_invoice 5638 5638 0 0.00 6 102148 3310 0 0
sp_supplier_order 5635 5635 0 0.00 5 25831 1376 0 0
srv_aggregate_perf_data 108 108 0 0.00 2467 333565 14094 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1235 173705 49261 0 0
srv_find_qd_qs_mism 1033117 1033117 0 0.00 0 11515 6 0 0
srv_make_invnt_saldo 478 478 0 0.00 42 98283 3052 0 0
srv_make_money_saldo 724 724 0 0.00 1 18912 584 0 0
srv_recalc_idx_stat 4 4 0 0.00 204588 528027 361264 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5100 5100 0 0.00 0 6588 160 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 2850 82 0 0
t$perf-norm:sp_cancel_client_order 1894 1894 0 0.00 0 7793 17 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 15371 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 5486 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 2408 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 3014 25 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 17962 1467 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 1118 4 0 0
t$perf-norm:sp_client_order 20808 20808 0 0.00 0 8752 17 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1517 5 0 0
t$perf-norm:sp_pay_from_customer 7186 7186 0 0.00 0 3935 8 0 0
t$perf-norm:sp_pay_to_supplier 6081 6081 0 0.00 0 4779 8 0 0
t$perf-norm:sp_reserve_write_off 10290 10290 0 0.00 0 7209 11 0 0
t$perf-norm:sp_supplier_invoice 5638 5638 0 0.00 0 7217 19 0 0
t$perf-norm:sp_supplier_order 5635 5635 0 0.00 0 6369 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 637 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1504 34 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 2170 10 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 2112 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10344 10178 166 1.60 0 6242 17 0 166
v_all_customers 38052 38052 0 0.00 0 62 1 0 0
v_all_suppliers 31358 31358 0 0.00 0 24 1 0 0
v_all_wares 41614 41614 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1667 8 0 0
v_cancel_client_order 3788 3788 0 0.00 0 2695 5 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 2902 8 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1799 6 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 727 4 0 0
v_cancel_supplier_invoice 862 854 8 0.92 0 3363 18 0 8
v_cancel_supplier_order 806 806 0 0.00 0 879 6 0 0
v_cancel_write_off 2042 2042 0 0.00 0 5659 17 0 0
v_max_id_clo_ord 5629 5629 0 0.00 0 313 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 1151 9 0 0
v_max_id_ord_sup 5633 5633 0 0.00 0 3241 4 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 4036 4 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 7074 7 0 0
v_min_id_clo_ord 5629 5629 0 0.00 0 4695 10 0 0
v_min_id_clo_res 985 985 0 0.00 0 7319 24 0 0
v_min_id_ord_sup 5633 5633 0 0.00 0 5604 7 0 0
v_min_non_paid_invoice 6081 6081 0 0.00 0 3748 10 0 0
v_min_non_paid_realizn 7186 7186 0 0.00 0 5958 15 0 0
v_our_firm 10454 10454 0 0.00 0 17 1 0 0
v_reserve_write_off 20580 20580 0 0.00 0 5132 8 0 0
x_lock_depdocs_on_canc_invoice 10930 10930 0 0.00 0 13713 114 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 13586 353 0 0
x_make_qstorno_1000_1200 5635 5635 0 0.00 2 25780 1294 0 0
x_make_qstorno_1200_2000 5638 5638 0 0.00 3 99827 3127 0 0
x_make_qstorno_2100_3300 467139 467139 0 0.00 0 71423 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-04-2021 04:27:51 to 04-04-2021 04:27:55.

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
01:57:02 16 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:03 45993 8192000000 9732177536 10030530560 797571904 8.872 100 91 181 2 828182928 8114272 22496752
01:59:52 15931 8192000000 9759415504 10069471232 840676672 9.307 100 84 169 0 874156736 14042144 19437920
02:01:11 19125 8192000000 9831100208 10154545152 886268960 9.762 100 79 159 1 929523872 25596176 17658736
02:02:35 12914 8192000000 9886120848 10219954176 923948624 10.135 100 78 157 0 992396608 51543600 16904384
02:03:53 7664 8192000000 9935978528 10284650496 973470368 10.621 100 73 146 0 1048083376 59589104 15023904
02:05:03 4622 8192000000 9978768928 10335277056 1008100304 10.957 100 79 157 1 1094734592 70338608 16295680
02:06:10 2058 8192000000 10024584880 10387881984 1031322592 11.181 100 89 179 0 1139135968 89618336 18195040
02:07:14 1188 8192000000 10039755376 10408026112 1056883904 11.427 100 88 176 1 1147074400 71810672 18379824
02:08:16 3693 8192000000 10004819104 10388008960 1064572992 11.500 100 43 86 0 1131985440 58475648 8936800
02:09:20 3504 8192000000 9990472128 10382069760 1075432448 11.604 100 55 109 1 1114073488 26899104 11741936
02:10:25 14421 8192000000 10009920768 10390802432 1076997152 11.619 100 73 148 0 1111793344 18681296 16114896
02:11:39 8926 8192000000 10007065344 10404159488 1088115216 11.725 100 30 61 2 1116672832 22023872 6533744
02:12:53 7762 8192000000 10018673952 10413658112 1089651088 11.739 100 42 85 1 1120310096 21231984 9427024
02:14:06 18567 8192000000 10020155456 10419494912 1094857648 11.789 100 25 48 2 1122721072 22654912 5208512
02:15:27 4509 8192000000 10030013296 10425028608 1099059424 11.829 100 60 119 1 1132321712 20280240 12982048
02:16:32 7388 8192000000 10015887616 10427420672 1105120928 11.886 100 50 101 1 1130070592 13825408 11124256
02:17:40 5100 8192000000 9987720864 10380726272 1065417056 11.508 99 42 83 0 1089302560 14340800 9544704
02:18:51 7752 8192000000 9953756624 10343669760 1005411200 10.931 96 33 66 1 1032536752 20045664 7079888
02:20:01 38203 8192000000 9919018768 10306707456 972727120 10.613 94 34 67 0 1000305200 19351440 8226640
02:21:44 6906 8192000000 9930284112 10316652544 1009722096 10.973 99 55 108 5 1038028624 17279344 11027184
02:22:56 6837 8192000000 9962183744 10355396608 1045043696 11.313 100 35 69 2 1066070736 12367136 8659904
02:24:06 32265 8192000000 9980647184 10391347200 1055620320 11.415 100 24 48 1 1079667120 18754336 5292464
02:26:12 10209 8192000000 9981416480 10390941696 1071379040 11.565 100 48 96 3 1105650000 23237104 11033856
02:27:29 26768 8192000000 10019918960 10409156608 1086638496 11.711 100 56 110 1 1123237760 24736832 11862432
02:28:58 40714 8192000000 10103259488 10508046336 1114084960 11.971 100 51 97 0 1190344976 66626464 9633552
02:30:42 64931 8192000000 10113921488 10530111488 1132600160 12.146 100 46 92 1 1232581728 87984928 11996640
02:32:55 8199 8192000000 10155687920 10555039744 1141430544 12.229 100 79 152 3 1257672320 100229760 16012016
02:34:05 6755 8192000000 10184102624 10595274752 1148190416 12.293 100 56 112 2 1291621264 131248560 12182288
02:35:22 2963 8192000000 10195229152 10602536960 1165230528 12.452 100 70 139 0 1302254128 121824336 15199264
02:36:34 4699 8192000000 10235345440 10644201472 1165590272 12.456 100 66 132 0 1338210064 157861072 14758720
02:37:45 2834 8192000000 10253721040 10676727808 1171251376 12.509 100 80 157 1 1355880736 166999536 17629824
02:38:51 3615 8192000000 10261498240 10670473216 1165751968 12.457 99 62 122 1 1358996048 179938192 13305888
02:40:00 1233 8192000000 10278496576 10675499008 1148635280 12.297 98 87 173 1 1362442256 194075792 19731184
02:41:04 3935 8192000000 10286980000 10702094336 1139590896 12.212 99 69 139 4 1382079200 226161952 16326352
02:42:12 5910 8192000000 10297973008 10695954432 1135251168 12.171 99 81 161 0 1382950896 229492496 18207232
02:43:21 2499 8192000000 10308872192 10704166912 1149203680 12.302 100 89 177 2 1395332848 225150688 20978480
02:44:26 2069 8192000000 10324293952 10721009664 1141274864 12.228 100 90 178 2 1419599968 258172160 20152944
02:45:32 11341 8192000000 10284780704 10720391168 1136198816 12.180 99 45 88 2 1379798816 232911808 10688192
02:46:49 13107 8192000000 10320854464 10750418944 1141281344 12.228 99 36 72 0 1405559904 255246432 9032128
02:48:09 16144 8192000000 10341556512 10757681152 1131406272 12.135 99 40 80 1 1431040832 288983168 10651392
02:49:30 13925 8192000000 10433768816 10854825984 1144699952 12.260 100 42 83 1 1524452544 368796576 10956016
02:50:52 3126 8192000000 10398375616 10833707008 1162741904 12.429 100 47 92 2 1501495744 327705056 11048784
02:52:15 13253 8192000000 10333693648 10806259712 1116971376 11.998 98 24 48 0 1429420832 305828368 6621088
02:54:08 57223 8192000000 10080512064 10519859200 1082384944 11.670 97 9 18 1 1265869632 180992976 2491712
02:56:07 42911 8192000000 10070704032 10487681024 1124362160 12.068 100 46 94 1 1171292992 36570032 10360800
02:57:57 167015 8192000000 10065019072 10506342400 1116521616 11.994 98 28 56 1 1141276976 19100784 5654576
03:01:56 51950 8192000000 10091800048 10583289856 1139435712 12.210 100 16 32 2 1208116960 64883296 3797952
03:03:53 44350 8192000000 10061138640 10515615744 1114367888 11.974 98 21 42 1 1199046800 79452736 5226176
03:05:44 60432 8192000000 10071241008 10515550208 1123579152 12.061 98 12 24 1 1162061584 35933888 2548544
03:07:45 69806 8192000000 10164760256 10639929344 1152639760 12.334 100 13 26 0 1248864304 93520448 2704096
03:10:07 64131 8192000000 10117746976 10577973248 1142927744 12.243 99 23 47 0 1227958480 79649184 5381552
03:12:15 34763 8192000000 10095115776 10551386112 1139046160 12.207 99 33 66 0 1172002464 26075456 6880848
03:13:50 65424 8192000000 10086246672 10526789632 1117595328 12.004 97 27 50 2 1178037280 54869280 5572672
03:16:00 84045 8192000000 10105094176 10563600384 1138151344 12.198 99 19 38 1 1208752656 66643344 3957968
03:18:24 29014 8192000000 10096499184 10556538880 1144878000 12.261 100 35 70 1 1199474736 47708336 6888400
03:19:59 85734 8192000000 10070136192 10526203904 1128066912 12.103 98 22 44 1 1177967648 45666480 4234256
03:22:31 78755 8192000000 10100134912 10550288384 1134933536 12.168 99 15 29 2 1182125920 43715744 3476640
03:24:50 43477 8192000000 10083733104 10531233792 1092746400 11.769 96 10 20 0 1157326944 62633728 1946816
03:26:38 71454 8192000000 10102191520 10543665152 1094766208 11.788 96 13 26 1 1150033984 52893008 2374768
03:28:52 56459 8192000000 10071167152 10505740288 1060606224 11.462 93 20 40 0 1096948640 32321776 4020640
03:30:50 74983 8192000000 10104224704 10558316544 1046338672 11.326 92 17 34 0 1121371344 71528992 3503680
03:33:05 51493 8192000000 10071627232 10527203328 1062457568 11.480 95 21 41 0 1119920064 53061344 4401152
03:34:58 36778 8192000000 10032321248 10456805376 1090559952 11.748 98 22 44 1 1135282224 40090464 4631808
03:36:39 85097 8192000000 10048078848 10560610304 1108048144 11.914 100 26 52 1 1213147136 99699424 5399568
03:39:37 32362 8192000000 10017927664 10467672064 1088412880 11.728 98 12 24 0 1115994448 25131968 2449600
03:41:18 42628 8192000000 10049396592 10510020608 1118296832 12.011 100 6 12 0 1150737168 31345520 1094816
03:43:02 63749 8192000000 10096371968 10538975232 1121684400 12.043 99 23 46 1 1170634112 43879904 5069808
03:45:07 87377 8192000000 10132421072 10593259520 1140945040 12.224 100 20 40 0 1253582880 108019424 4618416
03:47:34 173705 8192000000 10127147584 10583183360 1116885200 11.998 98 17 34 0 1203848944 83385248 3578496
03:51:36 98104 8192000000 10075255680 10598309888 1103373040 11.870 97 10 20 0 1204592144 98702544 2516560
03:54:19 45790 8192000000 10112876336 10576412672 1129950784 12.121 100 14 26 2 1187326272 54580896 2794592
03:56:11 78237 8192000000 10055094656 10500018176 1123729344 12.062 100 20 40 1 1175314048 47432992 4151712
03:58:35 37560 8192000000 10064304672 10514599936 1116587392 11.995 99 6 12 0 1146534432 28676192 1270848
04:00:31 65008 8192000000 10099552912 10633691136 1136251184 12.180 100 8 16 0 1213004288 75112736 1640368
04:02:37 47300 8192000000 10069658192 10516381696 1122501696 12.051 98 17 34 0 1164000640 38276320 3222624
04:04:28 33732 8192000000 10088674608 10541621248 1149055216 12.301 100 23 46 1 1192565296 38457792 5052288
04:06:05 94972 8192000000 10106183280 10557423616 1088048512 11.724 94 24 48 0 1156145024 63053168 5043344
04:08:46 55527 8192000000 10063809120 10529443840 1115023008 11.980 98 20 40 0 1168992304 49807616 4161680
04:11:05 56862 8192000000 10094320752 10575253504 1130021600 12.122 99 29 58 1 1187355568 51129840 6204128
04:13:02 83283 8192000000 10073106272 10556678144 1089927824 11.742 97 17 34 1 1139820128 46728656 3163648
04:15:25 119798 8192000000 10046853408 10499670016 1114995888 11.980 100 22 44 0 1152275200 32714576 4564736
04:18:34 54129 8192000000 10017249824 10480398336 1064057584 11.495 96 6 12 0 1101603376 36255856 1289936
04:20:35 61879 8192000000 10051787488 10504220672 1060768592 11.464 95 12 24 1 1118663264 54966688 2927984
04:22:38 31429 8192000000 10106237968 10543763456 1109536736 11.928 99 30 60 0 1189541216 72465120 7539360
04:24:27 87365 8192000000 10114035040 10592555008 1084176320 11.687 98 13 26 0 1221248848 133967040 3105488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-04-2021 04:27:57 to 04-04-2021 04:28:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-04-2021 04:28:13 to 04-04-2021 04:28:15.

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          240288
MON$OLDEST_ACTIVE               240837
MON$OLDEST_SNAPSHOT             240837
MON$NEXT_TRANSACTION            240837
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                       2098432
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             816
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2401 Firebird 4.0 Release Candidate 1/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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Apr  4 04:27:02 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Apr  4 04:27:02 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Apr  4 04:27:02 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Apr  4 04:27:02 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

04.04.21 04:28:16. 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-04-04 04:28:18 No severe PSQL-related problems occured
04.04.21 04:28:19. End of report.