Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.2020 10:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16775372 kB
MemAvailable 31530568 kB
Buffers 0 kB
Cached 14839944 kB
SwapCached 1968 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 2020-12-05 10:28:19
DTS_END                         2020-12-05 10:26:53.0310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46402
TRN_ID                          243260
ATT_ID                          259
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: 9278.30 21207 1113396
customer order: creation 178.71 398 21445
customer order: refuse 15.13 358 1816
order to supplier: creation 46.30 1279 5556
order to supplier: removal 3.29 110127 395
invoice (draft): creation 47.79 3388 5735
invoice (draft): removal 51.33 788 6159
invoice accept: apply 43.07 18015 5168
invoice accept: cancel 46.38 834 5565
customer reserve: creation 3951.90 176 474228
customer reserve: removal 4592.43 63 551092
realization accept: apply 86.89 173 10427
realization accept: cancel 81.49 138 9779
payment from customer: creation 61.70 113 7404
payment from customer: removal 6.32 68 758
payment to supplier: creation 50.76 125 6091
payment to supplier: removal 3.74 90 449
service: total inventory turnovers 4.21 2760 505
service: total monetary turnovers 5.98 553 718
service: aggregate perf. data 0.85 16395 102
service: refresh index statistics 0.03 268303 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2020 10:30:13 to 05-12-2020 10:30:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4655
TEST_TIME 2 4019
TEST_TIME 3 4608
TEST_TIME 4 4771
TEST_TIME 5 4612
TEST_TIME 6 5311
TEST_TIME 7 5285
TEST_TIME 8 5337
TEST_TIME 9 5551
TEST_TIME 10 5613
TEST_TIME 11 5815
TEST_TIME 12 5953
TEST_TIME 13 6086
TEST_TIME 14 6372
TEST_TIME 15 7349
TEST_TIME 16 7761
TEST_TIME 17 7698
TEST_TIME 18 7940
TEST_TIME 19 8901
TEST_TIME 20 8979
TEST_TIME 21 9112
TEST_TIME 22 9579
TEST_TIME 23 9886
TEST_TIME 24 9899
TEST_TIME 25 10878
TEST_TIME 26 11457
TEST_TIME 27 11312
TEST_TIME 28 11251
TEST_TIME 29 11081
TEST_TIME 30 10844
TEST_TIME 31 10517
TEST_TIME 32 10218
TEST_TIME 33 10219
TEST_TIME 34 10236
TEST_TIME 35 10412
TEST_TIME 36 10303
TEST_TIME 37 10153
TEST_TIME 38 10044
TEST_TIME 39 9982
TEST_TIME 40 9920
TEST_TIME 41 10061
TEST_TIME 42 10135
TEST_TIME 43 10083
TEST_TIME 44 10067
TEST_TIME 45 10080
TEST_TIME 46 10061
TEST_TIME 47 10096
TEST_TIME 48 10097
TEST_TIME 49 10012
TEST_TIME 50 9987
TEST_TIME 51 9871
TEST_TIME 52 9806
TEST_TIME 53 9726
TEST_TIME 54 9632
TEST_TIME 55 9539
TEST_TIME 56 9611
TEST_TIME 57 9589
TEST_TIME 58 9598
TEST_TIME 59 9656
TEST_TIME 60 9618
TEST_TIME 61 9573
TEST_TIME 62 9795
TEST_TIME 63 9756
TEST_TIME 64 9839
TEST_TIME 65 9789
TEST_TIME 66 9803
TEST_TIME 67 9840
TEST_TIME 68 9716
TEST_TIME 69 9619
TEST_TIME 70 9607
TEST_TIME 71 9521
TEST_TIME 72 9433
TEST_TIME 73 9429
TEST_TIME 74 9452
TEST_TIME 75 9447
TEST_TIME 76 9495
TEST_TIME 77 9439
TEST_TIME 78 9488
TEST_TIME 79 9576
TEST_TIME 80 9547
TEST_TIME 81 9570
TEST_TIME 82 9650
TEST_TIME 83 9638
TEST_TIME 84 9695
TEST_TIME 85 9595
TEST_TIME 86 9528
TEST_TIME 87 9469
TEST_TIME 88 9403
TEST_TIME 89 9356
TEST_TIME 90 9321
TEST_TIME 91 9297
TEST_TIME 92 9280
TEST_TIME 93 9282
TEST_TIME 94 9243
TEST_TIME 95 9216
TEST_TIME 96 9223
TEST_TIME 97 9330
TEST_TIME 98 9441
TEST_TIME 99 9392
TEST_TIME 100 9438
TEST_TIME 101 9445
TEST_TIME 102 9445
TEST_TIME 103 9390
TEST_TIME 104 9325
TEST_TIME 105 9317
TEST_TIME 106 9313
TEST_TIME 107 9285
TEST_TIME 108 9245
TEST_TIME 109 9231
TEST_TIME 110 9205
TEST_TIME 111 9196
TEST_TIME 112 9210
TEST_TIME 113 9206
TEST_TIME 114 9224
TEST_TIME 115 9225
TEST_TIME 116 9320
TEST_TIME 117 9310
TEST_TIME 118 9337
TEST_TIME 119 9308
TEST_TIME 120 9281

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-12-2020 10:30:16 to 05-12-2020 10:30:20.

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 1552428 1552428 0 0.00 0 22225 7 0 0
doc_list_biud 1552424 1552424 0 0.00 0 35628 28 0 0
sp_add_invoice_to_stock 5256 5168 88 1.67 28 197482 18016 0 88
sp_cancel_adding_invoice 5565 5565 0 0.00 5 92802 835 0 0
sp_cancel_client_order 1816 1816 0 0.00 4 12024 359 0 0
sp_cancel_customer_reserve 551092 551092 0 0.00 1 34959 64 0 0
sp_cancel_pay_from_customer 758 758 0 0.00 3 5311 69 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 2 11343 91 0 0
sp_cancel_supplier_invoice 6174 6159 15 0.24 2 29466 788 0 15
sp_cancel_supplier_order 395 395 0 0.00 9 1148840 110127 0 0
sp_cancel_write_off 9779 9779 0 0.00 1 9531 138 0 0
sp_client_order 21445 21445 0 0.00 4 37496 399 0 0
sp_customer_reserve 474228 474228 0 0.00 0 50075 177 0 0
sp_fill_shopping_cart 32726 32726 0 0.00 1 30144 43 0 0
sp_fill_shopping_cart_clo_res 474225 474225 0 0.00 0 32241 18 0 0
sp_get_clo_for_invoice 5164 5164 0 0.00 0 39304 437 0 0
sp_kill_cost_storno 16551 16551 0 0.00 0 11455 54 0 0
sp_kill_qstorno_ret_qs2qd 1118517 1118517 0 0.00 0 29459 34 0 0
sp_kill_qty_storno 590397 590397 0 0.00 0 35628 74 0 0
sp_lock_selected_doc 21583 21583 0 0.00 0 10411 8 0 0
sp_make_cost_storno 29086 29086 0 0.00 0 15335 53 0 0
sp_multiply_rows_for_pdistr 29086 29086 0 0.00 0 7671 32 0 0
sp_multiply_rows_for_qdistr 21445 21445 0 0.00 0 37482 228 0 0
sp_pay_from_customer 7404 7404 0 0.00 4 11681 114 0 0
sp_pay_to_supplier 6091 6091 0 0.00 5 14047 126 0 0
sp_payment_common 13494 13494 0 0.00 1 11672 22 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 10232 318 0 0
sp_qd_handle_on_invoice_upd_sts 10729 10729 0 0.00 0 35627 359 0 0
sp_qd_handle_on_reserve_upd_sts 10427 10427 0 0.00 0 11640 47 0 0
sp_reserve_write_off 10427 10427 0 0.00 7 16684 174 0 0
sp_supplier_invoice 5735 5735 0 0.00 9 149037 3389 0 0
sp_supplier_order 5556 5556 0 0.00 6 21626 1279 0 0
srv_aggregate_perf_data 102 102 0 0.00 2938 211243 16395 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1574 147836 49691 0 0
srv_find_qd_qs_mism 1064066 1064066 0 0.00 0 22224 6 0 0
srv_make_invnt_saldo 505 505 0 0.00 33 103243 2761 0 0
srv_make_money_saldo 718 718 0 0.00 1 12695 554 0 0
srv_recalc_idx_stat 4 4 0 0.00 186074 436321 268304 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5168 5168 0 0.00 0 13212 151 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 5892 101 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 4952 13 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 12428 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 758 758 0 0.00 0 1691 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 1172 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 7962 37 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 18033 1411 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 924 5 0 0
t$perf-norm:sp_client_order 21445 21445 0 0.00 0 9895 17 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 946 6 0 0
t$perf-norm:sp_pay_from_customer 7404 7404 0 0.00 0 10108 9 0 0
t$perf-norm:sp_pay_to_supplier 6091 6091 0 0.00 0 9093 12 0 0
t$perf-norm:sp_reserve_write_off 10427 10427 0 0.00 0 7169 10 0 0
t$perf-norm:sp_supplier_invoice 5735 5735 0 0.00 0 5157 18 0 0
t$perf-norm:sp_supplier_order 5556 5556 0 0.00 0 12867 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 2000 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 333 9 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 875 10 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 2174 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10502 10326 176 1.67 0 7413 15 0 176
v_all_customers 39190 39190 0 0.00 0 58 1 0 0
v_all_suppliers 31230 31230 0 0.00 0 23 1 0 0
v_all_wares 42890 42890 0 0.00 0 115 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 5871 16 0 0
v_cancel_client_order 3632 3632 0 0.00 0 3103 6 0 0
v_cancel_customer_prepayment 1516 1516 0 0.00 0 5300 9 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 1025 5 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 664 5 0 0
v_cancel_supplier_invoice 784 754 30 3.82 0 557 14 0 30
v_cancel_supplier_order 790 790 0 0.00 0 3216 8 0 0
v_cancel_write_off 2156 2156 0 0.00 0 1001 14 0 0
v_max_id_clo_ord 5554 5554 0 0.00 0 408 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 3252 20 0 0
v_max_id_ord_sup 5725 5725 0 0.00 0 4119 4 0 0
v_max_non_paid_invoice 1754 1754 0 0.00 0 527 2 0 0
v_max_non_paid_realizn 3922 3922 0 0.00 0 7676 6 0 0
v_min_id_clo_ord 5554 5554 0 0.00 0 4070 8 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 2292 5 0 0
v_min_id_ord_sup 5725 5725 0 0.00 0 7899 14 0 0
v_min_non_paid_invoice 6090 6090 0 0.00 0 4300 12 0 0
v_min_non_paid_realizn 7404 7404 0 0.00 0 5586 15 0 0
v_our_firm 10664 10664 0 0.00 0 17 1 0 0
v_reserve_write_off 20852 20852 0 0.00 0 8409 5 0 0
x_lock_depdocs_on_canc_invoice 11347 11347 0 0.00 0 30020 97 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 34280 390 0 0
x_make_qstorno_1000_1200 5556 5556 0 0.00 2 21619 1207 0 0
x_make_qstorno_1200_2000 5735 5735 0 0.00 3 136675 3220 0 0
x_make_qstorno_2100_3300 474228 474228 0 0.00 0 50042 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2020 10:30:21 to 05-12-2020 10:30:25.

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
07:56:56 7 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:57 27656 8192000000 9718794624 10014580736 796760304 8.863 100 88 176 0 826646368 7495584 22390480
07:59:32 26729 8192000000 9767901296 10074161152 838185088 9.282 100 88 176 0 872267136 13010784 21071264
08:01:05 21585 8192000000 9825889696 10147414016 886140880 9.761 100 85 170 3 933805712 28550304 19114528
08:02:32 11356 8192000000 9898714576 10234036224 936805136 10.262 100 89 178 3 1007121072 50873840 19442096
08:03:47 8592 8192000000 9923020688 10267811840 963806416 10.526 100 76 152 0 1036544880 56786976 15951488
08:04:58 6174 8192000000 9973220496 10329862144 1000933760 10.888 100 82 162 1 1084528000 66895696 16698544
08:06:07 3066 8192000000 10003185872 10362486784 1027692752 11.146 100 87 172 2 1112309904 67257024 17360128
08:07:13 2247 8192000000 10024278496 10391465984 1058152080 11.439 100 94 188 2 1140982224 63686320 19143824
08:08:17 5927 8192000000 10047587456 10420256768 1070054816 11.553 100 89 179 1 1162876288 74150128 18671344
08:09:25 1764 8192000000 10024877360 10404003840 1072039328 11.572 100 71 142 3 1123043440 35536208 15467904
08:10:28 4830 8192000000 10016029584 10401886208 1078959376 11.638 100 49 100 2 1126152448 36813440 10379632
08:11:36 9214 8192000000 9994547168 10388094976 1083965968 11.685 100 25 50 0 1118203312 29234176 5003168
08:12:48 70069 8192000000 10009845760 10403094528 1085315424 11.698 100 37 75 2 1113874496 20226432 8332640
08:14:59 7566 8192000000 10015677696 10419183616 1094540208 11.786 100 42 86 2 1123546224 19240560 9765456
08:16:07 6933 8192000000 10010893104 10405261312 1094041472 11.781 100 32 65 3 1120681520 19760624 6879424
08:17:15 20265 8192000000 9965310768 10353012736 1002436976 10.902 92 32 62 1 1024897456 15392240 7068240
08:18:36 22495 8192000000 9961498592 10362093568 1023639792 11.107 96 24 46 4 1051919056 23462176 4817088
08:20:04 7616 8192000000 9930081856 10316566528 996430752 10.844 96 29 57 2 1016368912 13390688 6547472
08:21:14 8786 8192000000 9959354960 10349281280 1029168384 11.160 100 39 77 1 1052044928 14352752 8523792
08:22:25 57697 8192000000 9959384128 10364276736 1020843056 11.080 98 21 42 0 1046444464 20870784 4730624
08:24:25 8650 8192000000 9966636432 10348990464 1034708992 11.214 99 28 56 7 1062025728 20408080 6908656
08:25:34 6674 8192000000 9983597136 10381488128 1058991744 11.447 100 32 64 2 1093891568 27762928 7136896
08:26:41 9315 8192000000 10002451904 10409324544 1084298400 11.688 100 25 50 0 1104616592 14815904 5502288
08:27:58 62825 8192000000 10014941632 10423095296 1098432416 11.823 100 35 70 0 1128553040 22215712 7904912
08:30:08 13036 8192000000 10068597920 10472296448 1121438496 12.041 100 43 86 0 1179890560 49096208 9355856
08:31:25 1859 8192000000 10125245184 10532462592 1135155920 12.170 100 57 112 3 1226550064 79776912 11617232
08:32:32 3671 8192000000 10159258816 10561863680 1156581760 12.371 100 66 130 1 1265093936 93987088 14525088
08:33:37 3337 8192000000 10212119264 10611519488 1160731776 12.410 100 73 145 1 1312926480 136597216 15597488
08:34:49 4476 8192000000 10240963936 10645999616 1169608336 12.493 100 68 136 1 1342435312 158050112 14776864
08:36:01 6546 8192000000 10294783264 10701283328 1177067712 12.563 100 67 134 0 1383566288 191970048 14528528
08:37:11 5636 8192000000 10342361936 10752315392 1186951808 12.655 100 67 133 0 1437966608 236943008 14071792
08:38:24 2506 8192000000 10375662704 10773155840 1170582464 12.502 100 87 172 2 1472130064 282441392 19106208
08:39:32 3349 8192000000 10373878768 10784571392 1152408528 12.332 100 67 133 0 1480188368 312029680 15750160
08:40:40 3981 8192000000 10378663072 10774597632 1126142112 12.085 99 81 160 0 1471962896 327696208 18124576
08:41:51 1571 8192000000 10344818944 10754236416 1138316352 12.200 100 96 193 1 1452794544 292869024 21609168
08:42:55 11426 8192000000 10375567248 10798735360 1145083360 12.263 100 54 107 3 1475373616 317911744 12378512
08:44:11 4137 8192000000 10437281344 10835165184 1137529328 12.192 100 87 171 1 1533049056 375661312 19858416
08:45:17 18971 8192000000 10329436560 10756952064 1091335984 11.755 96 37 74 2 1430376912 329180144 9860784
08:46:39 14407 8192000000 10350221648 10776473600 1117031008 11.999 99 37 74 0 1463649552 336488288 10130256
08:47:57 17649 8192000000 10358418352 10822017024 1125715696 12.081 100 34 68 1 1459478016 324276096 9486224
08:49:31 35146 8192000000 10318140192 10807431168 1122645936 12.052 99 25 50 2 1408497872 279093328 6758608
08:51:21 33061 8192000000 10222909152 10820497408 1093686304 11.778 100 14 28 0 1386561664 288537488 4337872
08:53:05 12761 8192000000 10041480960 10455789568 1104527040 11.881 100 45 90 1 1130856464 16933344 9396080
08:54:24 25812 8192000000 10044701856 10480926720 1108009600 11.914 99 45 87 1 1130280144 12742784 9527760
08:56:09 114303 8192000000 10112660368 10550169600 1129664192 12.118 100 34 66 3 1196688192 60222720 6801280
08:59:07 25257 8192000000 10139259232 10588729344 1142676016 12.241 100 55 111 1 1231898816 77871056 11351744
09:00:37 38552 8192000000 10146124656 10613141504 1142149200 12.236 100 16 33 1 1247635120 102173680 3312240
09:02:19 35732 8192000000 10059123616 10544254976 1128063536 12.103 100 8 16 0 1178506224 48853728 1588960
09:03:56 27586 8192000000 10065388416 10507538432 1105841888 11.893 98 13 26 1 1144593696 36172864 2578944
09:05:48 51441 8192000000 10098294416 10555461632 1131508752 12.136 100 15 30 0 1198929088 64094544 3325792
09:07:40 51640 8192000000 10065838176 10505138176 1123434896 12.059 99 35 69 2 1184772192 53651344 7685952
09:09:33 25094 8192000000 10107729616 10557956096 1124576448 12.070 99 49 98 1 1190807408 55977792 10253168
09:11:06 94723 8192000000 10100064736 10566295552 1147586464 12.287 100 19 38 0 1205425904 53245440 4594000
09:13:41 98414 8192000000 10082752656 10530836480 1082910672 11.675 94 17 34 0 1116303344 29862656 3530016
09:16:20 145161 8192000000 10059645072 10517454848 1112977952 11.961 98 18 36 1 1154030352 36974160 4078240
09:19:47 61402 8192000000 10099319120 10589810688 1116538384 11.994 98 13 26 2 1186306944 66681392 3087168
09:21:49 66373 8192000000 10075287584 10560708608 1078200624 11.630 95 25 50 0 1125900032 41990256 5709152
09:24:00 39168 8192000000 10133839760 10571632640 1096675280 11.806 96 37 74 1 1172877360 67939056 8263024
09:25:40 65326 8192000000 10117157632 10575331328 1090238592 11.745 97 14 28 0 1155052752 62136400 2677760
09:27:54 64754 8192000000 10073462768 10586271744 1075496544 11.605 96 18 36 2 1185317456 106269760 3551152
09:29:59 100258 8192000000 10058421280 10500194304 1099877232 11.836 98 14 28 1 1163849184 61132464 2839488
09:32:41 15925 8192000000 10099969600 10535464960 1124250512 12.067 100 42 84 1 1184960608 51999072 8711024
09:33:58 147836 8192000000 10039474736 10493849600 1093832720 11.779 97 28 55 3 1140063536 39853456 6377360
09:37:31 76736 8192000000 10086871296 10534944768 1099222896 11.830 98 11 22 0 1149899120 47940544 2735680
09:39:53 72315 8192000000 10062535776 10552635392 1078164320 11.630 97 19 38 1 1151552640 69024352 4363968
09:42:09 65456 8192000000 10092643696 10574737408 1103891104 11.875 99 19 38 0 1166530816 58573680 4066032
09:44:18 87706 8192000000 10067300368 10551947264 1113946352 11.970 99 21 42 0 1218110432 99107328 5056752
09:46:48 32564 8192000000 10096624768 10545266688 1110708256 11.939 98 38 75 1 1174233200 56163408 7361536
09:48:22 67211 8192000000 10097858560 10567266304 1112339696 11.955 98 27 53 1 1153157216 35134000 5683520
09:50:30 115832 8192000000 10076368464 10540331008 1138158928 12.198 100 15 30 1 1191961056 50544048 3258080
09:53:27 61187 8192000000 10050737840 10512416768 1138204320 12.199 100 4 8 0 1163487024 24657568 625136
09:55:38 65712 8192000000 10061168352 10523508736 1112752096 11.958 98 9 17 1 1134445584 19862320 1831168
09:57:44 61748 8192000000 10094278240 10554740736 1134873184 12.167 99 20 39 1 1179684448 40695488 4115776
09:59:46 33805 8192000000 10104881200 10547109888 1140175504 12.217 99 22 44 3 1186383808 41419152 4789152
10:01:24 56589 8192000000 10181899744 10628980736 1142190624 12.236 99 27 53 1 1250272208 102169056 5912528
10:03:21 113231 8192000000 10079604160 10582794240 1115301584 11.983 98 9 18 0 1209612608 92170896 2140128
10:06:17 36009 8192000000 10146618176 10618400768 1136699920 12.184 99 33 66 0 1220234928 77012640 6522368
10:07:54 47313 8192000000 10095273728 10549698560 1108212816 11.915 97 32 64 0 1134920304 19877472 6830016
10:09:41 133257 8192000000 10088632048 10574180352 1123891552 12.064 99 41 82 0 1232242896 99834288 8517056
10:12:56 50322 8192000000 10065600128 10560860160 1111617056 11.948 98 1 2 0 1144590480 32937936 35488
10:14:47 63486 8192000000 10091603856 10548498432 1095035584 11.791 96 22 43 0 1126587632 27265344 4286704
10:18:03 54347 8192000000 10101179968 10561187840 1066274656 11.516 93 20 40 1 1134818224 64272960 4270608
10:19:59 46636 8192000000 10146734640 10583007232 1127565808 12.098 99 25 50 0 1218167808 84980576 5621424
10:21:54 120137 8192000000 10100862352 10583859200 1101154144 11.849 98 13 26 1 1188308480 84362880 2791456
10:24:54 64051 8192000000 10058260176 10477240320 1060528864 11.462 96 34 65 1 1114809632 47461856 6818912

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-12-2020 10:30:26 to 05-12-2020 10:30:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-12-2020 10:30:42 to 05-12-2020 10:30:44.

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          245098
MON$OLDEST_ACTIVE               245099
MON$OLDEST_SNAPSHOT             245099
MON$NEXT_TRANSACTION            245099
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                       2111936
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             819
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
--- end of diff output ---

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

05.12.20 10:30:45. 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
2020-12-05 10:30:47 No severe PSQL-related problems occured
05.12.20 10:30:49. End of report.