Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2020 18:57
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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'
./oltp_isql_run_worker.sh line 45: dmidecode: command not found
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 17323640 kB
MemAvailable 31609752 kB
Buffers 12 kB
Cached 14327948 kB
SwapCached 6232 kB
Command: fdisk -l
./oltp_isql_run_worker.sh: line 45: fdisk: command not found

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.7
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-09 18:56:57
DTS_END                         2020-10-09 18:56:57.2870
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(56:17)
IP                              ::1/50928
TRN_ID                          255474
ATT_ID                          657
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: 9828.17 23567 1179380
customer order: creation 181.89 359 21827
customer order: refuse 15.29 300 1835
order to supplier: creation 48.02 1153 5762
order to supplier: removal 3.61 81497 433
invoice (draft): creation 48.03 3251 5763
invoice (draft): removal 56.79 575 6815
invoice accept: apply 44.11 15584 5293
invoice accept: cancel 51.83 621 6220
customer reserve: creation 4007.90 157 480948
customer reserve: removal 5057.47 46 606896
realization accept: apply 86.81 164 10417
realization accept: cancel 91.57 99 10988
payment from customer: creation 61.81 105 7417
payment from customer: removal 5.94 57 713
payment to supplier: creation 50.98 110 6117
payment to supplier: removal 3.59 57 431
service: total inventory turnovers 4.89 1056 587
service: total monetary turnovers 6.79 27 815
service: aggregate perf. data 0.83 20131 99
service: refresh index statistics 0.03 345991 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-10-2020 18:57:39 to 09-10-2020 18:57:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2210
TEST_TIME 2 3536
TEST_TIME 3 4120
TEST_TIME 4 4303
TEST_TIME 5 4231
TEST_TIME 6 4611
TEST_TIME 7 5075
TEST_TIME 8 6183
TEST_TIME 9 6673
TEST_TIME 10 6964
TEST_TIME 11 7162
TEST_TIME 12 7719
TEST_TIME 13 7855
TEST_TIME 14 8103
TEST_TIME 15 8803
TEST_TIME 16 9075
TEST_TIME 17 9082
TEST_TIME 18 8868
TEST_TIME 19 9136
TEST_TIME 20 9566
TEST_TIME 21 10312
TEST_TIME 22 10565
TEST_TIME 23 11741
TEST_TIME 24 11818
TEST_TIME 25 11596
TEST_TIME 26 11483
TEST_TIME 27 11493
TEST_TIME 28 11237
TEST_TIME 29 10980
TEST_TIME 30 11324
TEST_TIME 31 11305
TEST_TIME 32 11139
TEST_TIME 33 11015
TEST_TIME 34 10883
TEST_TIME 35 10755
TEST_TIME 36 10795
TEST_TIME 37 10783
TEST_TIME 38 10831
TEST_TIME 39 10713
TEST_TIME 40 10525
TEST_TIME 41 10439
TEST_TIME 42 10485
TEST_TIME 43 10450
TEST_TIME 44 10339
TEST_TIME 45 10365
TEST_TIME 46 10287
TEST_TIME 47 10231
TEST_TIME 48 10266
TEST_TIME 49 10269
TEST_TIME 50 10187
TEST_TIME 51 10198
TEST_TIME 52 10492
TEST_TIME 53 10419
TEST_TIME 54 10405
TEST_TIME 55 10482
TEST_TIME 56 10441
TEST_TIME 57 10678
TEST_TIME 58 10532
TEST_TIME 59 10460
TEST_TIME 60 10381
TEST_TIME 61 10320
TEST_TIME 62 10298
TEST_TIME 63 10181
TEST_TIME 64 10178
TEST_TIME 65 10204
TEST_TIME 66 10172
TEST_TIME 67 10187
TEST_TIME 68 10251
TEST_TIME 69 10224
TEST_TIME 70 10249
TEST_TIME 71 10251
TEST_TIME 72 10291
TEST_TIME 73 10274
TEST_TIME 74 10270
TEST_TIME 75 10383
TEST_TIME 76 10339
TEST_TIME 77 10282
TEST_TIME 78 10379
TEST_TIME 79 10278
TEST_TIME 80 10189
TEST_TIME 81 10216
TEST_TIME 82 10160
TEST_TIME 83 10138
TEST_TIME 84 10130
TEST_TIME 85 10054
TEST_TIME 86 10032
TEST_TIME 87 9948
TEST_TIME 88 9987
TEST_TIME 89 9977
TEST_TIME 90 9946
TEST_TIME 91 9954
TEST_TIME 92 9978
TEST_TIME 93 9959
TEST_TIME 94 10038
TEST_TIME 95 10045
TEST_TIME 96 10065
TEST_TIME 97 10079
TEST_TIME 98 10102
TEST_TIME 99 10111
TEST_TIME 100 10170
TEST_TIME 101 10146
TEST_TIME 102 10074
TEST_TIME 103 10048
TEST_TIME 104 10053
TEST_TIME 105 10007
TEST_TIME 106 9986
TEST_TIME 107 9928
TEST_TIME 108 9935
TEST_TIME 109 9893
TEST_TIME 110 9845
TEST_TIME 111 9808
TEST_TIME 112 9778
TEST_TIME 113 9816
TEST_TIME 114 9802
TEST_TIME 115 9772
TEST_TIME 116 9761
TEST_TIME 117 9826
TEST_TIME 118 9873
TEST_TIME 119 9840
TEST_TIME 120 9834

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-10-2020 18:57:43 to 09-10-2020 18:57:46.

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 1625299 1625299 0 0.00 0 24945 5 0 0
doc_list_biud 1625299 1625299 0 0.00 0 27676 22 0 0
sp_add_invoice_to_stock 5449 5293 156 2.86 25 167895 15585 0 156
sp_cancel_adding_invoice 6220 6220 0 0.00 5 60133 621 0 0
sp_cancel_client_order 1835 1835 0 0.00 4 9418 301 0 0
sp_cancel_customer_reserve 606896 606896 0 0.00 1 26364 47 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 3404 57 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 3994 57 0 0
sp_cancel_supplier_invoice 6831 6815 16 0.23 2 15282 576 0 16
sp_cancel_supplier_order 433 433 0 0.00 9 846372 81498 0 0
sp_cancel_write_off 10988 10988 0 0.00 1 13510 100 0 0
sp_client_order 21827 21827 0 0.00 3 16750 360 0 0
sp_customer_reserve 480948 480948 0 0.00 0 36969 157 0 0
sp_fill_shopping_cart 33344 33344 0 0.00 1 10876 35 0 0
sp_fill_shopping_cart_clo_res 480939 480939 0 0.00 0 16528 14 0 0
sp_get_clo_for_invoice 5284 5284 0 0.00 0 28459 293 0 0
sp_kill_cost_storno 18352 18352 0 0.00 0 12623 45 0 0
sp_kill_qstorno_ret_qs2qd 1232028 1232028 0 0.00 0 26358 25 0 0
sp_kill_qty_storno 648887 648887 0 0.00 0 27675 54 0 0
sp_lock_selected_doc 21684 21684 0 0.00 0 8986 6 0 0
sp_make_cost_storno 29234 29234 0 0.00 0 23010 48 0 0
sp_multiply_rows_for_pdistr 29234 29234 0 0.00 0 15502 23 0 0
sp_multiply_rows_for_qdistr 21827 21827 0 0.00 0 11470 161 0 0
sp_pay_from_customer 7417 7417 0 0.00 4 16305 105 0 0
sp_pay_to_supplier 6117 6117 0 0.00 4 12938 110 0 0
sp_payment_common 13534 13534 0 0.00 1 16281 32 0 0
sp_qd_handle_on_cancel_clo 1835 1835 0 0.00 0 9387 266 0 0
sp_qd_handle_on_invoice_upd_sts 11503 11503 0 0.00 0 27674 258 0 0
sp_qd_handle_on_reserve_upd_sts 10417 10417 0 0.00 0 19876 56 0 0
sp_reserve_write_off 10417 10417 0 0.00 6 19893 165 0 0
sp_supplier_invoice 5763 5763 0 0.00 6 106572 3252 0 0
sp_supplier_order 5762 5762 0 0.00 5 33098 1154 0 0
srv_aggregate_perf_data 99 99 0 0.00 2743 128975 20132 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1760 138758 56457 0 0
srv_find_qd_qs_mism 1129757 1129757 0 0.00 0 15691 4 0 0
srv_make_invnt_saldo 587 587 0 0.00 16 32842 1057 0 0
srv_make_money_saldo 815 815 0 0.00 1 3875 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 237346 493113 345992 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 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 5294 5294 0 0.00 0 10057 148 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 3660 65 0 0
t$perf-norm:sp_cancel_client_order 1835 1835 0 0.00 0 6762 14 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1597 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1423 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 766 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 4081 24 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 15050 1225 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 2029 8 0 0
t$perf-norm:sp_client_order 21827 21827 0 0.00 0 8436 13 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 789 5 0 0
t$perf-norm:sp_pay_from_customer 7417 7417 0 0.00 0 8097 10 0 0
t$perf-norm:sp_pay_to_supplier 6117 6117 0 0.00 0 6603 11 0 0
t$perf-norm:sp_reserve_write_off 10417 10417 0 0.00 0 7225 12 0 0
t$perf-norm:sp_supplier_invoice 5763 5763 0 0.00 0 8328 19 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 8424 22 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 48 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 31 1 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 807 13 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 5637 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 247 63 0 0
v_add_invoice_to_stock 10878 10566 312 2.86 0 4506 16 0 312
v_all_customers 39796 39796 0 0.00 0 21 1 0 0
v_all_suppliers 31726 31726 0 0.00 0 100 1 0 0
v_all_wares 43654 43654 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2853 9 0 0
v_cancel_client_order 3670 3670 0 0.00 0 1531 4 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 411 3 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 3140 7 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 3984 10 0 0
v_cancel_supplier_invoice 840 808 32 3.80 0 278 12 0 32
v_cancel_supplier_order 866 866 0 0.00 0 880 4 0 0
v_cancel_write_off 2086 2086 0 0.00 0 2103 16 0 0
v_max_id_clo_ord 5762 5762 0 0.00 0 4738 2 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2544 15 0 0
v_max_id_ord_sup 5755 5755 0 0.00 0 3533 5 0 0
v_max_non_paid_invoice 1771 1771 0 0.00 0 3448 4 0 0
v_max_non_paid_realizn 3939 3939 0 0.00 0 2976 4 0 0
v_min_id_clo_ord 5762 5762 0 0.00 0 5951 7 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 3497 13 0 0
v_min_id_ord_sup 5755 5755 0 0.00 0 4553 5 0 0
v_min_non_paid_invoice 6117 6117 0 0.00 0 12895 16 0 0
v_min_non_paid_realizn 7417 7417 0 0.00 0 16254 25 0 0
v_our_firm 10814 10814 0 0.00 0 15 1 0 0
v_reserve_write_off 20834 20834 0 0.00 0 4130 5 0 0
x_lock_depdocs_on_canc_invoice 12631 12631 0 0.00 0 7962 84 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 20231 367 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 2 33083 1081 0 0
x_make_qstorno_1200_2000 5763 5763 0 0.00 2 106541 3130 0 0
x_make_qstorno_2100_3300 480948 480948 0 0.00 0 36966 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2020 18:57:47 to 09-10-2020 18:57:51.

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
16:27:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:28:01 29926 8192000000 10356166624 10672377856 1407780880 14.664 100 84 168 0 1441827424 5529856 28516688
16:29:38 3514 8192000000 10413924928 10741489664 1472730480 15.238 100 85 170 0 1507583808 7580064 27273264
16:30:48 15112 8192000000 10472180176 10818740224 1531018208 15.746 100 78 156 2 1564069264 9006432 24044624
16:32:10 7804 8192000000 10564075104 10924462080 1630601232 16.600 100 74 146 3 1662616144 10355808 21659104
16:33:24 6154 8192000000 10668839232 11039580160 1723357760 17.380 100 79 158 2 1758599920 12439872 22802288
16:34:33 4603 8192000000 10724695008 11100295168 1779440512 17.845 100 86 172 2 1818837168 15271120 24125536
16:35:39 6379 8192000000 10772521616 11160035328 1828974448 18.251 100 82 164 1 1865367392 13670736 22722208
16:36:47 1550 8192000000 10818348352 11209732096 1872915888 18.608 100 93 186 1 1912635840 13823296 25896656
16:37:49 4254 8192000000 10799165520 11220508672 1882209008 18.683 100 31 62 2 1898960496 8065472 8686016
16:38:55 54087 8192000000 10810192640 11240861696 1897647072 18.807 100 40 80 1 1916693072 6402544 12643456
16:40:52 7345 8192000000 10822866240 11255447552 1909097488 18.899 100 34 67 4 1925135088 6286560 9751040
16:42:00 12553 8192000000 10820094736 11258830848 1910447664 18.910 100 15 31 2 1920368400 5448848 4471888
16:43:14 12754 8192000000 10823709776 11265740800 1920053888 18.987 100 17 34 2 1929955632 5587696 4314048
16:44:28 16631 8192000000 10816524704 11259367424 1885678736 18.711 98 20 40 1 1897413408 4850016 6884656
16:45:47 6140 8192000000 10813510768 11239141376 1880191024 18.667 99 36 72 0 1897270704 6945760 10133920
16:46:54 6625 8192000000 10789444848 11212632064 1858222960 18.489 99 28 56 4 1872708528 5691552 8794016
16:48:09 17039 8192000000 10675972224 11086196736 1623477920 16.539 90 25 50 2 1635007920 4367024 7162976
16:49:27 16866 8192000000 10701801616 11122704384 1759069744 17.677 97 26 50 3 1771298848 3569440 8659664
16:50:46 6133 8192000000 10725760176 11148894208 1802598912 18.035 99 27 54 5 1816754448 6120480 8035056
16:51:54 6314 8192000000 10766510688 11191812096 1837716080 18.322 99 28 56 0 1852315040 6273056 8325904
16:53:00 8436 8192000000 10773243056 11207647232 1866242096 18.554 100 21 42 1 1878099984 5743488 6114400
16:54:12 26268 8192000000 10789543072 11214557184 1873139744 18.610 100 22 44 0 1885161648 5737488 6284416
16:55:40 9076 8192000000 10797400912 11223273472 1879732912 18.663 100 26 52 1 1893152384 6093776 7325696
16:56:49 9097 8192000000 10804848144 11240239104 1893326512 18.773 100 23 45 2 1905571520 5466144 6778864
16:58:13 15224 8192000000 10864006240 11285811200 1932741312 19.089 100 47 90 1 1952128016 6613680 12773024
16:59:32 93157 8192000000 10884091856 11324137472 1946628304 19.200 99 38 75 0 1971207504 13537600 11041600
17:02:11 17651 8192000000 10892017872 11321880576 1956292768 19.277 100 39 78 1 1981820912 14311408 11216736
17:03:31 8173 8192000000 10902790784 11332497408 1962569248 19.326 99 53 106 0 1995278224 17170352 15538624
17:04:43 1974 8192000000 10926281088 11335757824 1966817488 19.360 100 73 148 4 2005837024 18359088 20660448
17:05:51 1760 8192000000 10935823360 11345137664 1970719568 19.391 100 75 151 0 2017124304 24254736 22150000
17:06:55 9597 8192000000 10911920512 11337904128 1926794624 19.041 98 58 116 0 1973451632 28860976 17796032
17:08:11 2246 8192000000 10920616896 11331743744 1947213440 19.204 100 75 150 2 2006068384 35651728 23203216
17:09:17 3836 8192000000 10949655424 11354181632 1968298704 19.372 100 75 151 3 2026575728 35183840 23093184
17:10:27 3585 8192000000 10898315856 11299340288 1900003760 18.826 98 71 142 1 1961684512 39315552 22365200
17:11:33 3195 8192000000 10939447664 11353915392 1953016592 19.250 100 68 134 2 2020940624 46518256 21405776
17:12:46 10123 8192000000 10910974576 11340365824 1943444048 19.174 100 44 88 1 1997803792 40122368 14237376
17:14:00 12934 8192000000 10919857600 11358679040 1940451648 19.150 99 28 56 0 1993824048 43936688 9435712
17:15:16 17724 8192000000 10923283856 11368329216 1949203072 19.220 100 41 82 3 2016288176 53051296 14033808
17:16:38 19236 8192000000 10902899280 11347271680 1913063648 18.931 99 26 52 1 1974037776 52182288 8791840
17:18:16 51134 8192000000 10895478128 11362197504 1907048592 18.883 99 19 38 3 1969022704 55321408 6652704
17:20:07 48069 8192000000 10864855360 11317108736 1928693280 19.056 100 9 18 0 1940352448 9538272 2120896
17:22:04 92490 8192000000 10888307872 11328036864 1916567360 18.959 99 20 40 0 1935699968 12982256 6150352
17:24:38 120816 8192000000 10880141904 11349868544 1950587472 19.231 100 32 64 0 1972945120 13174736 9182912
17:27:42 68199 8192000000 10895872560 11362488320 1948587920 19.215 99 12 24 1 1970862528 18890384 3384224
17:29:52 49684 8192000000 10927426192 11373727744 1977680912 19.446 99 41 82 3 2008522032 19299328 11541792
17:31:59 52425 8192000000 10934918560 11401519104 1992443552 19.563 100 15 30 1 2021299712 24251232 4604928
17:33:59 58100 8192000000 10926569008 11392917504 2000149312 19.624 100 17 34 1 2016665696 11674784 4841600
17:36:06 95970 8192000000 10935060384 11407114240 2011028688 19.710 100 22 44 0 2029821552 11657104 7135760
17:39:57 74284 8192000000 10919517744 11411935232 1999685728 19.620 100 22 44 0 2018074176 11811216 6577232
17:42:12 38338 8192000000 10931485776 11398610944 2003075584 19.647 100 24 48 0 2017564928 7710336 6779008
17:43:51 103051 8192000000 10921590768 11388112896 1950851344 19.233 97 18 36 0 1972892976 16704240 5337392
17:46:34 44840 8192000000 10921996144 11387191296 1939626880 19.144 97 25 50 1 1965917520 18820752 7469888
17:48:49 53049 8192000000 10907385792 11363205120 1921428480 18.998 97 25 50 1 1940927456 12165072 7333904
17:51:11 79682 8192000000 10896468288 11361107968 1937488000 19.127 98 23 46 1 1966047600 22390320 6169280
17:53:31 124981 8192000000 10847398928 11297062912 1764584496 17.722 90 23 46 0 1782621088 11807088 6229504
17:56:37 54607 8192000000 10852478064 11319304192 1896355552 18.797 98 30 60 0 1916249872 11211088 8683232
17:58:37 36833 8192000000 10843766480 11323281408 1912636544 18.928 99 3 6 0 1923496752 10344768 515440
18:00:18 88015 8192000000 10817956928 11302100992 1853697680 18.452 97 5 10 0 1863611920 8897248 1016992
18:02:50 68190 8192000000 10863588832 11331678208 1897392160 18.805 98 26 51 1 1918857104 14387248 7077696
18:05:00 83102 8192000000 10867717152 11327864832 1869424080 18.580 96 11 22 0 1885467504 13217424 2826000
18:07:23 68520 8192000000 10869772016 11319500800 1886830368 18.720 98 17 34 0 1905026224 13369648 4826208
18:09:39 41758 8192000000 10869465088 11318800384 1901219392 18.836 98 38 76 0 1925423888 13354352 10850144
18:11:21 94820 8192000000 10860727712 11319627776 1872589696 18.605 96 13 26 0 1890835856 14890864 3355296
18:13:57 89464 8192000000 10865467088 11346886656 1914660640 18.944 98 25 50 0 1938377632 17022640 6694352
18:16:33 84198 8192000000 10890991936 11342622720 1940161712 19.148 99 47 93 0 1965621088 11855360 13604016
18:19:01 41018 8192000000 10870553376 11344519168 1954480016 19.262 100 21 42 0 1969026032 8727472 5818544
18:20:47 52578 8192000000 10872016912 11355684864 1962480176 19.326 100 4 8 0 1971680896 8234432 966288
18:22:58 102906 8192000000 10905397984 11384070144 1975768336 19.431 100 8 16 0 1988299648 10601152 1930160
18:25:41 31467 8192000000 10916101888 11368972288 1963855856 19.337 99 22 41 0 1975583248 6521488 5205904
18:27:13 110179 8192000000 10904010048 11375656960 1886784688 18.720 95 15 30 1 1903402816 12975104 3643024
18:30:06 138758 8192000000 10900400224 11362689024 1903922640 18.858 96 15 30 1 1924020304 15721728 4375936
18:33:25 49260 8192000000 10879296848 11351134208 1922339712 19.006 98 17 34 2 1941221216 14149392 4732112
18:35:15 94288 8192000000 10865079856 11339030528 1817368960 18.156 92 14 28 1 1833025648 11434192 4222496
18:37:49 39132 8192000000 10871959296 11311878144 1906281344 18.877 98 32 64 1 1926506832 11390432 8835056
18:39:36 69316 8192000000 10845969728 11305500672 1895223744 18.788 98 23 45 1 1917104496 15175840 6704912
18:41:49 84099 8192000000 10873843776 11342118912 1917101072 18.964 99 20 40 0 1933516720 10552416 5863232
18:44:18 83802 8192000000 10856298464 11317870592 1888741536 18.736 97 15 28 0 1897958704 5192544 4024624
18:46:43 71561 8192000000 10832294304 11301289984 1847134576 18.399 96 12 24 0 1862422784 12294736 2993472
18:49:00 89267 8192000000 10823068096 11289128960 1844381600 18.376 96 12 24 0 1861352176 13315888 3654688
18:51:32 62499 8192000000 10867225344 11320664064 1859971536 18.503 96 23 46 0 1877636048 10851280 6813232
18:53:46 84899 8192000000 10855588096 11325173760 1891815952 18.760 98 14 28 1 1909992592 14220368 3956272
18:56:30 31680 8192000000 9023711184 9230868480 192359840 2.294 11 5 9 0 195386416 1686080 1340496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-10-2020 18:57:53 to 09-10-2020 18:58:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 156 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2020 18:58:08 to 09-10-2020 18:58:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          255776
MON$OLDEST_ACTIVE               255777
MON$OLDEST_SNAPSHOT             255777
MON$NEXT_TRANSACTION            255777
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-03 16:41:55.3470
MON$PAGES                       2051872
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33371 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33371 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33371 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33371 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Oct  9 18:57:05 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Oct  9 18:57:06 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
--- end of diff output ---

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

09.10.20 18:58:11. There are 3 logs in /var/tmp/logs-oltp30 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-10-09 18:58:14 No severe PSQL-related problems occured
09.10.20 18:58:15. End of report.