Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.03.2021 13: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 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 16561860 kB
MemAvailable 31535848 kB
Buffers 0 kB
Cached 15120424 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-03-25 13:26:58
DTS_END                         2021-03-25 13:26:58.9030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43988
TRN_ID                          223320
ATT_ID                          594
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: 9272.91 23334 1112749
customer order: creation 161.53 438 19383
customer order: refuse 13.68 382 1642
order to supplier: creation 43.51 1371 5221
order to supplier: removal 3.48 128595 417
invoice (draft): creation 43.42 3926 5210
invoice (draft): removal 55.54 940 6665
invoice accept: apply 38.77 18999 4652
invoice accept: cancel 50.89 938 6107
customer reserve: creation 3589.03 185 430684
customer reserve: removal 4982.71 70 597925
realization accept: apply 79.01 192 9481
realization accept: cancel 88.86 127 10663
payment from customer: creation 56.11 113 6733
payment from customer: removal 6.03 91 724
payment to supplier: creation 46.96 122 5635
payment to supplier: removal 3.38 103 406
service: total inventory turnovers 3.63 4046 435
service: total monetary turnovers 5.60 713 672
service: aggregate perf. data 0.75 22188 90
service: refresh index statistics 0.03 283136 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-03-2021 13:30:00 to 25-03-2021 13:30:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3884
TEST_TIME 2 3783
TEST_TIME 3 3498
TEST_TIME 4 3847
TEST_TIME 5 4422
TEST_TIME 6 4222
TEST_TIME 7 4528
TEST_TIME 8 4376
TEST_TIME 9 4557
TEST_TIME 10 4900
TEST_TIME 11 4982
TEST_TIME 12 5352
TEST_TIME 13 5694
TEST_TIME 14 5752
TEST_TIME 15 6074
TEST_TIME 16 6540
TEST_TIME 17 6789
TEST_TIME 18 6894
TEST_TIME 19 7186
TEST_TIME 20 7579
TEST_TIME 21 7861
TEST_TIME 22 7780
TEST_TIME 23 7800
TEST_TIME 24 7807
TEST_TIME 25 8096
TEST_TIME 26 8137
TEST_TIME 27 8170
TEST_TIME 28 8457
TEST_TIME 29 8535
TEST_TIME 30 8485
TEST_TIME 31 9583
TEST_TIME 32 9793
TEST_TIME 33 10593
TEST_TIME 34 10525
TEST_TIME 35 10667
TEST_TIME 36 10607
TEST_TIME 37 10437
TEST_TIME 38 10280
TEST_TIME 39 10343
TEST_TIME 40 10244
TEST_TIME 41 10015
TEST_TIME 42 9991
TEST_TIME 43 9831
TEST_TIME 44 9670
TEST_TIME 45 9712
TEST_TIME 46 9873
TEST_TIME 47 9855
TEST_TIME 48 9761
TEST_TIME 49 9692
TEST_TIME 50 9713
TEST_TIME 51 9631
TEST_TIME 52 9666
TEST_TIME 53 9664
TEST_TIME 54 9615
TEST_TIME 55 9671
TEST_TIME 56 9738
TEST_TIME 57 9708
TEST_TIME 58 9712
TEST_TIME 59 9616
TEST_TIME 60 9688
TEST_TIME 61 9751
TEST_TIME 62 9777
TEST_TIME 63 9801
TEST_TIME 64 9671
TEST_TIME 65 9524
TEST_TIME 66 9550
TEST_TIME 67 9552
TEST_TIME 68 9501
TEST_TIME 69 9459
TEST_TIME 70 9420
TEST_TIME 71 9367
TEST_TIME 72 9364
TEST_TIME 73 9361
TEST_TIME 74 9354
TEST_TIME 75 9332
TEST_TIME 76 9434
TEST_TIME 77 9411
TEST_TIME 78 9387
TEST_TIME 79 9456
TEST_TIME 80 9447
TEST_TIME 81 9449
TEST_TIME 82 9375
TEST_TIME 83 9268
TEST_TIME 84 9292
TEST_TIME 85 9241
TEST_TIME 86 9166
TEST_TIME 87 9116
TEST_TIME 88 9094
TEST_TIME 89 9099
TEST_TIME 90 9069
TEST_TIME 91 9034
TEST_TIME 92 9043
TEST_TIME 93 9016
TEST_TIME 94 9026
TEST_TIME 95 9165
TEST_TIME 96 9195
TEST_TIME 97 9169
TEST_TIME 98 9196
TEST_TIME 99 9157
TEST_TIME 100 9216
TEST_TIME 101 9253
TEST_TIME 102 9279
TEST_TIME 103 9248
TEST_TIME 104 9169
TEST_TIME 105 9155
TEST_TIME 106 9135
TEST_TIME 107 9109
TEST_TIME 108 9098
TEST_TIME 109 9061
TEST_TIME 110 9058
TEST_TIME 111 9106
TEST_TIME 112 9071
TEST_TIME 113 9066
TEST_TIME 114 9053
TEST_TIME 115 9060
TEST_TIME 116 9067
TEST_TIME 117 9136
TEST_TIME 118 9211
TEST_TIME 119 9261
TEST_TIME 120 9279
TEST_TIME 121 9203

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-03-2021 13:30:04 to 25-03-2021 13:30:07.

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 1512289 1512289 0 0.00 0 14780 7 0 0
doc_list_biud 1512286 1512286 0 0.00 0 69012 35 0 0
sp_add_invoice_to_stock 4811 4652 159 3.30 34 215758 19000 0 159
sp_cancel_adding_invoice 6107 6107 0 0.00 4 96293 939 0 0
sp_cancel_client_order 1642 1642 0 0.00 4 15468 382 0 0
sp_cancel_customer_reserve 597925 597925 0 0.00 1 69013 71 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 3340 91 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 6509 103 0 0
sp_cancel_supplier_invoice 6681 6665 16 0.23 2 26230 941 0 16
sp_cancel_supplier_order 417 417 0 0.00 6 1186979 128595 0 0
sp_cancel_write_off 10663 10663 0 0.00 1 10422 127 0 0
sp_client_order 19383 19383 0 0.00 4 84642 438 0 0
sp_customer_reserve 430684 430684 0 0.00 0 112152 185 0 0
sp_fill_shopping_cart 29802 29802 0 0.00 1 20780 48 0 0
sp_fill_shopping_cart_clo_res 430671 430671 0 0.00 0 22086 18 0 0
sp_get_clo_for_invoice 4636 4636 0 0.00 0 50281 416 0 0
sp_kill_cost_storno 17900 17900 0 0.00 0 8701 66 0 0
sp_kill_qstorno_ret_qs2qd 1213595 1213595 0 0.00 0 61383 38 0 0
sp_kill_qty_storno 637536 637536 0 0.00 0 69012 82 0 0
sp_lock_selected_doc 19606 19606 0 0.00 0 7556 5 0 0
sp_make_cost_storno 26485 26485 0 0.00 0 14767 54 0 0
sp_multiply_rows_for_pdistr 26485 26485 0 0.00 0 12837 36 0 0
sp_multiply_rows_for_qdistr 19383 19383 0 0.00 0 84633 251 0 0
sp_pay_from_customer 6733 6733 0 0.00 4 12845 114 0 0
sp_pay_to_supplier 5635 5635 0 0.00 5 12908 122 0 0
sp_payment_common 12368 12368 0 0.00 1 8548 22 0 0
sp_qd_handle_on_cancel_clo 1642 1642 0 0.00 0 15452 358 0 0
sp_qd_handle_on_invoice_upd_sts 10743 10743 0 0.00 0 40449 435 0 0
sp_qd_handle_on_reserve_upd_sts 9481 9481 0 0.00 0 33382 61 0 0
sp_reserve_write_off 9481 9481 0 0.00 6 33623 192 0 0
sp_supplier_invoice 5210 5210 0 0.00 6 147986 3927 0 0
sp_supplier_order 5221 5221 0 0.00 6 93193 1372 0 0
srv_aggregate_perf_data 90 90 0 0.00 2611 372940 22189 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1031 181485 45083 0 0
srv_find_qd_qs_mism 1068170 1068170 0 0.00 0 11186 5 0 0
srv_make_invnt_saldo 435 435 0 0.00 66 111624 4047 0 0
srv_make_money_saldo 672 672 0 0.00 1 24777 714 0 0
srv_recalc_idx_stat 4 4 0 0.00 173141 389837 283136 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4652 4652 0 0.00 0 10178 169 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 1742 95 0 0
t$perf-norm:sp_cancel_client_order 1642 1642 0 0.00 0 2579 14 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 2128 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 2287 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 335 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 351 351 0 0.00 0 1355 15 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 23822 1634 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 4943 20 0 0
t$perf-norm:sp_client_order 19383 19383 0 0.00 0 6783 18 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 3754 11 0 0
t$perf-norm:sp_pay_from_customer 6733 6733 0 0.00 0 12785 9 0 0
t$perf-norm:sp_pay_to_supplier 5635 5635 0 0.00 0 7058 11 0 0
t$perf-norm:sp_reserve_write_off 9481 9481 0 0.00 0 7565 12 0 0
t$perf-norm:sp_supplier_invoice 5210 5210 0 0.00 0 6849 18 0 0
t$perf-norm:sp_supplier_order 5222 5222 0 0.00 0 2946 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 240 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 609 9 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 1601 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 9588 9270 318 3.31 0 4679 17 0 318
v_all_customers 35378 35378 0 0.00 0 35 1 0 0
v_all_suppliers 29054 29054 0 0.00 0 129 1 0 0
v_all_wares 38764 38764 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 3365 10 0 0
v_cancel_client_order 3284 3284 0 0.00 0 542 3 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 3319 6 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 0 382 4 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 813 5 0 0
v_cancel_supplier_invoice 734 702 32 4.35 0 1437 16 0 32
v_cancel_supplier_order 834 834 0 0.00 0 3316 9 0 0
v_cancel_write_off 1900 1900 0 0.00 0 1556 15 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 196 1 0 0
v_max_id_clo_res 978 978 0 0.00 0 3560 15 0 0
v_max_id_ord_sup 5204 5204 0 0.00 0 8416 7 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 2136 3 0 0
v_max_non_paid_realizn 3523 3523 0 0.00 0 532 2 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 10802 28 0 0
v_min_id_clo_res 978 978 0 0.00 0 6204 30 0 0
v_min_id_ord_sup 5204 5204 0 0.00 0 7542 13 0 0
v_min_non_paid_invoice 5635 5635 0 0.00 0 7690 14 0 0
v_min_non_paid_realizn 6733 6733 0 0.00 0 8544 15 0 0
v_our_firm 9806 9806 0 0.00 0 15 1 0 0
v_reserve_write_off 18962 18962 0 0.00 0 3321 5 0 0
x_lock_depdocs_on_canc_invoice 12421 12421 0 0.00 0 17709 111 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 12416 393 0 0
x_make_qstorno_1000_1200 5221 5221 0 0.00 1 92820 1260 0 0
x_make_qstorno_1200_2000 5210 5210 0 0.00 2 146954 3761 0 0
x_make_qstorno_2100_3300 430684 430684 0 0.00 0 112151 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-03-2021 13:30:08 to 25-03-2021 13:30:12.

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
10:57:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:04 33460 8192000000 9733937584 10030481408 805007744 8.947 100 88 176 2 834086496 7719984 21358768
10:59:51 15737 8192000000 9771505072 10076299264 843528752 9.335 100 87 174 1 876346704 12251472 20566480
11:01:21 17497 8192000000 9818330528 10137784320 878965536 9.689 100 85 170 1 923956704 25418224 19572944
11:02:44 13048 8192000000 9875340896 10205163520 921507040 10.111 100 80 160 2 972723360 33888304 17328016
11:04:11 6918 8192000000 9931384688 10274922496 963260704 10.521 100 83 166 0 1042132464 61680720 17191040
11:05:22 5611 8192000000 9951004848 10303516672 993812112 10.818 100 81 162 1 1066281728 55733168 16736448
11:06:31 3211 8192000000 9988234640 10343555072 1020627024 11.078 100 86 172 2 1099232368 61373168 17232176
11:07:38 2943 8192000000 10012333120 10374799360 1039624640 11.261 100 83 164 2 1126309792 70503584 16181568
11:08:43 2265 8192000000 10046394192 10411974656 1061407008 11.470 100 93 187 0 1155942640 75913760 18621872
11:09:49 2058 8192000000 10028864784 10401497088 1073972928 11.590 100 86 173 1 1148992224 57458400 17560896
11:10:55 1337 8192000000 10062174192 10436710400 1078261792 11.631 100 92 184 0 1171172816 73529632 19381392
11:11:58 7984 8192000000 9998574896 10389667840 1080614928 11.653 100 34 67 1 1123679392 36250064 6814400
11:13:07 4226 8192000000 10034030704 10419810304 1084158880 11.687 100 48 96 1 1142680096 48539904 9981312
11:14:14 2757 8192000000 9990597664 10387193856 1091198720 11.754 100 34 69 0 1111142512 12809936 7133856
11:15:20 36409 8192000000 10015802368 10405609472 1089446128 11.737 100 58 117 0 1114809296 13000432 12362736
11:16:57 6031 8192000000 10007406896 10409349120 1090016368 11.743 100 38 74 1 1111668704 13904912 7747424
11:18:05 6735 8192000000 10000417008 10391121920 1069966912 11.552 99 37 74 2 1104609328 26544768 8097648
11:19:13 39199 8192000000 10000757600 10409029632 1057135024 11.429 98 30 60 0 1082862128 19427152 6299952
11:20:53 9719 8192000000 9956403296 10356215808 1030982592 11.178 97 38 76 1 1058085184 18560720 8541872
11:22:03 6557 8192000000 9928855136 10316853248 979121008 10.676 95 28 53 2 1011896464 26842080 5933376
11:23:13 82170 8192000000 9949831088 10347479040 1022393104 11.095 98 29 55 0 1070047792 41827888 5826800
11:25:36 5716 8192000000 9953118704 10337832960 1037058032 11.236 99 32 65 0 1056587952 11874240 7655680
11:26:45 2517 8192000000 9976015824 10365874176 1062967744 11.485 100 52 104 1 1085362272 10941408 11453120
11:27:58 13234 8192000000 10018439008 10411360256 1084934944 11.694 100 44 88 0 1120972880 26640048 9397888
11:29:16 88785 8192000000 10064035888 10468757504 1102533760 11.862 100 37 74 1 1173623520 64211536 6878224
11:31:56 38410 8192000000 10098386784 10509209600 1131272304 12.133 100 37 74 1 1200802896 61435856 8094736
11:33:40 13439 8192000000 10144207344 10563235840 1141900528 12.233 100 54 105 0 1247785920 94321936 11563456
11:34:56 7540 8192000000 10184371632 10587537408 1157471632 12.380 100 64 126 3 1288449120 117164608 13812880
11:36:21 8761 8192000000 10234768944 10654957568 1161050928 12.413 100 55 110 1 1347869024 174726336 12091760
11:37:34 6867 8192000000 10262519456 10675691520 1172159104 12.517 100 59 116 2 1366864352 181552336 13152912
11:38:50 4914 8192000000 10292403184 10705104896 1179029120 12.581 100 70 140 0 1405516688 211431728 15055840
11:40:02 8354 8192000000 10300197248 10714935296 1177380144 12.566 100 66 131 1 1404578176 212353040 14844992
11:41:17 3571 8192000000 10337555872 10749616128 1173942656 12.534 100 81 162 0 1440983472 248568640 18472176
11:42:25 3735 8192000000 10312320576 10727550976 1177940608 12.571 100 67 131 3 1416762688 222716528 16105552
11:43:35 2412 8192000000 10325627120 10749747200 1186457008 12.650 100 73 146 2 1426237008 221331520 18448480
11:44:41 5929 8192000000 10318060688 10732072960 1175942672 12.552 100 79 157 0 1411268368 218221232 17104464
11:45:51 4557 8192000000 10338606656 10753044480 1174779936 12.541 100 72 142 3 1436368736 244936528 16652272
11:46:59 1029 8192000000 10289815680 10709008384 1173390032 12.529 100 86 167 4 1386782112 193068304 20323776
11:48:04 9945 8192000000 10295498000 10716925952 1165032576 12.450 99 49 98 0 1396598880 219673680 11892624
11:49:21 5602 8192000000 10368339568 10785038336 1177403968 12.566 100 62 121 0 1470572128 278899680 14268480
11:50:29 6278 8192000000 10379073488 10809536512 1155635568 12.362 100 44 88 0 1476101072 309310400 11155104
11:51:39 2587 8192000000 10412234480 10828414976 1168506832 12.483 100 61 122 1 1507812688 324684064 14621792
11:52:45 9303 8192000000 10424082944 10849968128 1165779664 12.457 100 42 81 1 1526253280 350248896 10224720
11:54:07 18774 8192000000 10477599104 10937716736 1169635152 12.493 100 31 62 1 1579690080 401896480 8158448
11:55:27 10609 8192000000 10436990864 10921127936 1141830272 12.233 100 27 54 1 1568474192 418543392 8100528
11:56:56 3980 8192000000 10394837312 10906202112 1124638256 12.071 100 36 72 0 1491895408 357577344 9679808
11:58:11 37266 8192000000 10264389488 10745720832 1136818016 12.186 100 16 32 0 1378976576 237880656 4277904
11:59:49 70103 8192000000 10086648720 10552393728 1121428752 12.040 97 17 31 5 1145332544 20354544 3549248
12:02:00 50535 8192000000 10048000512 10488045568 1092620240 11.768 97 13 26 1 1137038352 41947728 2470384
12:03:52 49258 8192000000 10138600464 10564386816 1122454352 12.050 99 41 81 1 1212117152 80796256 8866544
12:05:42 181484 8192000000 10083209440 10517266432 1088809472 11.731 97 23 44 1 1138082768 44354016 4919280
12:09:56 46236 8192000000 10162270080 10587508736 1133203136 12.152 100 51 102 2 1246328944 101818352 11307456
12:11:49 47130 8192000000 10160693200 10689236992 1131066944 12.131 100 13 25 1 1277826832 143864464 2895424
12:13:40 51629 8192000000 10065324448 10512793600 1101866512 11.855 98 14 28 0 1142924928 38053312 3005104
12:16:07 40579 8192000000 10108735776 10586107904 1117494608 12.003 99 15 30 0 1222411744 100743472 4173664
12:18:01 59172 8192000000 10069353136 10547060736 1128878832 12.111 100 22 43 2 1189150352 55852032 4419488
12:20:01 58032 8192000000 10092909632 10533093376 1115846160 11.988 98 23 46 0 1156335536 35946096 4543280
12:22:04 71167 8192000000 10080086784 10568298496 1132862208 12.148 99 22 45 1 1200870880 63028112 4980560
12:24:16 62960 8192000000 10093963856 10530480128 1124322656 12.068 98 18 36 0 1165220320 37229376 3668288
12:26:22 74981 8192000000 10084740752 10577465344 1123505472 12.060 98 23 46 0 1181302592 53104560 4692560
12:28:54 120938 8192000000 10159133312 10631680000 1144230912 12.255 99 16 33 1 1210088272 62462048 3395312
12:32:55 76880 8192000000 10101353312 10597306368 1142069408 12.235 99 12 23 0 1218112944 73999808 2043728
12:35:12 69702 8192000000 10108681744 10602954752 1144084736 12.254 99 9 18 0 1197080512 51254592 1741184
12:38:39 18245 8192000000 10111888624 10555256832 1148382256 12.294 99 33 66 2 1201176512 45827568 6966688
12:39:58 55223 8192000000 10110081696 10539941888 1132103664 12.141 99 21 42 1 1188607536 51579632 4924240
12:41:58 89442 8192000000 10062732512 10546712576 1116415408 11.993 98 18 34 1 1198082736 77958352 3708976
12:44:28 34159 8192000000 10120009232 10557034496 1126966224 12.093 99 28 56 0 1183572256 50976016 5630016
12:46:12 63774 8192000000 10112592080 10534338560 1110374544 11.936 98 38 76 1 1159924144 41717408 7832192
12:48:19 78170 8192000000 10088450576 10555400192 1118575792 12.014 100 10 20 1 1192209232 71308496 2324944
12:50:58 46192 8192000000 10037029024 10496913408 1100514464 11.843 98 9 19 0 1129812848 27808848 1489536
12:53:00 65463 8192000000 10045795632 10508238848 1091614768 11.758 98 11 22 0 1135710800 41363872 2732160
12:55:07 33393 8192000000 10061566624 10506977280 1105659616 11.891 99 21 41 2 1149910352 40060192 4190544
12:56:47 57663 8192000000 10081119776 10529828864 1117057664 11.999 99 21 42 1 1165998448 44227680 4713104
12:58:53 37322 8192000000 10096199904 10531885056 1076048640 11.610 95 21 42 0 1142677264 62615088 4013536
13:00:40 61567 8192000000 10105373072 10655330304 1113317744 11.964 99 17 34 2 1242514560 125104336 4092480
13:02:56 29219 8192000000 10070363920 10503815168 1114437952 11.974 99 25 50 0 1150110336 30438272 5234112
13:04:26 54256 8192000000 10097502720 10536755200 1105945120 11.894 98 41 82 2 1158452288 44346256 8160912
13:06:20 39561 8192000000 10080351808 10560774144 1096212896 11.802 97 37 74 0 1187900704 84505056 7182752
13:08:08 25769 8192000000 10089123760 10523635712 1085776192 11.702 97 20 40 0 1136735392 46580752 4378448
13:09:34 161006 8192000000 10069368704 10563387392 1114907584 11.979 100 11 22 0 1181691184 64718864 2064736
13:14:34 101835 8192000000 10096052576 10536468480 1096263392 11.802 97 18 34 1 1152857024 53153072 3440560
13:17:21 83079 8192000000 10083011264 10525806592 1132698432 12.147 100 22 44 0 1184692544 47559520 4434592
13:19:46 27479 8192000000 10098631664 10521755648 1124766784 12.072 100 41 82 1 1168035856 34142080 9126992
13:21:15 69356 8192000000 10152615808 10609094656 1138070432 12.197 100 29 58 1 1229712512 85818480 5823600
13:23:27 47076 8192000000 10125281584 10673430528 1146249728 12.274 100 11 22 0 1259632720 111084432 2298560
13:25:17 114216 8192000000 9061768880 9315737600 198089424 2.360 18 2 2 1 205856160 7381808 384928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-03-2021 13:30:14 to 25-03-2021 13:30:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-03-2021 13:30:30 to 25-03-2021 13:30:32.

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          223593
MON$OLDEST_ACTIVE               223594
MON$OLDEST_SNAPSHOT             223594
MON$NEXT_TRANSACTION            223594
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                       2131712
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:336: 
:337: mscs-test-161.budzdorov.ru    Thu Mar 25 13:27:57 2021
:338:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:339: 
--- end of diff output ---

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

25.03.21 13:30:33. 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-03-25 13:30:35 No severe PSQL-related problems occured
25.03.21 13:30:37. End of report.